vm-test-run-switch-test-user
checks.aarch64-linux.switchTest-user
· build #43
· 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: QEMU running (pid 45)11machine # Disk image does not exist, creating the virtualisation disk image...12machine # Formatting '/build/vm-state-machine/tmp.e3tSnF9aa3', fmt=raw size=107374182413machine # 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: 2114362b-26da-4036-ab78-08438651cda117machine # 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-24T02:50:33Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28machine # [2026-09-24T02:50:33Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29machine # [2026-09-24T02:50:33Z INFO virtiofsd] Waiting for vhost-user socket connection...30machine # [2026-09-24T02:50:33Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31machine # [2026-09-24T02:50:33Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32machine # [2026-09-24T02:50:33Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)33machine # [2026-09-24T02:50:33Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether34machine # [2026-09-24T02:50:33Z INFO virtiofsd] Waiting for vhost-user socket connection...35machine # [2026-09-24T02:50:33Z INFO virtiofsd] Waiting for vhost-user socket connection...36machine # [2026-09-24T02:50:33Z INFO virtiofsd] Client connected, servicing requests37machine # [2026-09-24T02:50:33Z INFO virtiofsd] Client connected, servicing requests38machine # [2026-09-24T02:50:33Z 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/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/init regInfo=/nix/.ro-store/c3r2l8ga7dbb2wx1islaai4krqvfmcvc-closure-info/registration console=ttyAMA0,115200n8 console=tty074machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/c3r2l8ga7dbb2wx1islaai4krqvfmcvc-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.000034] arm-pv: using stolen time PV118machine # [ 0.000440] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)119machine # [ 0.000625] Console: colour dummy device 80x25120machine # [ 0.000632] printk: legacy console [tty0] enabled121machine # [ 0.000821] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)122machine # [ 0.000828] pid_max: default: 32768 minimum: 301123machine # [ 0.000912] LSM: initializing lsm=capability,landlock,yama,bpf,ima124machine # [ 0.001053] landlock: Up and running.125machine # [ 0.001056] Yama: becoming mindful.126machine # [ 0.001533] LSM support for eBPF active127machine # [ 0.001672] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)128machine # [ 0.001692] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)129machine # [ 0.003607] rcu: Hierarchical SRCU implementation.130machine # [ 0.003612] rcu: Max phase no-delay instances is 1000.131machine # [ 0.004910] fsl-mc MSI: its@8080000 domain created132machine # [ 0.005028] EFI services will not be available.133machine # [ 0.005109] smp: Bringing up secondary CPUs ...134machine # [ 0.005117] smp: Brought up 1 node, 1 CPU135machine # [ 0.005121] SMP: Total of 1 processors activated.136machine # [ 0.005124] CPU: All CPU(s) started at EL1137machine # [ 0.005136] CPU features: detected: Branch Target Identification138machine # [ 0.005141] CPU features: detected: ARMv8.4 Translation Table Level139machine # [ 0.005144] CPU features: detected: Instruction cache invalidation not required for I/D coherence140machine # [ 0.005147] CPU features: detected: Data cache clean to the PoU not required for I/D coherence141machine # [ 0.005151] CPU features: detected: Common not Private translations142machine # [ 0.005154] CPU features: detected: CRC32 instructions143machine # [ 0.005157] CPU features: detected: Data cache clean to Point of Deep Persistence144machine # [ 0.005160] CPU features: detected: Data cache clean to Point of Persistence145machine # [ 0.005163] CPU features: detected: Data independent timing control (DIT)146machine # [ 0.005166] CPU features: detected: E0PD147machine # [ 0.005169] CPU features: detected: Enhanced Counter Virtualization148machine # [ 0.005172] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)149machine # [ 0.005175] CPU features: detected: Enhanced Virtualization Traps150machine # [ 0.005178] CPU features: detected: Fine Grained Traps151machine # [ 0.005182] CPU features: detected: Generic authentication (architected QARMA5 algorithm)152machine # [ 0.005186] CPU features: detected: RCpc load-acquire (LDAPR)153machine # [ 0.005189] CPU features: detected: LSE atomic instructions154machine # [ 0.005192] CPU features: detected: Privileged Access Never155machine # [ 0.005195] CPU features: detected: PMUv3156machine # [ 0.005198] CPU features: detected: RAS Extension Support157machine # [ 0.005200] CPU features: detected: RASv1p1 Extension Support158machine # [ 0.005203] CPU features: detected: Random Number Generator159machine # [ 0.005206] CPU features: detected: Speculation barrier (SB)160machine # [ 0.005208] CPU features: detected: Stage-2 Force Write-Back161machine # [ 0.005211] CPU features: detected: TLB range maintenance instructions162machine # [ 0.005215] CPU features: detected: Speculative Store Bypassing Safe (SSBS)163machine # [ 0.005255] alternatives: applying system-wide alternatives164machine # [ 0.008280] CPU features: detected: BBM Level 2 without TLB conflict abort165machine # [ 0.008477] Memory: 893532K/1048576K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 113500K reserved, 32768K cma-reserved)166machine # [ 0.008813] devtmpfs: initialized167machine # [ 0.010602] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)168machine # [ 0.010625] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).169machine # [ 0.010840] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL170machine # [ 0.010844] 0 pages in range for non-PLT usage171machine # [ 0.010845] 508224 pages in range for PLT usage172machine # [ 0.010960] pinctrl core: initialized pinctrl subsystem173machine # [ 0.011753] DMI not present or invalid.174machine # [ 0.015019] NET: Registered PF_NETLINK/PF_ROUTE protocol family175machine # [ 0.017554] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations176machine # [ 0.017701] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations177machine # [ 0.017883] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations178machine # [ 0.017905] audit: initializing netlink subsys (disabled)179machine # [ 0.018482] thermal_sys: Registered thermal governor 'fair_share'180machine # [ 0.018484] thermal_sys: Registered thermal governor 'bang_bang'181machine # [ 0.018487] thermal_sys: Registered thermal governor 'step_wise'182machine # [ 0.018490] thermal_sys: Registered thermal governor 'user_space'183machine # [ 0.018493] thermal_sys: Registered thermal governor 'power_allocator'184machine # [ 0.018522] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1185machine # [ 0.018530] cpuidle: using governor ladder186machine # [ 0.018536] cpuidle: using governor menu187machine # [ 0.018736] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.188machine # [ 0.018752] ASID allocator initialised with 65536 entries189machine # [ 0.020028] Serial: AMBA PL011 UART driver190machine # [ 0.025660] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1191machine # [ 0.025856] printk: console [ttyAMA0] enabled192machine # [ 0.150721] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages193machine # [ 0.150730] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page194machine # [ 0.150734] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages195machine # [ 0.150737] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page196machine # [ 0.150739] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages197machine # [ 0.150742] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page198machine # [ 0.150745] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages199machine # [ 0.150748] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page200machine # [ 0.151919] fbcon: Taking over console201machine # [ 0.151928] ACPI: Interpreter disabled.202machine # [ 0.161713] iommu: Default domain type: Translated203machine # [ 0.161719] iommu: DMA domain TLB invalidation policy: strict mode204machine # [ 0.162122] SCSI subsystem initialized205machine # [ 0.162302] usbcore: registered new interface driver usbfs206machine # [ 0.162337] usbcore: registered new interface driver hub207machine # [ 0.162353] usbcore: registered new device driver usb208machine # [ 0.166446] pps_core: LinuxPPS API ver. 1 registered209machine # [ 0.166451] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>210machine # [ 0.166458] PTP clock support registered211machine # [ 0.168722] EDAC MC: Ver: 3.0.0212machine # [ 0.168899] scmi_core: SCMI protocol bus registered213machine # [ 0.171626] FPGA manager framework214machine # [ 0.172170] vgaarb: loaded215machine # [ 0.172436] clocksource: Switched to clocksource arch_sys_counter216machine # [ 0.179695] VFS: Disk quotas dquot_6.6.0217machine # [ 0.179726] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)218machine # [ 0.182019] netfs: FS-Cache loaded219machine # [ 0.182132] pnp: PnP ACPI: disabled220machine # [ 0.187648] NET: Registered PF_INET protocol family221machine # [ 0.187811] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)222machine # [ 0.217359] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)223machine # [ 0.217394] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)224machine # [ 0.217432] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)225machine # [ 0.217475] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)226machine # [ 0.217547] TCP: Hash tables configured (established 8192 bind 8192)227machine # [ 0.217629] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)228machine # [ 0.217660] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)229machine # [ 0.217683] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)230machine # [ 0.217769] NET: Registered PF_UNIX/PF_LOCAL protocol family231machine # [ 0.217795] NET: Registered PF_XDP protocol family232machine # [ 0.217830] PCI: CLS 0 bytes, default 64233machine # [ 0.218184] Trying to unpack rootfs image as initramfs...234machine # [ 0.230352] kvm [1]: HYP mode not available235machine # [ 0.332982] Initialise system trusted keyrings236machine # [ 0.333126] workingset: timestamp_bits=42 max_order=18 bucket_order=0237machine # [ 0.333502] squashfs: version 4.0 (2009/01/31) Phillip Lougher238machine # [ 0.333555] 9p: Installing v9fs 9p2000 file system support239machine # [ 0.361583] Key type asymmetric registered240machine # [ 0.361599] Asymmetric key parser 'x509' registered241machine # [ 0.361671] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)242machine # [ 0.361793] io scheduler mq-deadline registered243machine # [ 0.361797] io scheduler kyber registered244machine # [ 0.374116] pl061_gpio 9030000.pl061: PL061 GPIO chip registered245machine # [ 0.374692] ledtrig-cpu: registered to indicate activity on CPUs246machine # [ 0.375052] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:247machine # [ 0.375067] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000248machine # [ 0.375077] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000249machine # [ 0.375082] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000250machine # [ 0.375101] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits251machine # [ 0.375120] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]252machine # [ 0.375198] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00253machine # [ 0.375205] pci_bus 0000:00: root bus resource [bus 00-ff]254machine # [ 0.375208] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]255machine # [ 0.375212] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]256machine # [ 0.375215] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]257machine # [ 0.375272] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint258machine # [ 0.375697] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint259machine # [ 0.375877] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]260machine # [ 0.375889] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]261machine # [ 0.375915] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]262machine # [ 0.375926] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]263machine # [ 0.376370] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint264machine # [ 0.400639] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]265machine # [ 0.400659] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]266machine # [ 0.400685] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]267machine # [ 0.401192] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint268machine # [ 0.401375] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]269machine # [ 0.401386] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]270machine # [ 0.401412] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]271machine # [ 0.401870] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint272machine # [ 0.402047] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]273machine # [ 0.402058] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]274machine # [ 0.402084] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275machine # [ 0.402096] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]276machine # [ 0.402542] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint277machine # [ 0.402721] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]278machine # [ 0.402746] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279machine # [ 0.403199] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint280machine # [ 0.403379] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.403403] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.403789] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint283machine # [ 0.403962] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]284machine # [ 0.404212] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint285machine # [ 0.404390] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]286machine # [ 0.404414] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]287machine # [ 0.423506] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint288machine # [ 0.423685] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]289machine # [ 0.423710] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]290machine # [ 0.424150] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint291machine # [ 0.424327] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]292machine # [ 0.424352] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]293machine # [ 0.436916] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint294machine # [ 0.437213] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]295machine # [ 0.437226] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]296machine # [ 0.437251] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]297machine # [ 0.437721] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint298machine # [ 0.437919] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]299machine # [ 0.437930] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]300machine # [ 0.437955] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]301machine # [ 0.438579] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned302machine # [ 0.438585] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned303machine # [ 0.438589] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned304machine # [ 0.438633] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned305machine # [ 0.438676] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned306machine # [ 0.438720] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned307machine # [ 0.438764] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned308machine # [ 0.438807] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned309machine # [ 0.438851] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned310machine # [ 0.438894] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned311machine # [ 0.438937] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned312machine # [ 0.438979] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned313machine # [ 0.439063] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned314machine # [ 0.439121] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned315machine # [ 0.439139] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned316machine # [ 0.439155] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned317machine # [ 0.439173] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned318machine # [ 0.439190] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned319machine # [ 0.439207] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned320machine # [ 0.439224] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned321machine # [ 0.439243] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned322machine # [ 0.439264] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned323machine # [ 0.439282] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned324machine # [ 0.439300] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned325machine # [ 0.439318] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned326machine # [ 0.439335] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned327machine # [ 0.439352] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned328machine # [ 0.439369] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned329machine # [ 0.439386] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned330machine # [ 0.439403] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned331machine # [ 0.439420] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned332machine # [ 0.439441] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]333machine # [ 0.439447] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]334machine # [ 0.439450] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]335machine # [ 0.440274] pci 0000:00:07.0: enabling device (0000 -> 0002)336machine # [ 0.477116] pci 0000:00:07.0: quirk_usb_early_handoff+0x0/0xa60 took 35983 usecs337machine # [ 0.505176] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)338machine # [ 0.507623] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)339machine # [ 0.511977] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)340machine # [ 0.521973] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)341machine # [ 0.524069] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)342machine # [ 0.527854] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)343machine # [ 0.538247] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)344machine # [ 0.540387] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)345machine # [ 0.543837] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)346machine # [ 0.546466] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)347machine # [ 0.557893] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)348machine # [ 0.569718] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled349machine # [ 0.571643] msm_serial: driver initialized350machine # [ 0.571786] SuperH (H)SCI(F) driver initialized351machine # [ 0.571842] STM32 USART driver initialized352machine # [ 0.605340] loop: module loaded353machine # [ 0.605539] virtio_blk virtio2: 1/0/0 default/read/poll queues354machine # [ 0.606566] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)355machine # [ 0.616615] megasas: 07.734.00.00-rc1356machine # [ 0.617440] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]357machine # [ 0.619865] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000358machine # [ 0.619883] Intel/Sharp Extended Query Table at 0x0031359machine # [ 0.624884] Using buffer write method360machine # [ 0.624922] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]361machine # [ 0.626678] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000362machine # [ 0.626692] Intel/Sharp Extended Query Table at 0x0031363machine # [ 0.640486] Using buffer write method364machine # [ 0.640505] Concatenating MTD devices:365machine # [ 0.640508] (0): "0.flash"366machine # [ 0.640511] (1): "0.flash"367machine # [ 0.640517] into device "0.flash"368machine # [ 0.887736] Freeing initrd memory: 26388K369machine # [ 0.893878] tun: Universal TUN/TAP device driver, 1.6370machine # [ 0.897702] thunder_xcv, ver 1.0371machine # [ 0.897728] thunder_bgx, ver 1.0372machine # [ 0.897746] nicpf, ver 1.0373machine # [ 0.898318] e1000: Intel(R) PRO/1000 Network Driver374machine # [ 0.898322] e1000: Copyright (c) 1999-2006 Intel Corporation.375machine # [ 0.898343] e1000e: Intel(R) PRO/1000 Network Driver376machine # [ 0.898349] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.377machine # [ 0.898374] igb: Intel(R) Gigabit Ethernet Network Driver378machine # [ 0.898378] igb: Copyright (c) 2007-2014 Intel Corporation.379machine # [ 0.898397] igbvf: Intel(R) Gigabit Virtual Function Network Driver380machine # [ 0.898401] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.381machine # [ 0.898539] sky2: driver version 1.30382machine # [ 0.900177] usbcore: registered new interface driver usb-storage383machine # [ 0.901255] ehci-pci 0000:00:07.0: EHCI Host Controller384machine # [ 0.901273] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1385machine # [ 0.901472] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000386machine # [ 0.911823] usbcore: registered new interface driver usbserial_generic387machine # [ 0.911836] usbserial: USB Serial support registered for generic388machine # [ 0.912498] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00389machine # [ 0.915074] hub 1-0:1.0: USB hub found390machine # [ 0.915088] hub 1-0:1.0: 6 ports detected391machine # [ 0.915574] hv_vmbus: registering driver hyperv_keyboard392machine # [ 0.918129] rtc-pl031 9010000.pl031: registered as rtc0393machine # [ 0.918150] rtc-pl031 9010000.pl031: setting system clock to 2026-09-24T02:50:35 UTC (1790218235)394machine # [ 0.918462] i2c_dev: i2c /dev entries driver395machine # [ 0.924497] sdhci: Secure Digital Host Controller Interface driver396machine # [ 0.924508] sdhci: Copyright(c) Pierre Ossman397machine # [ 0.924802] Synopsys Designware Multimedia Card Interface Driver398machine # [ 0.925189] sdhci-pltfm: SDHCI platform and OF driver helper399machine # [ 0.926822] hid: raw HID events driver (C) Jiri Kosina400machine # [ 0.927067] usbcore: registered new interface driver usbhid401machine # [ 0.927071] usbhid: USB HID core driver402machine # [ 0.928248] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available403machine # [ 0.935603] drop_monitor: Initializing network drop monitor service404machine # [ 0.935819] NET: Registered PF_INET6 protocol family405machine # [ 0.936387] Segment Routing with IPv6406machine # [ 0.936411] In-situ OAM (IOAM) with IPv6407machine # [ 0.939184] NET: Registered PF_PACKET protocol family408machine # [ 0.939257] 9pnet: Installing 9P2000 support409machine # [ 0.939309] Key type dns_resolver registered410machine # [ 0.947083] registered taskstats version 1411machine # [ 0.947222] Loading compiled-in X.509 certificates412machine # [ 0.955828] Demotion targets for Node 0: null413machine # [ 0.955952] Key type .fscrypt registered414machine # [ 0.955956] Key type fscrypt-provisioning registered415machine # [ 0.956061] ima: No TPM chip found, activating TPM-bypass!416machine # [ 0.956078] ima: Allocated hash algorithm: sha1417machine # [ 0.956107] ima: No architecture policies found418machine # [ 0.960629] input: gpio-keys as /devices/platform/gpio-keys/input/input0419machine # [ 0.980181] clk: Disabling unused clocks420machine # [ 0.980200] PM: genpd: Disabling unused power domains421machine # [ 0.984387] Freeing unused kernel memory: 4736K422machine # [ 0.985354] Run /init as init process423machine # [ 1.003113] systemd[1]: Successfully made /usr/ read-only.424machine # [ 1.160555] usb 1-1: new high-speed USB device number 2 using ehci-pci425machine # [ 1.315286] 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/input1426machine # [ 1.337300] systemd[1]: systemd 263-1f286f9 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)427machine # [ 1.337349] systemd[1]: Detected virtualization qemu.428machine # [ 1.337484] systemd[1]: Detected architecture arm64.429machine # [ 1.337500] systemd[1]: Running in initrd.430machine # [ 1.338543] systemd[1]: Initializing machine ID from random generator.431machine # [ 1.338866] systemd[1]: Hostname set to <machine>.432machine # [ 1.408771] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0433machine # [ 1.506778] systemd[1]: bpf-restrict-fs: LSM BPF program attached434machine # [ 1.532577] usb 1-2: new high-speed USB device number 3 using ehci-pci435machine # [ 1.562911] systemd[1]: Queued start job for default target Initrd Default Target.436machine # [ 1.567570] systemd[1]: Created slice Slice /system/modprobe.437machine # [ 1.567749] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.438machine # [ 1.567774] systemd[1]: Expecting device /dev/disk/by-label/nixos...439machine # [ 1.567793] systemd[1]: Reached target Path Units.440machine # [ 1.567804] systemd[1]: Reached target Slice Units.441machine # [ 1.567816] systemd[1]: Reached target Swaps.442machine # [ 1.567830] systemd[1]: Reached target Timer Units.443machine # [ 1.567956] systemd[1]: Listening on D-Bus System Message Bus Socket.444machine # [ 1.568093] systemd[1]: Listening on Journal Socket (/dev/log).445machine # [ 1.568240] systemd[1]: Listening on Journal Sockets.446machine # [ 1.568312] systemd[1]: Listening on udev Kernel Socket.447machine # [ 1.568326] systemd[1]: Reached target Socket Units.448machine # [ 1.578363] systemd[1]: Starting Create List of Static Device Nodes...449machine # [ 1.578433] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs450machine # [ 1.584638] systemd[1]: Mounting Kernel Configuration File System...451machine # [ 1.594735] systemd[1]: Starting Journal Service...452machine # [ 1.624668] systemd[1]: Starting Load Kernel Modules...453machine # [ 1.624800] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os454machine # [ 1.626157] systemd[1]: Starting Coldplug All udev Devices...455machine # [ 1.636884] systemd[1]: Finished Create List of Static Device Nodes.456machine # [ 1.638861] systemd-journald[71]: Collecting audit messages is disabled.457machine # [ 1.686303] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...458machine # [ 1.687048] systemd[1]: Mounted Kernel Configuration File System.459machine # [ 1.711213] 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/input2460machine # [ 1.711356] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0461machine # [ 1.718976] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.462machine # [ 1.731271] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev463machine # [ 1.737159] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.464machine # [ 1.738873] systemd[1]: Starting Create Static Device Nodes in /dev...465machine # [ 1.747485] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0466machine # [ 1.747715] [drm] features: -virgl +edid -resource_blob -host_visible467machine # [ 1.747718] [drm] features: -context_init468machine # [ 1.764621] [drm] number of scanouts: 1469machine # [ 1.764638] [drm] number of cap sets: 0470machine # [ 1.765205] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic471machine # [ 1.765214] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0472machine # [ 1.797164] systemd[1]: Finished Create Static Device Nodes in /dev.473machine # [ 1.797366] systemd[1]: Reached target Preparation for Local File Systems.474machine # [ 1.797388] systemd[1]: Reached target Local File Systems.475machine # [ 1.798965] Console: switching to colour frame buffer device 160x50476machine # [ 1.813000] systemd[1]: Starting Rule-based Manager for Device Events and Files...477machine # [ 1.829170] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device478machine # [ 1.824662] systemd-modules-load[72]: Inserted module 'dm_mod'479machine # [ 1.826711] systemd-modules-load[72]: Module 'virtio_balloon' is built in480machine # [ 1.827889] systemd-modules-load[72]: Module 'virtio_console' is built in481machine # [ 1.845675] systemd[1]: Started Journal Service.482machine # [ 1.833459] systemd-modules-load[72]: Inserted module 'virtio_gpu'483machine # [ 1.840458] systemd-modules-load[72]: Module 'virtio_rng' is built in484machine # [ 1.845193] systemd[1]: Finished Load Kernel Modules.485machine # [ 1.847304] systemd[1]: Starting Apply Kernel Variables...486machine # [ 1.877255] systemd[1]: Starting Create System Files and Directories...487machine # [ 1.889624] systemd-udevd[78]: Using default interface naming scheme 'v261'.488machine # [ 1.922503] systemd[1]: Finished Apply Kernel Variables.489machine # [ 1.927025] systemd[1]: Finished Create System Files and Directories.490machine # [ 1.945939] systemd[1]: Started Rule-based Manager for Device Events and Files.491machine # [ 2.026476] systemd[1]: Starting Virtual Console Setup...492machine # [ 2.100569] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones.493machine # [ 2.105472] systemd[1]: Finished Virtual Console Setup.494machine # [ 2.690036] systemd[1]: Finished Coldplug All udev Devices.495machine # [ 2.690979] systemd[1]: Reached target System Initialization.496machine # [ 2.691767] systemd[1]: Reached target Basic System.497machine # [ 2.839942] (udev-worker)[96]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.498machine # [ 2.861435] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.499machine # [ 2.923928] systemd[1]: Found device /dev/disk/by-label/nixos.500machine # [ 2.927514] systemd[1]: Reached target Initrd Root Device.501machine # [ 2.930732] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...502machine # [ 2.986860] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks503machine # [ 3.026750] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.504machine # [ 3.032435] systemd[1]: Mounting /sysroot...505machine # [ 3.092301] EXT4-fs (vda): mounted filesystem 2114362b-26da-4036-ab78-08438651cda1 r/w with ordered data mode. Quota mode: none.506machine # [ 3.081629] systemd[1]: Mounted /sysroot.507machine # [ 3.084078] systemd[1]: Reached target Initrd Root File System.508machine # [ 3.090166] systemd[1]: Starting Mountpoints Configured in the Real Root...509machine # [ 3.123747] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.510machine # [ 3.130570] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...511machine # [ 3.134230] systemd[1]: Reloading...512machine # [ 3.340982] systemd[1]: Reloading finished in 211 ms.513machine # [ 3.375214] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...514machine # [ 3.380481] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...515machine # [ 3.385284] systemd[1]: initrd-parse-etc.service: Deactivated successfully.516machine # [ 3.387807] systemd[1]: Finished Mountpoints Configured in the Real Root.517machine # [ 3.390296] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.518machine # [ 3.616937] systemd[1]: Mounting /sysroot/nix/.ro-store...519machine # [ 3.624251] systemd[1]: Mounting /sysroot/nix/.rw-store...520machine # [ 3.648998] systemd[1]: Mounting /sysroot/run...521machine # [ 3.656250] systemd[1]: Mounting /sysroot/tmp/shared...522machine # [ 3.675101] systemd[1]: Mounting /sysroot/tmp/xchg...523machine # [ 3.769067] fuse: init (API version 7.45)524machine # [ 3.779655] virtiofs virtio6: discovered new tag: nix-store525machine # [ 3.769826] systemd[1]: Mounted /sysroot/run.526machine # [ 3.791609] virtiofs virtio6: virtio_fs_setup_dax: No cache capability527machine # [ 3.806725] virtiofs virtio7: discovered new tag: shared528machine # [ 3.807582] virtiofs virtio7: virtio_fs_setup_dax: No cache capability529machine # [ 3.814960] virtiofs virtio8: discovered new tag: xchg530machine # [ 3.815920] virtiofs virtio8: virtio_fs_setup_dax: No cache capability531machine # [ 3.808334] systemd[1]: Mounted /sysroot/nix/.rw-store.532machine # [ 3.828146] systemd[1]: Starting rw-sysroot-nix-store.service...533machine # [ 3.830260] systemd[1]: Mounted /sysroot/nix/.ro-store.534machine # [ 3.832458] systemd[1]: Mounted /sysroot/tmp/shared.535machine # [ 3.837886] systemd[1]: Mounted /sysroot/tmp/xchg.536machine # [ 3.866557] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.537machine # [ 3.869787] systemd[1]: Finished rw-sysroot-nix-store.service.538machine # [ 3.872159] systemd[1]: Mounting /sysroot/nix/store...539machine # [ 3.924763] systemd[1]: Mounted /sysroot/nix/store.540machine # [ 3.927013] systemd[1]: Reached target Initrd File Systems.541machine # [ 3.929084] systemd[1]: Starting Find NixOS closure...542machine # [ 3.939021] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...543machine # [ 3.975826] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.544machine # [ 3.997890] systemd[1]: Finished Find NixOS closure.545machine # [ 4.000485] systemd[1]: Reached target Initrd Default Target.546machine # [ 4.003773] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...547machine # [ 4.042529] systemd[1]: Stopped target Initrd Default Target.548machine # [ 4.046330] systemd[1]: Stopped target Basic System.549machine # [ 4.051478] systemd[1]: Stopped target Initrd Root Device.550machine # [ 4.053407] systemd[1]: Stopped target Path Units.551machine # [ 4.055298] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.552machine # [ 4.057628] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.553machine # [ 4.061216] systemd[1]: Stopped target Slice Units.554machine # [ 4.065761] systemd[1]: Stopped target Socket Units.555machine # [ 4.067239] systemd[1]: Stopped target System Initialization.556machine # [ 4.070646] systemd[1]: Stopped target Swaps.557machine # [ 4.076209] systemd[1]: Stopped target Timer Units.558machine # [ 4.077616] systemd[1]: dbus.socket: Deactivated successfully.559machine # [ 4.080458] systemd[1]: Closed D-Bus System Message Bus Socket.560machine # [ 4.082096] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.561machine # [ 4.086585] systemd[1]: Stopped Find NixOS closure.562machine # [ 4.089281] systemd[1]: Starting rw-sysroot-nix-store.service...563machine # [ 4.093010] systemd[1]: systemd-sysctl.service: Deactivated successfully.564machine # [ 4.095182] systemd[1]: Stopped Apply Kernel Variables.565machine # [ 4.101792] systemd[1]: systemd-modules-load.service: Deactivated successfully.566machine # [ 4.102980] systemd[1]: Stopped Load Kernel Modules.567machine # [ 4.103727] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.568machine # [ 4.107440] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.569machine # [ 4.112537] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.570machine # [ 4.116266] systemd[1]: Stopped Create System Files and Directories.571machine # [ 4.121421] systemd[1]: Stopped target Local File Systems.572machine # [ 4.123492] systemd[1]: Stopped target Preparation for Local File Systems.573machine # [ 4.125783] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.574machine # [ 4.128316] systemd[1]: Stopped Coldplug All udev Devices.575machine # [ 4.130371] systemd[1]: Stopping Rule-based Manager for Device Events and Files...576machine # [ 4.136518] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.577machine # [ 4.137701] systemd[1]: Stopped Virtual Console Setup.578machine # [ 4.138417] systemd[1]: initrd-cleanup.service: Deactivated successfully.579machine # [ 4.139370] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.580machine # [ 4.141242] systemd[1]: systemd-udevd.service: Deactivated successfully.581machine # [ 4.142754] systemd[1]: Stopped Rule-based Manager for Device Events and Files.582machine # [ 4.144207] systemd[1]: systemd-udevd.service: Consumed 1.126s CPU time over 2.326s wall clock time, 17.1M memory peak.583machine # [ 4.145702] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.584machine # [ 4.146730] systemd[1]: Finished rw-sysroot-nix-store.service.585machine # [ 4.147572] systemd[1]: Starting Cleanup udev Database...586machine # [ 4.148446] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.587machine # [ 4.149563] systemd[1]: Stopped Create Static Device Nodes in /dev.588machine # [ 4.150473] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.589machine # [ 4.151653] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.590machine # [ 4.156290] systemd[1]: kmod-static-nodes.service: Deactivated successfully.591machine # [ 4.158814] systemd[1]: Stopped Create List of Static Device Nodes.592machine # [ 4.187130] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.593machine # [ 4.190790] systemd[1]: Finished Cleanup udev Database.594machine # [ 4.191634] systemd[1]: Reached target Switch Root.595machine # [ 4.196651] systemd[1]: Starting NixOS Activation...596machine # [ 4.287494] initrd-nixos-activation-start[166]: booting system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test597machine # [ 4.327631] initrd-nixos-activation-start[166]: running activation script...598machine # [ 4.614534] initrd-nixos-activation-start[189]: setting up /etc...599machine # [ 4.739265] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.600machine # [ 4.742415] systemd[1]: Finished NixOS Activation.601machine # [ 4.743682] systemd[1]: Starting Switch Root...602machine # [ 4.769548] systemd[1]: Switching root.603machine # [ 4.951809] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).604machine # [ 5.453486] systemd[1]: systemd 263-1f286f9 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)605machine # [ 5.453723] systemd[1]: Detected virtualization qemu.606machine # [ 5.453892] systemd[1]: Detected architecture arm64.607machine # [ 5.454320] systemd[1]: Detected first boot.608machine # [ 5.482388] systemd[1]: Initializing machine ID from random generator.609machine # [ 5.691640] systemd[1]: bpf-restrict-fs: LSM BPF program attached610machine # [ 5.887774] systemd[1]: Applying preset policy.611machine # [ 6.185681] systemd[1]: Populated /etc with preset unit settings.612machine # [ 6.446752] systemd[1]: initrd-switch-root.service: Deactivated successfully.613machine # [ 6.447312] systemd[1]: Stopped initrd-switch-root.service.614machine # [ 6.453399] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.615machine # [ 6.456205] systemd[1]: Created slice Slice /system/getty.616machine # [ 6.461358] systemd[1]: Created slice User and Session Slice.617machine # [ 6.462149] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.618machine # [ 6.463224] systemd[1]: Started Forward Password Requests to Wall Directory Watch.619machine # [ 6.464136] systemd[1]: Expecting device /dev/hvc0...620machine # [ 6.469411] systemd[1]: Expecting device /dev/ttyAMA0...621machine # [ 6.469919] systemd[1]: Reached target Local Encrypted Volumes.622machine # [ 6.470957] systemd[1]: Stopped target initrd-fs.target.623machine # [ 6.471912] systemd[1]: Stopped target initrd-root-fs.target.624machine # [ 6.476753] systemd[1]: Stopped target initrd-switch-root.target.625machine # [ 6.477214] systemd[1]: Reached target Virtual Machines and Containers.626machine # [ 6.478227] systemd[1]: Reached target Path Units.627machine # [ 6.479163] systemd[1]: Reached target Remote File Systems.628machine # [ 6.479547] systemd[1]: Reached target Slice Units.629machine # [ 6.479866] systemd[1]: Reached target Swaps.630machine # [ 6.487006] systemd[1]: Listening on Query the User Interactively for a Password.631machine # [ 6.490414] systemd[1]: Listening on Process Core Dump Socket.632machine # [ 6.492229] systemd[1]: Listening on Credential Encryption/Decryption.633machine # [ 6.496752] systemd[1]: Listening on Factory Reset Management.634machine # [ 6.497232] systemd[1]: Listening on Hostname Service Socket.635machine # [ 6.503071] systemd[1]: Starting Journal Log Access Socket...636machine # [ 6.505568] systemd[1]: Listening on Journal Audit Socket.637machine # [ 6.508074] systemd[1]: Listening on Console Output Muting Service Socket.638machine # [ 6.510735] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.639machine # [ 6.513586] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os640machine # [ 6.514727] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki641machine # [ 6.522312] systemd[1]: Listening on Disk Repartitioning Service Socket.642machine # [ 6.523001] systemd[1]: Listening on udev Varlink Socket.643machine # [ 6.528898] systemd[1]: Mounting Huge Pages File System...644machine # [ 6.532118] systemd[1]: Mounting POSIX Message Queue File System...645machine # [ 6.546424] systemd[1]: Mounting Kernel Debug File System...646machine # [ 6.560943] systemd[1]: Mounting Kernel Trace File System...647machine # [ 6.576771] systemd[1]: Starting Create List of Static Device Nodes...648machine # [ 6.577390] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs649machine # [ 6.595790] systemd[1]: Mounting Kernel Configuration File System...650machine # [ 6.598895] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm651machine # [ 6.605727] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore652machine # [ 6.607161] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse653machine # [ 6.639280] systemd[1]: Mounting FUSE Control File System...654machine # [ 6.639927] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67655machine # [ 6.682100] systemd[1]: Starting Journal Service...656machine # [ 6.707194] systemd[1]: Starting Load Kernel Modules...657machine # [ 6.731323] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...658machine # [ 6.750596] systemd[1]: Starting Remount Root and Kernel File Systems...659machine # [ 6.751046] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os660machine # [ 6.780592] systemd[1]: Starting Coldplug All udev Devices...661machine # [ 6.783865] systemd[1]: Listening on Journal Log Access Socket.662machine # [ 6.796826] systemd[1]: Mounted Huge Pages File System.663machine # [ 6.797601] systemd[1]: Mounted POSIX Message Queue File System.664machine # [ 6.798167] systemd[1]: Mounted Kernel Debug File System.665machine # [ 6.798752] systemd[1]: Mounted Kernel Trace File System.666machine # [ 6.810995] systemd[1]: Finished Create List of Static Device Nodes.667machine # [ 6.814865] systemd-journald[261]: Collecting audit messages is enabled.668machine # [ 6.827493] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...669machine # [ 6.832628] systemd[1]: Started Journal Service.670machine # [ 6.821350] systemd[1]: Queued start job for default target Multi-User System.671machine # [ 6.822905] systemd[1]: systemd-journald.service: Deactivated successfully.672machine # [ 6.828703] systemd-modules-load[262]: Module 'atkbd' is built in673machine # [ 6.829951] systemd-modules-load[262]: Module 'loop' is built in674machine # [ 6.865461] systemd[1]: Finished Load Kernel Modules.675machine # [ 6.909392] EXT4-fs (vda): re-mounted 2114362b-26da-4036-ab78-08438651cda1.676machine # [ 6.900668] systemd[1]: Starting Firewall...677machine # [ 6.910069] systemd[1]: Starting Apply Kernel Variables...678machine # [ 6.911055] systemd[1]: Mounted Kernel Configuration File System.679machine # [ 6.914385] systemd-oomd[263]: No swap; memory pressure usage will be degraded680machine # [ 6.924215] systemd[1]: Finished Remount Root and Kernel File Systems.681machine # [ 6.937415] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.682machine # [ 6.952541] systemd[1]: Listening on Disk Image Download Service Socket.683machine # [ 6.970999] systemd[1]: Starting Flush Journal to Persistent Storage...684machine # [ 6.972649] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore685machine # [ 6.984563] systemd[1]: Starting Load/Save OS Random Seed...686machine # [ 6.985576] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os687machine # [ 7.014002] systemd[1]: Mounted FUSE Control File System.688machine # [ 7.118760] systemd-journald[261]: Received client request to flush runtime journal.689machine # [ 7.189623] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.690machine # [ 7.190942] systemd[1]: Starting Create Static Device Nodes in /dev...691machine # [ 7.191943] systemd[1]: Finished Load/Save OS Random Seed.692machine # [ 7.205068] systemd[1]: Reached target First Boot Complete.693machine # [ 7.206669] systemd[1]: Finished Apply Kernel Variables.694machine # [ 7.207813] systemd[1]: Finished Flush Journal to Persistent Storage.695machine # [ 7.454468] systemd[1]: Finished Create Static Device Nodes in /dev.696machine # [ 7.455625] systemd[1]: Reached target Preparation for Local File Systems.697machine # [ 7.467307] systemd[1]: Mounting /run/wrappers...698machine # [ 7.472198] systemd[1]: Starting Rule-based Manager for Device Events and Files...699machine # [ 7.577028] systemd[1]: Mounted /run/wrappers.700machine # [ 7.577954] systemd[1]: Reached target Local File Systems.701machine # [ 7.583489] systemd[1]: Listening on Boot Loader Control Service Socket.702machine # [ 7.595636] systemd[1]: Starting Create SUID/SGID Wrappers...703machine # [ 7.596772] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.704machine # [ 7.603259] systemd[1]: Starting Save Transient machine-id to Disk...705machine # [ 7.610370] systemd[1]: Starting Create System Files and Directories...706machine # [ 7.844692] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.707machine # [ 7.860195] systemd[1]: Finished Save Transient machine-id to Disk.708machine # [ 7.961045] systemd-udevd[298]: Using default interface naming scheme 'v261'.709machine # [ 7.976163] systemd[1]: Finished Create System Files and Directories.710machine # [ 7.991654] systemd[1]: Starting Rebuild Journal Catalog...711machine # [ 8.003358] systemd[1]: Starting Record System Boot/Shutdown in UTMP...712machine # [ 8.207098] systemd[1]: Finished Record System Boot/Shutdown in UTMP.713machine # [ 8.233628] systemd[1]: Finished Rebuild Journal Catalog.714machine # [ 8.256688] systemd[1]: Starting Update is Completed...715machine # [ 8.261264] systemd[1]: Started Rule-based Manager for Device Events and Files.716machine # [ 8.418052] systemd[1]: Finished Update is Completed.717machine # [ 9.088777] systemd[1]: Finished Coldplug All udev Devices.718machine # [ 9.173345] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.719machine # [ 9.178398] systemd[1]: Finished Create SUID/SGID Wrappers.720machine # [ 9.184155] systemd[1]: Reached target System Initialization.721machine # [ 9.185180] systemd[1]: Started Discard unused filesystem blocks once a week.722machine # [ 9.186197] systemd[1]: Started Daily Cleanup of Temporary Directories.723machine # [ 9.187192] systemd[1]: Reached target Timer Units.724machine # [ 9.202194] systemd[1]: Listening on D-Bus System Message Bus Socket.725machine # [ 9.203413] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.726machine # [ 9.205994] systemd[1]: Reached target Socket Units.727machine # [ 9.206824] systemd[1]: Reached target Basic System.728machine # [ 9.207636] systemd[1]: Starting Import lastlog data into lastlog2 database...729machine # [ 9.216274] systemd[1]: Starting Name Service Cache Daemon (nsncd)...730machine # [ 9.230853] systemd[1]: Starting Post-Boot Actions...731machine # [ 9.300142] systemd[1]: Started Reset console on configuration changes.732machine # [ 9.340867] systemd[1]: Starting resolvconf update...733machine # [ 9.414728] systemd[1]: Starting D-Bus System Message Bus...734machine # [ 9.436276] systemd[1]: Finished Post-Boot Actions.735machine # [ 9.456182] systemd[1]: Started Name Service Cache Daemon (nsncd).736machine # [ 9.461431] nsncd[415]: Sep 24 02:50:44.053 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"737machine # [ 9.484942] systemd[1]: Reached target Host and Network Name Lookups.738machine # [ 9.486054] systemd[1]: Reached target User and Group Name Lookups.739machine # [ 9.514793] systemd[1]: Starting User Login Management...740machine # [ 9.593171] systemd[1]: Finished Import lastlog data into lastlog2 database.741machine # [ 9.685493] dbus-broker-launch[422]: Looking up NSS user entry for 'systemd-timesync'...742machine # [ 9.695879] dbus-broker-launch[422]: NSS returned no entry for 'systemd-timesync'743machine # [ 9.702391] dbus-broker-launch[422]: Invalid user-name in /nix/store/06f4ai6wjjkb4jld63scbxzmrkbvz5v8-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"744machine # [ 9.735636] systemd-logind[442]: New seat seat0.745machine # [ 9.741982] systemd[1]: Started User Login Management.746machine # [ 9.749070] systemd[1]: Starting linger-users.service...747machine # [ 9.770737] systemd[1]: Started D-Bus System Message Bus.748machine # [ 9.838473] dbus-broker-launch[422]: Ready749machine # [ 9.864362] systemd[1]: Stopped target Host and Network Name Lookups.750machine # [ 9.871680] systemd[1]: Stopping Host and Network Name Lookups...751machine # [ 9.878555] systemd[1]: Stopped target User and Group Name Lookups.752machine # [ 9.879770] systemd[1]: Stopping User and Group Name Lookups...753machine # [ 9.887326] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...754machine # [ 9.891869] systemd[1]: nscd.service: Deactivated successfully.755machine # [ 9.899366] systemd[1]: Stopped Name Service Cache Daemon (nsncd).756machine # [ 9.923350] systemd[1]: Starting Name Service Cache Daemon (nsncd)...757machine # [ 10.010060] systemd[1]: Created slice Slice /user/1001.758machine # [ 10.031218] systemd[1]: Starting User Runtime Directory /run/user/1001...759machine # [ 10.064150] systemd[1]: linger-users.service: Deactivated successfully.760machine # [ 10.088097] systemd[1]: Finished linger-users.service.761machine # [ 10.159281] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3762machine # [ 10.159351] systemd[1]: Started Name Service Cache Daemon (nsncd).763machine # [ 10.185027] systemd[1]: Reached target Host and Network Name Lookups.764machine # [ 10.186439] nsncd[503]: Sep 24 02:50:44.760 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"765machine # [ 10.245513] systemd[1]: Reached target User and Group Name Lookups.766machine # [ 10.246525] systemd[1]: Finished User Runtime Directory /run/user/1001.767machine # [ 10.285883] systemd[1]: Starting User Manager for UID 1001...768machine # [ 10.332863] systemd[1]: Finished resolvconf update.769machine # [ 10.353146] systemd[1]: Starting DHCP Client...770machine # [ 10.399886] (systemd)[534]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)771machine # [ 10.425207] systemd-logind[442]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.772machine # [ 10.513454] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy773machine # [ 10.884919] systemd[534]: Queued start job for default target Main User Target.774machine # [ 10.907588] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy775machine # [ 10.936201] systemd[534]: Created slice User Application Slice.776machine # [ 10.948379] systemd[534]: Started Daily Cleanup of User's Temporary Directories.777machine # [ 10.953533] systemd[534]: Reached target Paths.778machine # [ 10.956079] systemd[534]: Reached target Timers.779machine # [ 10.960646] systemd[534]: Starting D-Bus User Message Bus Socket...780machine # [ 10.970922] systemd[534]: Starting Create User Files and Directories...781machine # [ 10.981211] systemd[534]: Finished Create User Files and Directories.782machine # [ 10.982246] systemd[1]: Starting Virtual Console Setup...783machine # [ 10.989386] systemd[534]: Listening on D-Bus User Message Bus Socket.784machine # [ 10.993790] systemd[534]: Reached target Sockets.785machine # [ 11.000581] systemd[534]: Reached target Basic System.786machine # [ 11.004480] systemd[1]: Started User Manager for UID 1001.787machine # [ 11.009818] systemd[534]: Starting Run user-specific NixOS activation...788machine # [ 11.016151] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs789machine # [ 11.024240] systemd[534]: Finished Run user-specific NixOS activation.790machine # [ 11.031894] systemd[534]: Reached target Main User Target.791machine # [ 11.034454] systemd[534]: Startup finished in 396ms.792machine # [ 11.038505] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.793machine # [ 11.047037] systemd[1]: Stopped Virtual Console Setup.794machine # [ 11.052603] systemd[1]: Starting Virtual Console Setup...795machine # [ 11.223385] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.796machine # [ 11.249628] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.797machine # [ 11.278902] systemd[1]: Started backdoor.service.798machine # [ 11.303757] dhcpcd[563]: dhcpcd-10.3.2 starting799machine # [ 11.347417] dhcpcd[595]: dev: loaded udev800machine # [ 11.365420] (udev-worker)[583]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.801machine # [ 11.413601] 8021q: 802.1Q VLAN Support v1.8802machine # [ 11.424137] (udev-worker)[583]: Network interface NamePolicy= disabled on kernel command line.803machine # [ 11.524279] cfg80211: Loading compiled-in X.509 certificates for regulatory database804machine # [ 11.570185] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'805machine # [ 11.570688] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'806machine # [ 11.574626] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2807machine # [ 11.574960] cfg80211: failed to load regulatory.db808machine # [ 11.668890] systemd[1]: Condition check resulted in Virtio network device being skipped.809machine # [ 11.730173] systemd-logind[442]: Watching system buttons on /dev/input/event0 (gpio-keys)810machine # connecting to host...811machine # [ 11.907551] mousedev: PS/2 mouse device common for all mice812machine: Guest shell says: b'Spawning backdoor root shell...\n'813machine: connected to guest root shell814machine: (connecting took 12.24 seconds)815machine: (finished: waiting for the VM to finish booting, in 12.93 seconds)816machine # [ 11.950649] systemd[1]: Finished Firewall.817machine # [ 11.951433] systemd[1]: Reached target Preparation for Network.818machine # [ 11.971708] systemd[1]: Starting Address configuration of eth1...819machine # [ 11.981484] systemd[1]: Starting Extra networking commands....820machine # [ 12.011153] 8021q: adding VLAN 0 to HW filter on device eth0821machine # [ 12.002579] dhcpcd[595]: eth0: waiting for carrier822machine # [ 12.008983] systemd-vconsole-setup[562]: Configuration of first virtual console was skipped, ignoring remaining ones.823machine # [ 12.025172] dhcpcd[595]: eth0: waiting for carrier824machine # [ 12.026019] systemd[1]: Finished Virtual Console Setup.825machine # [ 12.056254] dhcpcd[595]: eth0: carrier acquired826machine # [ 12.119640] dhcpcd[595]: DUID 00:01:00:01:32:47:4c:86:52:54:00:12:34:56827machine # [ 12.132384] dhcpcd[595]: eth0: IAID 00:12:34:56828machine # [ 12.133064] dhcpcd[595]: eth0: adding address fe80::5054:ff:fe12:3456829machine # [ 12.185556] systemd-logind[442]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)830machine # [ 12.387285] 8021q: adding VLAN 0 to HW filter on device eth1831machine # [ 12.466934] network-addresses-eth1-start[628]: adding address 192.168.1.1/24... done832machine # [ 12.532432] network-addresses-eth1-start[628]: adding address 2001:db8:1::1/64... done833machine # [ 12.642096] systemd[1]: Finished Address configuration of eth1.834machine # [ 13.232239] systemd[1]: Finished Extra networking commands..835machine # [ 13.233226] systemd[1]: Reached target Network.836machine # [ 13.256500] systemd[1]: Starting Permit User Sessions...837machine # [ 13.444361] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.838machine # [ 13.445613] systemd[1]: Stopped Virtual Console Setup.839machine # [ 13.446399] systemd[1]: Stopping Virtual Console Setup...840machine # [ 13.498207] systemd[1]: Starting Virtual Console Setup...841machine # [ 13.499190] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.842machine # [ 13.524868] systemd[1]: Finished Permit User Sessions.843machine # [ 13.525726] systemd[1]: Started Getty on tty1.844machine # [ 13.526363] systemd[1]: Reached target Login Prompts.845machine # [ 13.527383] systemd-logind[442]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)846machine # [ 13.552763] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.847machine # [ 13.553887] systemd[1]: Stopped Virtual Console Setup.848machine # [ 13.554647] systemd[1]: Starting Virtual Console Setup...849machine # [ 13.708469] systemd-vconsole-setup[707]: Configuration of first virtual console was skipped, ignoring remaining ones.850machine # [ 13.714615] systemd[1]: Finished Virtual Console Setup.851machine # [ 14.045079] dhcpcd[595]: eth0: soliciting an IPv6 router852machine # [ 14.049576] dhcpcd[595]: eth0: Router Advertisement from fe80::2853machine # [ 14.052772] dhcpcd[595]: eth0: adding address fec0::5054:ff:fe12:3456/64854machine # [ 14.055829] dhcpcd[595]: eth0: adding route to fec0::/64855machine # [ 14.059102] dhcpcd[595]: eth0: adding default route via fe80::2856machine # [ 14.117973] dhcpcd[595]: eth0: soliciting a DHCP lease857machine # [ 14.125807] dhcpcd[595]: eth0: offered 10.0.2.15 from 10.0.2.2858machine # [ 14.130777] dhcpcd[595]: eth0: probing address 10.0.2.15/24859machine # [ 19.345241] dhcpcd[595]: eth0: leased 10.0.2.15 for 86400 seconds860machine # [ 19.345555] dhcpcd[595]: eth0: adding route to 10.0.2.0/24861machine # [ 19.345608] dhcpcd[595]: eth0: adding default route via 10.0.2.2862machine # [ 19.502773] systemd[1]: Started DHCP Client.863machine # [ 19.505729] systemd[1]: Reached target Multi-User System.864machine # [ 19.506718] systemd[1]: Startup finished in 973ms (kernel) + 4.044s (initrd) + 14.487s (userspace) = 19.505s.865machine: (finished: waiting for unit multi-user.target, in 21.37 seconds)866machine: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test867machine # Checking switch inhibitors... done868machine # [ 20.633318] nixos[829]: switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test869machine # activating the configuration...870machine # [ 20.637915] systemd[1]: Stopped target Local File Systems.871machine # [ 20.638785] systemd[1]: Stopped target Remote File Systems.872machine # setting up /etc...873machine # [ 20.982504] systemd[1]: Reload requested from client PID 829 ('.switch-to-conf') (unit backdoor.service)...874machine # [ 20.984672] systemd[1]: Reloading...875machine # [ 21.379167] systemd[1]: Reloading finished in 392 ms.876machine # reloading user units for usertest...877machine # [ 21.433751] systemd[534]: Created slice User Core Session Slice.878machine # [ 21.437045] systemd[534]: Starting D-Bus User Message Bus...879machine # [ 21.471388] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/06f4ai6wjjkb4jld63scbxzmrkbvz5v8-system-path/share/dbus-1/services/org.freedesktop.import1.service'880machine # [ 21.476174] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/06f4ai6wjjkb4jld63scbxzmrkbvz5v8-system-path/share/dbus-1/services/org.freedesktop.machine1.service'881machine # [ 21.482601] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/06f4ai6wjjkb4jld63scbxzmrkbvz5v8-system-path/share/dbus-1/services/org.freedesktop.portable1.service'882machine # [ 21.492427] dbus-broker-launch[899]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/06f4ai6wjjkb4jld63scbxzmrkbvz5v8-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'883machine # [ 21.495094] dbus-broker-launch[899]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored884machine # [ 21.507775] dbus-broker-launch[899]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored885machine # [ 21.513357] systemd[534]: Started D-Bus User Message Bus.886machine # [ 21.516308] dbus-broker-launch[899]: Ready887machine # [ 21.579230] systemd[534]: Reexecution requested from client PID 898 ('switch-to-confi')...888machine # [ 21.583173] systemd[534]: Reexecuting.889machine # [ 21.640476] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy890machine # [ 21.764304] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy891machine # [ 21.771561] systemd[534]: Reload requested from client PID 898 ('switch-to-confi')...892machine # [ 21.775742] systemd[534]: Reloading...893machine # [ 21.896363] systemd[534]: Reloading finished in 121 ms.894machine # [ 21.912207] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy895machine # restarting the following user units: nixos-activation.service896machine # [ 21.923083] systemd[534]: Stopped Run user-specific NixOS activation.897machine # [ 21.925446] systemd[534]: Stopping Run user-specific NixOS activation...898machine # [ 21.927562] systemd[534]: Starting Run user-specific NixOS activation...899machine # [ 21.964770] systemd[534]: Finished Run user-specific NixOS activation.900machine # restarting sysinit-reactivation.target901machine # [ 21.981266] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....902machine # [ 22.072135] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..903machine # [ 22.078124] systemd[1]: Reached target Reactivate sysinit units.904machine # [ 22.083448] systemd[1]: Reached target Remote File Systems.905machine # [ 22.088074] systemd[1]: Starting linger-users.service...906machine # [ 22.093785] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os907machine # [ 22.095193] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki908machine # [ 22.112991] systemd[1]: Reached target Local File Systems.909machine # [ 22.113952] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore910machine # [ 22.115418] systemd[1]: Starting Create SUID/SGID Wrappers...911machine # [ 22.122466] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.912machine # [ 22.123753] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67913machine # [ 22.126270] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore914machine # [ 22.127816] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os915machine # [ 22.134623] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os916machine # [ 22.193118] systemd[1]: linger-users.service: Deactivated successfully.917machine # [ 22.197967] systemd[1]: Finished linger-users.service.918machine # [ 22.396377] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.919machine # [ 22.399280] systemd[1]: Finished Create SUID/SGID Wrappers.920machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service921machine # [ 22.665060] nixos[829]: finished switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test922machine: (finished: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test, in 2.32 seconds)923machine: must succeed: rm /etc/NIXOS924machine: (finished: must succeed: rm /etc/NIXOS, in 0.05 seconds)925machine: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/2irxhqknxi5561v8fxgn0cvlp3fihk0j-nixos-system-other-test/bin/switch-to-configuration test926machine # this should succeed (config: /nix/store/2irxhqknxi5561v8fxgn0cvlp3fihk0j-nixos-system-other-test, action: test)927machine # Checking switch inhibitors... done928machine # [ 23.001569] nixos[977]: switching to system configuration /nix/store/2irxhqknxi5561v8fxgn0cvlp3fihk0j-nixos-system-other-test929machine # [ 23.005222] systemd[1]: Stopped target Remote File Systems.930machine # activating the configuration...931machine # [ 23.008380] systemd[1]: Stopped target Local File Systems.932machine # removing user ‘usertest’933machine # setting up /etc...934machine # [ 23.311753] systemd[1]: Reload requested from client PID 977 ('.switch-to-conf') (unit backdoor.service)...935machine # [ 23.314014] systemd[1]: Reloading...936machine # [ 23.665654] systemd[1]: Reloading finished in 350 ms.937machine # reloading user units for usertest...938machine # [ 23.761305] systemd[534]: Reexecution requested from client PID 1048 ('switch-to-confi')...939machine # [ 23.765585] systemd[534]: Reexecuting.940machine # [ 23.811918] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy941machine # [ 23.940683] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy942machine # [ 23.948178] systemd[534]: Reload requested from client PID 1048 ('switch-to-confi')...943machine # [ 23.949582] systemd[534]: Reloading...944machine # [ 24.072260] systemd[534]: Reloading finished in 120 ms.945machine # [ 24.090246] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy946machine # restarting the following user units: nixos-activation.service947machine # [ 24.100461] systemd[534]: Stopped Run user-specific NixOS activation.948machine # [ 24.103171] systemd[534]: Stopping Run user-specific NixOS activation...949machine # [ 24.105610] systemd[534]: Starting Run user-specific NixOS activation...950machine # [ 24.141635] systemd[534]: Finished Run user-specific NixOS activation.951machine # restarting sysinit-reactivation.target952machine # [ 24.146821] systemd[1]: Stopped target Reactivate sysinit units.953machine # reloading the following units: dbus-broker.service954machine # [ 24.151629] systemd[1]: Stopping Reactivate sysinit units...955machine # [ 24.153715] systemd[1]: Reached target Reactivate sysinit units.956machine # [ 24.155357] dbus-broker-launch[422]: Caught SIGHUP, trigger reload.957machine # [ 24.156845] systemd[1]: Reloading D-Bus System Message Bus...958machine # [ 24.162056] dbus-broker-launch[422]: Looking up NSS user entry for 'systemd-timesync'...959machine # [ 24.164437] dbus-broker-launch[422]: NSS returned no entry for 'systemd-timesync'960machine # [ 24.165518] dbus-broker-launch[422]: Invalid user-name in /nix/store/7zvww0026wqz1vns7gp7avsh9mj0gxkc-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"961machine # [ 24.182217] systemd[1]: Reloaded D-Bus System Message Bus.962machine # restarting the following units: network-addresses-eth1.service963machine # [ 24.189499] systemd[1]: Stopping Address configuration of eth1...964machine # [ 24.226304] network-addresses-eth1-pre-stop[1060]: deleting address 192.168.1.1/24... done965machine # [ 24.233530] network-addresses-eth1-pre-stop[1060]: deleting address 2001:db8:1::1/64... done966machine # [ 24.240946] systemd[1]: network-addresses-eth1.service: Deactivated successfully.967machine # [ 24.244253] systemd[1]: Stopped Address configuration of eth1.968machine # [ 24.248626] systemd[1]: Starting Address configuration of eth1...969machine # [ 24.293901] network-addresses-eth1-start[1065]: adding address 192.168.1.2/24... done970machine # [ 24.301804] network-addresses-eth1-start[1065]: adding address 2001:db8:1::2/64... done971machine # [ 24.311892] systemd[1]: Finished Address configuration of eth1.972machine # [ 24.316502] systemd[1]: Reached target Local File Systems.973machine # [ 24.319394] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore974machine # [ 24.328375] systemd[1]: Starting Create SUID/SGID Wrappers...975machine # [ 24.329318] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.976machine # [ 24.330531] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67977machine # [ 24.343762] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore978machine # [ 24.347839] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os979machine # [ 24.349351] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os980machine # [ 24.350497] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os981machine # [ 24.351720] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki982machine # [ 24.353357] systemd[1]: Reached target Remote File Systems.983machine # [ 24.542975] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.984machine # [ 24.547777] systemd[1]: Finished Create SUID/SGID Wrappers.985machine # [ 24.550058] systemd[1]: Starting linger-users.service...986machine # [ 24.579547] linger-users-start[1137]: id: ‘usertest’: no such user987machine # [ 24.581792] linger-users-start[1135]: Removing linger for missing user usertest988machine # [ 24.588569] systemd[1]: linger-users.service: Deactivated successfully.989machine # [ 24.591194] systemd[1]: Finished linger-users.service.990machine # [ 24.852633] nixos[977]: finished switching to system configuration /nix/store/2irxhqknxi5561v8fxgn0cvlp3fihk0j-nixos-system-other-test991machine: (finished: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/2irxhqknxi5561v8fxgn0cvlp3fihk0j-nixos-system-other-test/bin/switch-to-configuration test, in 2.13 seconds)992machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1993machine # [ 25.110417] nixos[1143]: switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test994machine # [ 25.113052] systemd[1]: Stopped target Remote File Systems.995machine # [ 25.115406] systemd[1]: Stopped target Local File Systems.996machine # [ 25.411701] systemd[1]: Reload requested from client PID 1143 ('.switch-to-conf') (unit backdoor.service)...997machine # [ 25.413976] systemd[1]: Reloading...998machine # [ 25.955955] systemd[1]: Reloading finished in 540 ms.999machine # [ 26.051073] systemd[534]: Reexecution requested from client PID 1213 ('switch-to-confi')...1000machine # [ 26.053310] systemd[534]: Reexecuting.1001machine # [ 26.107598] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1002machine # [ 26.226294] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1003machine # [ 26.232904] systemd[534]: Reload requested from client PID 1213 ('switch-to-confi')...1004machine # [ 26.235418] systemd[534]: Reloading...1005machine # [ 26.354017] systemd[534]: Reloading finished in 117 ms.1006machine # [ 26.369808] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1007machine # [ 26.378179] systemd[534]: Stopped Run user-specific NixOS activation.1008machine # [ 26.380726] systemd[534]: Stopping Run user-specific NixOS activation...1009machine # [ 26.383173] systemd[534]: Starting Run user-specific NixOS activation...1010machine # [ 26.419128] systemd[534]: Finished Run user-specific NixOS activation.1011machine # [ 26.423813] systemd[1]: Stopped target Reactivate sysinit units.1012machine # [ 26.426219] systemd[1]: Stopping Reactivate sysinit units...1013machine # [ 26.427098] systemd[1]: Reached target Reactivate sysinit units.1014machine # [ 26.430314] dbus-broker-launch[422]: Caught SIGHUP, trigger reload.1015machine # [ 26.431301] systemd[1]: Reloading D-Bus System Message Bus...1016machine # [ 26.437016] dbus-broker-launch[422]: Looking up NSS user entry for 'systemd-timesync'...1017machine # [ 26.439376] dbus-broker-launch[422]: NSS returned no entry for 'systemd-timesync'1018machine # [ 26.440713] dbus-broker-launch[422]: Invalid user-name in /nix/store/06f4ai6wjjkb4jld63scbxzmrkbvz5v8-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1019machine # [ 26.456814] systemd[1]: Reloaded D-Bus System Message Bus.1020machine # [ 26.463051] systemd[1]: Stopping Address configuration of eth1...1021machine # [ 26.496573] network-addresses-eth1-pre-stop[1225]: deleting address 192.168.1.2/24... done1022machine # [ 26.503420] network-addresses-eth1-pre-stop[1225]: deleting address 2001:db8:1::2/64... done1023machine # [ 26.511224] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1024machine # [ 26.514009] systemd[1]: Stopped Address configuration of eth1.1025machine # [ 26.519050] systemd[1]: Starting Address configuration of eth1...1026machine # [ 26.568007] network-addresses-eth1-start[1230]: adding address 192.168.1.1/24... done1027machine # [ 26.577427] network-addresses-eth1-start[1230]: adding address 2001:db8:1::1/64... done1028machine # [ 26.588821] systemd[1]: Finished Address configuration of eth1.1029machine # [ 26.595374] systemd[1]: Reached target Remote File Systems.1030machine # [ 26.600385] systemd[1]: Starting linger-users.service...1031machine # [ 26.606063] systemd[1]: Reached target Local File Systems.1032machine # [ 26.615578] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1033machine # [ 26.619323] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1034machine # [ 26.620887] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1035machine # [ 26.628300] systemd[1]: Starting Create SUID/SGID Wrappers...1036machine # [ 26.636867] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1037machine # [ 26.638393] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671038machine # [ 26.648283] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1039machine # [ 26.650954] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1040machine # [ 26.654837] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1041machine # [ 26.689173] systemd[1]: linger-users.service: Deactivated successfully.1042machine # [ 26.692638] systemd[1]: Finished linger-users.service.1043machine # [ 26.878611] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1044machine # [ 26.882001] systemd[1]: Finished Create SUID/SGID Wrappers.1045machine # [ 27.140971] nixos[1143]: finished switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test1046machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.29 seconds)1047subtest: user services1048machine: waiting for unit user@1001.service1049machine: (finished: waiting for unit user@1001.service, in 0.10 seconds)1050machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11051machine # [ 27.474084] nixos[1303]: switching to system configuration /nix/store/kwbg73nja10ymgkwl9pygbvpwdl0j07f-nixos-system-machine-test1052machine # [ 27.476959] systemd[1]: Stopped target Remote File Systems.1053machine # [ 27.479426] systemd[1]: Stopped target Local File Systems.1054machine # [ 27.783000] systemd[1]: Reload requested from client PID 1303 ('.switch-to-conf') (unit backdoor.service)...1055machine # [ 27.785180] systemd[1]: Reloading...1056machine # [ 28.117571] systemd[1]: Reloading finished in 329 ms.1057machine # [ 28.240516] systemd[534]: Reexecution requested from client PID 1372 ('switch-to-confi')...1058machine # [ 28.241975] systemd[534]: Reexecuting.1059machine # [ 28.303850] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1060machine # [ 28.428912] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1061machine # [ 28.435815] systemd[534]: Reload requested from client PID 1372 ('switch-to-confi')...1062machine # [ 28.438312] systemd[534]: Reloading...1063machine # [ 28.558265] systemd[534]: Reloading finished in 118 ms.1064machine # [ 28.572485] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1065machine # [ 28.583688] systemd[534]: Starting usertest.service...1066machine # [ 28.610044] systemd[534]: Finished usertest.service.1067machine # [ 28.613098] systemd[534]: Stopped Run user-specific NixOS activation.1068machine # [ 28.615516] systemd[534]: Stopping Run user-specific NixOS activation...1069machine # [ 28.617796] systemd[534]: Starting Run user-specific NixOS activation...1070machine # [ 28.655286] systemd[534]: Finished Run user-specific NixOS activation.1071machine # [ 28.660929] systemd[1]: Stopped target Reactivate sysinit units.1072machine # [ 28.661989] systemd[1]: Stopping Reactivate sysinit units...1073machine # [ 28.662792] systemd[1]: Reached target Reactivate sysinit units.1074machine # [ 28.667857] systemd[1]: Reached target Remote File Systems.1075machine # [ 28.672851] systemd[1]: Starting linger-users.service...1076machine # [ 28.687185] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1077machine # [ 28.692768] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1078machine # [ 28.694164] systemd[1]: Reached target Local File Systems.1079machine # [ 28.694930] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1080machine # [ 28.709588] systemd[1]: Starting Create SUID/SGID Wrappers...1081machine # [ 28.710624] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1082machine # [ 28.712888] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671083machine # [ 28.717323] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1084machine # [ 28.721337] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1085machine # [ 28.722764] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1086machine # [ 28.771072] systemd[1]: linger-users.service: Deactivated successfully.1087machine # [ 28.774073] systemd[1]: Finished linger-users.service.1088machine # [ 28.943200] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1089machine # [ 28.945797] systemd[1]: Finished Create SUID/SGID Wrappers.1090machine # [ 29.205971] nixos[1303]: finished switching to system configuration /nix/store/kwbg73nja10ymgkwl9pygbvpwdl0j07f-nixos-system-machine-test1091machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.96 seconds)1092machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1093machine # [ 29.293785] sudo[1443]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1094machine # [ 29.313112] sudo[1443]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1095machine # [ 29.329653] sudo[1443]: pam_unix(sudo:session): session closed for user usertest1096machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.12 seconds)1097machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11098machine # [ 29.550941] nixos[1452]: switching to system configuration /nix/store/kwbg73nja10ymgkwl9pygbvpwdl0j07f-nixos-system-machine-test1099machine # [ 29.553799] systemd[1]: Stopped target Remote File Systems.1100machine # [ 29.556503] systemd[1]: Stopped target Local File Systems.1101machine # [ 29.857257] systemd[1]: Reload requested from client PID 1452 ('.switch-to-conf') (unit backdoor.service)...1102machine # [ 29.858834] systemd[1]: Reloading...1103machine # [ 30.201553] systemd[1]: Reloading finished in 341 ms.1104machine # [ 30.288335] systemd[534]: Reexecution requested from client PID 1521 ('switch-to-confi')...1105machine # [ 30.292773] systemd[534]: Reexecuting.1106machine # [ 30.343257] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1107machine # [ 30.460816] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1108machine # [ 30.467731] systemd[534]: Reload requested from client PID 1521 ('switch-to-confi')...1109machine # [ 30.471437] systemd[534]: Reloading...1110machine # [ 30.585738] systemd[534]: Reloading finished in 115 ms.1111machine # [ 30.600469] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1112machine # [ 30.615510] systemd[534]: Stopped Run user-specific NixOS activation.1113machine # [ 30.619316] systemd[534]: Stopping Run user-specific NixOS activation...1114machine # [ 30.622968] systemd[534]: Starting Run user-specific NixOS activation...1115machine # [ 30.658823] systemd[534]: Finished Run user-specific NixOS activation.1116machine # [ 30.663262] systemd[1]: Stopped target Reactivate sysinit units.1117machine # [ 30.665935] systemd[1]: Stopping Reactivate sysinit units...1118machine # [ 30.666837] systemd[1]: Reached target Reactivate sysinit units.1119machine # [ 30.670202] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1120machine # [ 30.673416] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1121machine # [ 30.676332] systemd[1]: Reached target Remote File Systems.1122machine # [ 30.680381] systemd[1]: Starting linger-users.service...1123machine # [ 30.686531] systemd[1]: Reached target Local File Systems.1124machine # [ 30.687555] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1125machine # [ 30.696753] systemd[1]: Starting Create SUID/SGID Wrappers...1126machine # [ 30.697706] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1127machine # [ 30.698953] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671128machine # [ 30.715841] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1129machine # [ 30.719539] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1130machine # [ 30.722733] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1131machine # [ 30.774838] systemd[1]: linger-users.service: Deactivated successfully.1132machine # [ 30.776887] systemd[1]: Finished linger-users.service.1133machine # [ 30.923779] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1134machine # [ 30.927675] systemd[1]: Finished Create SUID/SGID Wrappers.1135machine # [ 31.184990] nixos[1452]: finished switching to system configuration /nix/store/kwbg73nja10ymgkwl9pygbvpwdl0j07f-nixos-system-machine-test1136machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.87 seconds)1137machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&11138machine # [ 31.446712] nixos[1592]: switching to system configuration /nix/store/nhdzqcz5xv7176xfi78r1igjhrd6pal3-nixos-system-machine-test1139machine # [ 31.449295] systemd[1]: Stopped target Remote File Systems.1140machine # [ 31.451618] systemd[1]: Stopped target Local File Systems.1141machine # [ 31.801851] systemd[1]: Reload requested from client PID 1592 ('.switch-to-conf') (unit backdoor.service)...1142machine # [ 31.803812] systemd[1]: Reloading...1143machine # [ 32.141064] systemd[1]: Reloading finished in 335 ms.1144machine # [ 32.235743] systemd[534]: Stopped usertest.service.1145machine # [ 32.237668] systemd[534]: Reexecution requested from client PID 1662 ('switch-to-confi')...1146machine # [ 32.241157] systemd[534]: Reexecuting.1147machine # [ 32.287226] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1148machine # [ 32.405668] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1149machine # [ 32.412467] systemd[534]: Reload requested from client PID 1662 ('switch-to-confi')...1150machine # [ 32.415134] systemd[534]: Reloading...1151machine # [ 32.532269] systemd[534]: Reloading finished in 116 ms.1152machine # [ 32.548212] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1153machine # [ 32.558747] systemd[534]: Starting usertest.service...1154machine # [ 32.587257] systemd[534]: Finished usertest.service.1155machine # [ 32.591394] systemd[534]: Stopped Run user-specific NixOS activation.1156machine # [ 32.592596] systemd[534]: Stopping Run user-specific NixOS activation...1157machine # [ 32.595109] systemd[534]: Starting Run user-specific NixOS activation...1158machine # [ 32.630875] systemd[534]: Finished Run user-specific NixOS activation.1159machine # [ 32.635456] systemd[1]: Stopped target Reactivate sysinit units.1160machine # [ 32.638327] systemd[1]: Stopping Reactivate sysinit units...1161machine # [ 32.639238] systemd[1]: Reached target Reactivate sysinit units.1162machine # [ 32.644530] systemd[1]: Reached target Remote File Systems.1163machine # [ 32.647024] systemd[1]: Starting linger-users.service...1164machine # [ 32.653151] systemd[1]: Reached target Local File Systems.1165machine # [ 32.654148] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1166machine # [ 32.668228] systemd[1]: Starting Create SUID/SGID Wrappers...1167machine # [ 32.669321] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1168machine # [ 32.670585] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671169machine # [ 32.676743] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1170machine # [ 32.678492] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1171machine # [ 32.679766] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1172machine # [ 32.692868] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1173machine # [ 32.694406] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1174machine # [ 32.750377] systemd[1]: linger-users.service: Deactivated successfully.1175machine # [ 32.755076] systemd[1]: Finished linger-users.service.1176machine # [ 32.913787] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1177machine # [ 32.917252] systemd[1]: Finished Create SUID/SGID Wrappers.1178machine # [ 33.176316] nixos[1592]: finished switching to system configuration /nix/store/nhdzqcz5xv7176xfi78r1igjhrd6pal3-nixos-system-machine-test1179machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 1.99 seconds)1180machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1181machine # [ 33.253810] sudo[1732]: 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.service1182machine # [ 33.273433] sudo[1732]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1183machine # [ 33.289521] sudo[1732]: pam_unix(sudo:session): session closed for user usertest1184machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds)1185machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&11186machine # [ 33.510360] nixos[1741]: switching to system configuration /nix/store/265rbcs71l7xkn86nk8g5z77bv7jnbk1-nixos-system-machine-test1187machine # [ 33.513440] systemd[1]: Stopped target Local File Systems.1188machine # [ 33.515147] systemd[1]: Stopped target Remote File Systems.1189machine # [ 33.827748] systemd[1]: Reload requested from client PID 1741 ('.switch-to-conf') (unit backdoor.service)...1190machine # [ 33.830128] systemd[1]: Reloading...1191machine # [ 34.172512] systemd[1]: Reloading finished in 340 ms.1192machine # [ 34.267672] systemd[534]: Reexecution requested from client PID 1810 ('switch-to-confi')...1193machine # [ 34.271508] systemd[534]: Reexecuting.1194machine # [ 34.327490] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1195machine # [ 34.448899] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1196machine # [ 34.456116] systemd[534]: Reload requested from client PID 1810 ('switch-to-confi')...1197machine # [ 34.457485] systemd[534]: Reloading...1198machine # [ 34.573824] systemd[534]: Reloading finished in 114 ms.1199machine # [ 34.589818] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1200machine # [ 34.594790] systemd[534]: Stopped usertest.service.1201machine # [ 34.596990] systemd[534]: Stopping usertest.service...1202machine # [ 34.599085] systemd[534]: Starting usertest.service...1203machine # [ 34.626474] systemd[534]: Finished usertest.service.1204machine # [ 34.631866] systemd[534]: Stopped Run user-specific NixOS activation.1205machine # [ 34.634399] systemd[534]: Stopping Run user-specific NixOS activation...1206machine # [ 34.636516] systemd[534]: Starting Run user-specific NixOS activation...1207machine # [ 34.673203] systemd[534]: Finished Run user-specific NixOS activation.1208machine # [ 34.678162] systemd[1]: Stopped target Reactivate sysinit units.1209machine # [ 34.680232] systemd[1]: Stopping Reactivate sysinit units...1210machine # [ 34.681163] systemd[1]: Reached target Reactivate sysinit units.1211machine # [ 34.684431] systemd[1]: Reached target Local File Systems.1212machine # [ 34.691070] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1213machine # [ 34.702363] systemd[1]: Starting Create SUID/SGID Wrappers...1214machine # [ 34.703298] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1215machine # [ 34.704904] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671216machine # [ 34.707069] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1217machine # [ 34.714793] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1218machine # [ 34.716270] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1219machine # [ 34.717498] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1220machine # [ 34.718747] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1221machine # [ 34.719990] systemd[1]: Reached target Remote File Systems.1222machine # [ 34.943347] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1223machine # [ 34.948779] systemd[1]: Finished Create SUID/SGID Wrappers.1224machine # [ 34.951247] systemd[1]: Starting linger-users.service...1225machine # [ 34.996897] systemd[1]: linger-users.service: Deactivated successfully.1226machine # [ 35.000335] systemd[1]: Finished linger-users.service.1227machine # [ 35.259910] nixos[1741]: finished switching to system configuration /nix/store/265rbcs71l7xkn86nk8g5z77bv7jnbk1-nixos-system-machine-test1228machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 1.98 seconds)1229machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1230machine # [ 35.336537] sudo[1881]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1231machine # [ 35.356771] sudo[1881]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1232machine # [ 35.373261] sudo[1881]: pam_unix(sudo:session): session closed for user usertest1233machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds)1234machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&11235machine # [ 35.589333] nixos[1890]: switching to system configuration /nix/store/1gp9a4zxd5c52zpbk12khvz6q9xkmzjj-nixos-system-machine-test1236machine # [ 35.591770] systemd[1]: Stopped target Remote File Systems.1237machine # [ 35.594214] systemd[1]: Stopped target Local File Systems.1238machine # [ 35.897382] systemd[1]: Reload requested from client PID 1890 ('.switch-to-conf') (unit backdoor.service)...1239machine # [ 35.899340] systemd[1]: Reloading...1240machine # [ 36.237572] systemd[1]: Reloading finished in 336 ms.1241machine # [ 36.337178] systemd[534]: Reexecution requested from client PID 1959 ('switch-to-confi')...1242machine # [ 36.341343] systemd[534]: Reexecuting.1243machine # [ 36.387420] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1244machine # [ 36.508409] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1245machine # [ 36.515505] systemd[534]: Reload requested from client PID 1959 ('switch-to-confi')...1246machine # [ 36.519446] systemd[534]: Reloading...1247machine # [ 36.633820] systemd[534]: Reloading finished in 115 ms.1248machine # [ 36.648201] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1249machine # [ 36.657028] systemd[534]: Reloading usertest.service...1250machine # [ 36.683255] systemd[534]: Reloaded usertest.service.1251machine # [ 36.688700] systemd[534]: Stopped Run user-specific NixOS activation.1252machine # [ 36.691168] systemd[534]: Stopping Run user-specific NixOS activation...1253machine # [ 36.693750] systemd[534]: Starting Run user-specific NixOS activation...1254machine # [ 36.732770] systemd[534]: Finished Run user-specific NixOS activation.1255machine # [ 36.737023] systemd[1]: Stopped target Reactivate sysinit units.1256machine # [ 36.739951] systemd[1]: Stopping Reactivate sysinit units...1257machine # [ 36.741888] systemd[1]: Reached target Reactivate sysinit units.1258machine # [ 36.746770] systemd[1]: Reached target Local File Systems.1259machine # [ 36.750369] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1260machine # [ 36.753226] systemd[1]: Starting Create SUID/SGID Wrappers...1261machine # [ 36.754133] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1262machine # [ 36.761203] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671263machine # [ 36.763528] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1264machine # [ 36.775499] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1265machine # [ 36.777237] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1266machine # [ 36.778476] systemd[1]: Reached target Remote File Systems.1267machine # [ 36.779257] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1268machine # [ 36.780625] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1269machine # [ 36.963204] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1270machine # [ 36.968589] systemd[1]: Finished Create SUID/SGID Wrappers.1271machine # [ 36.970901] systemd[1]: Starting linger-users.service...1272machine # [ 37.016507] systemd[1]: linger-users.service: Deactivated successfully.1273machine # [ 37.019353] systemd[1]: Finished linger-users.service.1274machine # [ 37.279759] nixos[1890]: finished switching to system configuration /nix/store/1gp9a4zxd5c52zpbk12khvz6q9xkmzjj-nixos-system-machine-test1275machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 1.91 seconds)1276machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1277machine # [ 37.357212] sudo[2029]: 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.service1278machine # [ 37.375593] sudo[2029]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1279machine # [ 37.391037] sudo[2029]: pam_unix(sudo:session): session closed for user usertest1280machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds)1281machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11282machine # [ 37.608251] nixos[2038]: switching to system configuration /nix/store/kwbg73nja10ymgkwl9pygbvpwdl0j07f-nixos-system-machine-test1283machine # [ 37.610893] systemd[1]: Stopped target Local File Systems.1284machine # [ 37.613099] systemd[1]: Stopped target Remote File Systems.1285machine # [ 37.892519] systemd[1]: Reload requested from client PID 2038 ('.switch-to-conf') (unit backdoor.service)...1286machine # [ 37.894168] systemd[1]: Reloading...1287machine # [ 38.239830] systemd[1]: Reloading finished in 343 ms.1288machine # [ 38.366362] systemd[534]: Stopped usertest.service.1289machine # [ 38.370257] systemd[534]: Reexecution requested from client PID 2107 ('switch-to-confi')...1290machine # [ 38.371626] systemd[534]: Reexecuting.1291machine # [ 38.427577] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1292machine # [ 38.547432] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1293machine # [ 38.554127] systemd[534]: Reload requested from client PID 2107 ('switch-to-confi')...1294machine # [ 38.557937] systemd[534]: Reloading...1295machine # [ 38.672511] systemd[534]: Reloading finished in 115 ms.1296machine # [ 38.689753] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1297machine # [ 38.698722] systemd[534]: Starting usertest.service...1298machine # [ 38.726384] systemd[534]: Finished usertest.service.1299machine # [ 38.730414] systemd[534]: Stopped Run user-specific NixOS activation.1300machine # [ 38.731448] systemd[534]: Stopping Run user-specific NixOS activation...1301machine # [ 38.733864] systemd[534]: Starting Run user-specific NixOS activation...1302machine # [ 38.769024] systemd[534]: Finished Run user-specific NixOS activation.1303machine # [ 38.772975] systemd[1]: Stopped target Reactivate sysinit units.1304machine # [ 38.776931] systemd[1]: Stopping Reactivate sysinit units...1305machine # [ 38.778017] systemd[1]: Reached target Reactivate sysinit units.1306machine # [ 38.780480] systemd[1]: Reached target Local File Systems.1307machine # [ 38.781371] systemd[1]: Reached target Remote File Systems.1308machine # [ 38.786481] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1309machine # [ 38.787897] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1310machine # [ 38.794879] systemd[1]: Starting linger-users.service...1311machine # [ 38.802801] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1312machine # [ 38.807973] systemd[1]: Starting Create SUID/SGID Wrappers...1313machine # [ 38.809432] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1314machine # [ 38.810657] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671315machine # [ 38.820420] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1316machine # [ 38.822258] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1317machine # [ 38.823501] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1318machine # [ 38.877894] systemd[1]: linger-users.service: Deactivated successfully.1319machine # [ 38.880983] systemd[1]: Finished linger-users.service.1320machine # [ 39.019249] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1321machine # [ 39.021529] systemd[1]: Finished Create SUID/SGID Wrappers.1322machine # [ 39.281096] nixos[2038]: finished switching to system configuration /nix/store/kwbg73nja10ymgkwl9pygbvpwdl0j07f-nixos-system-machine-test1323machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.90 seconds)1324machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1325machine # [ 39.361408] sudo[2178]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1326machine # [ 39.383392] sudo[2178]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1327machine # [ 39.400791] sudo[2178]: pam_unix(sudo:session): session closed for user usertest1328machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds)1329machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&11330machine # [ 39.620356] nixos[2187]: switching to system configuration /nix/store/5ykvchrrj684zvdsxkdrcicrfa4whmaq-nixos-system-machine-test1331machine # [ 39.623133] systemd[1]: Stopped target Local File Systems.1332machine # [ 39.625158] systemd[1]: Stopped target Remote File Systems.1333machine # [ 39.921439] systemd[1]: Reload requested from client PID 2187 ('.switch-to-conf') (unit backdoor.service)...1334machine # [ 39.923353] systemd[1]: Reloading...1335machine # [ 40.240975] systemd[1]: Reloading finished in 316 ms.1336machine # [ 40.339945] systemd[534]: Reexecution requested from client PID 2256 ('switch-to-confi')...1337machine # [ 40.342232] systemd[534]: Reexecuting.1338machine # [ 40.400041] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1339machine # [ 40.558566] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1340machine # [ 40.566119] systemd[534]: Reload requested from client PID 2256 ('switch-to-confi')...1341machine # [ 40.570449] systemd[534]: Reloading...1342machine # [ 40.683814] systemd[534]: Reloading finished in 114 ms.1343machine # [ 40.700197] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1344machine # [ 40.710225] systemd[534]: Reloading usertest.service...1345machine # [ 40.734854] systemd[534]: Reloaded usertest.service.1346machine # [ 40.741668] systemd[534]: Stopped Run user-specific NixOS activation.1347machine # [ 40.742729] systemd[534]: Stopping Run user-specific NixOS activation...1348machine # [ 40.744949] systemd[534]: Starting Run user-specific NixOS activation...1349machine # [ 40.782658] systemd[534]: Finished Run user-specific NixOS activation.1350machine # [ 40.787573] systemd[1]: Stopped target Reactivate sysinit units.1351machine # [ 40.791101] systemd[1]: Stopping Reactivate sysinit units...1352machine # [ 40.794154] systemd[1]: Reached target Reactivate sysinit units.1353machine # [ 40.798699] systemd[1]: Reached target Local File Systems.1354machine # [ 40.799671] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1355machine # [ 40.808437] systemd[1]: Starting Create SUID/SGID Wrappers...1356machine # [ 40.809467] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1357machine # [ 40.810702] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671358machine # [ 40.823441] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1359machine # [ 40.826301] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1360machine # [ 40.827626] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1361machine # [ 40.828961] systemd[1]: Reached target Remote File Systems.1362machine # [ 40.829792] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1363machine # [ 40.831076] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1364machine # [ 41.007827] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1365machine # [ 41.012476] systemd[1]: Finished Create SUID/SGID Wrappers.1366machine # [ 41.015382] systemd[1]: Starting linger-users.service...1367machine # [ 41.058897] systemd[1]: linger-users.service: Deactivated successfully.1368machine # [ 41.061691] systemd[1]: Finished linger-users.service.1369machine # [ 41.317527] nixos[2187]: finished switching to system configuration /nix/store/5ykvchrrj684zvdsxkdrcicrfa4whmaq-nixos-system-machine-test1370machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 1.92 seconds)1371machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1372machine # [ 41.363637] sudo[2326]: 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.service1373machine # [ 41.378454] sudo[2326]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1374machine # [ 41.391609] sudo[2326]: pam_unix(sudo:session): session closed for user usertest1375machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1376machine: must fail: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&11377machine # [ 41.640055] nixos[2335]: switching to system configuration /nix/store/r3r59xsy6ysrvc21r5130z8j4bbhjfrr-nixos-system-machine-test1378machine # [ 41.643632] systemd[1]: Stopped target Remote File Systems.1379machine # [ 41.644763] systemd[1]: Stopped target Local File Systems.1380machine # [ 41.937466] systemd[1]: Reload requested from client PID 2335 ('.switch-to-conf') (unit backdoor.service)...1381machine # [ 41.939444] systemd[1]: Reloading...1382machine # [ 42.281912] systemd[1]: Reloading finished in 340 ms.1383machine # [ 42.382661] systemd[534]: Stopped usertest.service.1384machine # [ 42.384842] systemd[534]: Reexecution requested from client PID 2405 ('switch-to-confi')...1385machine # [ 42.390201] systemd[534]: Reexecuting.1386machine # [ 42.448367] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1387machine # [ 42.575014] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1388machine # [ 42.581879] systemd[534]: Reload requested from client PID 2405 ('switch-to-confi')...1389machine # [ 42.585748] systemd[534]: Reloading...1390machine # [ 42.699053] systemd[534]: Reloading finished in 114 ms.1391machine # [ 42.712208] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1392machine # [ 42.722991] systemd[534]: Starting usertest.service...1393machine # [ 42.750047] systemd[534]: usertest.service: Main process exited, code=exited, status=1/FAILURE1394machine # [ 42.752900] systemd[534]: usertest.service: Failed with result 'exit-code'.1395machine # [ 42.755211] systemd[534]: Failed to start usertest.service.1396machine # [ 42.757795] systemd[534]: Stopped Run user-specific NixOS activation.1397machine # [ 42.760169] systemd[534]: Stopping Run user-specific NixOS activation...1398machine # [ 42.762553] systemd[534]: Starting Run user-specific NixOS activation...1399machine # [ 42.800725] systemd[534]: Finished Run user-specific NixOS activation.1400machine # [ 42.805965] systemd[1]: Stopped target Reactivate sysinit units.1401machine # [ 42.807037] systemd[1]: Stopping Reactivate sysinit units...1402machine # [ 42.807853] systemd[1]: Reached target Reactivate sysinit units.1403machine # [ 42.811501] systemd[1]: Reached target Remote File Systems.1404machine # [ 42.817081] systemd[1]: Starting linger-users.service...1405machine # [ 42.824400] systemd[1]: Reached target Local File Systems.1406machine # [ 42.828001] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1407machine # [ 42.833753] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1408machine # [ 42.843095] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1409machine # [ 42.852274] systemd[1]: Starting Create SUID/SGID Wrappers...1410machine # [ 42.853377] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1411machine # [ 42.854622] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671412machine # [ 42.863123] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1413machine # [ 42.869063] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1414machine # [ 42.875177] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1415machine # [ 42.929392] systemd[1]: linger-users.service: Deactivated successfully.1416machine # [ 42.932492] systemd[1]: Finished linger-users.service.1417machine # [ 43.082939] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1418machine # [ 43.086039] systemd[1]: Finished Create SUID/SGID Wrappers.1419machine # [ 43.345130] nixos[2335]: switching to system configuration /nix/store/r3r59xsy6ysrvc21r5130z8j4bbhjfrr-nixos-system-machine-test failed (status 4)1420machine: (finished: must fail: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 1.97 seconds)1421machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11422machine # [ 43.628723] nixos[2478]: switching to system configuration /nix/store/kwbg73nja10ymgkwl9pygbvpwdl0j07f-nixos-system-machine-test1423machine # [ 43.632972] systemd[1]: Stopped target Local File Systems.1424machine # [ 43.635319] systemd[1]: Stopped target Remote File Systems.1425machine # [ 43.922144] systemd[1]: Reload requested from client PID 2478 ('.switch-to-conf') (unit backdoor.service)...1426machine # [ 43.924218] systemd[1]: Reloading...1427machine # [ 44.257951] systemd[1]: Reloading finished in 331 ms.1428machine # [ 44.350863] systemd[534]: Reexecution requested from client PID 2547 ('switch-to-confi')...1429machine # [ 44.354737] systemd[534]: Reexecuting.1430machine # [ 44.403413] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1431machine # [ 44.521554] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1432machine # [ 44.528769] systemd[534]: Reload requested from client PID 2547 ('switch-to-confi')...1433machine # [ 44.531443] systemd[534]: Reloading...1434machine # [ 44.647294] systemd[534]: Reloading finished in 114 ms.1435machine # [ 44.664185] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1436machine # [ 44.675833] systemd[534]: Starting usertest.service...1437machine # [ 44.702953] systemd[534]: Finished usertest.service.1438machine # [ 44.706048] systemd[534]: Stopped Run user-specific NixOS activation.1439machine # [ 44.708500] systemd[534]: Stopping Run user-specific NixOS activation...1440machine # [ 44.710761] systemd[534]: Starting Run user-specific NixOS activation...1441machine # [ 44.745858] systemd[534]: Finished Run user-specific NixOS activation.1442machine # [ 44.750062] systemd[1]: Stopped target Reactivate sysinit units.1443machine # [ 44.752807] systemd[1]: Stopping Reactivate sysinit units...1444machine # [ 44.753739] systemd[1]: Reached target Reactivate sysinit units.1445machine # [ 44.758251] systemd[1]: Reached target Local File Systems.1446machine # [ 44.759191] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1447machine # [ 44.768874] systemd[1]: Starting Create SUID/SGID Wrappers...1448machine # [ 44.769900] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1449machine # [ 44.771203] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671450machine # [ 44.791397] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1451machine # [ 44.795921] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1452machine # [ 44.798844] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1453machine # [ 44.802129] systemd[1]: Reached target Remote File Systems.1454machine # [ 44.807322] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1455machine # [ 44.809272] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1456machine # [ 45.007074] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1457machine # [ 45.010228] systemd[1]: Finished Create SUID/SGID Wrappers.1458machine # [ 45.014681] systemd[1]: Starting linger-users.service...1459machine # [ 45.059005] systemd[1]: linger-users.service: Deactivated successfully.1460machine # [ 45.061800] systemd[1]: Finished linger-users.service.1461machine # [ 45.320350] nixos[2478]: finished switching to system configuration /nix/store/kwbg73nja10ymgkwl9pygbvpwdl0j07f-nixos-system-machine-test1462machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.97 seconds)1463machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1464machine # [ 45.399949] sudo[2618]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1465machine # [ 45.421751] sudo[2618]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1466machine # [ 45.438416] sudo[2618]: pam_unix(sudo:session): session closed for user usertest1467machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds)1468machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&11469machine # [ 45.656203] nixos[2627]: switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test1470machine # [ 45.658929] systemd[1]: Stopped target Remote File Systems.1471machine # [ 45.661220] systemd[1]: Stopped target Local File Systems.1472machine # [ 45.928651] systemd[1]: Reload requested from client PID 2627 ('.switch-to-conf') (unit backdoor.service)...1473machine # [ 45.930615] systemd[1]: Reloading...1474machine # [ 46.258223] systemd[1]: Reloading finished in 326 ms.1475machine # [ 46.351619] systemd[534]: Stopped usertest.service.1476machine # [ 46.355039] systemd[534]: Reexecution requested from client PID 2696 ('switch-to-confi')...1477machine # [ 46.357365] systemd[534]: Reexecuting.1478machine # [ 46.415735] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1479machine # [ 46.531574] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1480machine # [ 46.538210] systemd[534]: Reload requested from client PID 2696 ('switch-to-confi')...1481machine # [ 46.540677] systemd[534]: Reloading...1482machine # [ 46.651667] systemd[534]: Reloading finished in 109 ms.1483machine # [ 46.672221] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1484machine # [ 46.681688] systemd[534]: Stopped Run user-specific NixOS activation.1485machine # [ 46.684146] systemd[534]: Stopping Run user-specific NixOS activation...1486machine # [ 46.686365] systemd[534]: Starting Run user-specific NixOS activation...1487machine # [ 46.720927] systemd[534]: Finished Run user-specific NixOS activation.1488machine # [ 46.725238] systemd[1]: Stopped target Reactivate sysinit units.1489machine # [ 46.728509] systemd[1]: Stopping Reactivate sysinit units...1490machine # [ 46.729387] systemd[1]: Reached target Reactivate sysinit units.1491machine # [ 46.730199] systemd[1]: Reached target Remote File Systems.1492machine # [ 46.732488] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1493machine # [ 46.733867] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1494machine # [ 46.740282] systemd[1]: Reached target Local File Systems.1495machine # [ 46.748678] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1496machine # [ 46.750251] systemd[1]: Starting Create SUID/SGID Wrappers...1497machine # [ 46.751048] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1498machine # [ 46.759154] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671499machine # [ 46.761721] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1500machine # [ 46.763274] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1501machine # [ 46.764569] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1502machine # [ 46.925765] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1503machine # [ 46.927624] systemd[1]: Finished Create SUID/SGID Wrappers.1504machine # [ 46.933145] systemd[1]: Starting linger-users.service...1505machine # [ 46.974678] systemd[1]: linger-users.service: Deactivated successfully.1506machine # [ 46.977496] systemd[1]: Finished linger-users.service.1507machine # [ 47.235232] nixos[2627]: finished switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test1508machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.80 seconds)1509machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1510machine # [ 47.289258] sudo[2764]: 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.service1511machine # [ 47.303549] sudo[2764]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1512machine # [ 47.317947] sudo[2764]: pam_unix(sudo:session): session closed for user usertest1513machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1514machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1515machine # [ 47.352492] sudo[2770]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1516machine # [ 47.364349] sudo[2770]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1517machine # [ 47.372634] sudo[2770]: pam_unix(sudo:session): session closed for user usertest1518machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.05 seconds)1519machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service1520machine # [ 47.402252] sudo[2776]: 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.service1521machine # [ 47.412463] sudo[2776]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1522machine # [ 47.421593] sudo[2776]: pam_unix(sudo:session): session closed for user usertest1523machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1524machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1525machine # [ 47.450126] sudo[2782]: 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.service1526machine # [ 47.461045] sudo[2782]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1527machine # [ 47.468846] sudo[2782]: pam_unix(sudo:session): session closed for user usertest1528machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)1529machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1530machine # [ 47.496778] sudo[2788]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1531machine # [ 47.506398] sudo[2788]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1532machine # [ 47.514945] systemd[534]: Reload requested from client PID 2793 ('systemctl')...1533machine # [ 47.519028] systemd[534]: Reloading...1534machine # [ 47.632805] systemd[534]: Reloading finished in 115 ms.1535machine # [ 47.644202] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1536machine # [ 47.657000] sudo[2788]: pam_unix(sudo:session): session closed for user usertest1537machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)1538machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1539machine # [ 47.685502] sudo[2798]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1540machine # [ 47.695540] sudo[2798]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1541machine # [ 47.709923] systemd[534]: Starting migrated.service...1542machine # [ 47.739432] systemd[534]: Finished migrated.service.1543machine # [ 47.743805] sudo[2798]: pam_unix(sudo:session): session closed for user usertest1544machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)1545machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1546machine # [ 47.772330] sudo[2806]: 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.service1547machine # [ 47.782619] sudo[2806]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1548machine # [ 47.792306] sudo[2806]: pam_unix(sudo:session): session closed for user usertest1549machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1550machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1551machine # [ 47.819931] sudo[2812]: 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-owner1552machine # [ 47.829786] sudo[2812]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1553machine # [ 47.837889] sudo[2812]: pam_unix(sudo:session): session closed for user usertest1554machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1555machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1556machine # [ 47.865432] sudo[2818]: 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.service1557machine # [ 47.875260] sudo[2818]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1558machine # [ 47.892423] sudo[2818]: pam_unix(sudo:session): session closed for user usertest1559machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)1560machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&11561machine # [ 48.091845] nixos[2827]: switching to system configuration /nix/store/1dlg43ns9zjlp31wkn03cvlvarv87sbj-nixos-system-machine-test1562machine # [ 48.094710] systemd[1]: Stopped target Local File Systems.1563machine # [ 48.097490] systemd[1]: Stopped target Remote File Systems.1564machine # [ 48.382575] systemd[1]: Reload requested from client PID 2827 ('.switch-to-conf') (unit backdoor.service)...1565machine # [ 48.384719] systemd[1]: Reloading...1566machine # [ 48.699536] systemd[1]: Reloading finished in 313 ms.1567machine # [ 48.792395] systemd[534]: Reexecution requested from client PID 2895 ('switch-to-confi')...1568machine # [ 48.793779] systemd[534]: Reexecuting.1569machine # [ 48.851323] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1570machine # [ 48.972393] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1571machine # [ 48.978568] systemd[534]: Reload requested from client PID 2895 ('switch-to-confi')...1572machine # [ 48.980897] systemd[534]: Reloading...1573machine # [ 49.091245] systemd[534]: Reloading finished in 109 ms.1574machine # [ 49.108179] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1575machine # [ 49.118540] systemd[534]: Stopped Run user-specific NixOS activation.1576machine # [ 49.120997] systemd[534]: Stopping Run user-specific NixOS activation...1577machine # [ 49.123074] systemd[534]: Starting Run user-specific NixOS activation...1578machine # [ 49.183052] systemd[534]: Reload requested from client PID 2908 ('systemctl') (unit nixos-activation.service)...1579machine # [ 49.185923] systemd[534]: Reloading...1580machine # [ 49.302347] systemd[534]: Reloading finished in 115 ms.1581machine # [ 49.316183] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1582machine # [ 49.336246] systemd[534]: Stopped migrated.service.1583machine # [ 49.346671] systemd[534]: Finished Run user-specific NixOS activation.1584machine # [ 49.349359] systemd[534]: Reload requested from client PID 2895 ('switch-to-confi')...1585machine # [ 49.353138] systemd[534]: Reloading...1586machine # [ 49.462693] systemd[534]: Reloading finished in 110 ms.1587machine # [ 49.480190] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1588machine # [ 49.491600] systemd[534]: Starting migrated.service...1589machine # [ 49.518497] systemd[534]: Finished migrated.service.1590machine # [ 49.522973] systemd[1]: Stopped target Reactivate sysinit units.1591machine # [ 49.526029] systemd[1]: Stopping Reactivate sysinit units...1592machine # [ 49.527324] systemd[1]: Reached target Reactivate sysinit units.1593machine # [ 49.530700] systemd[1]: Reached target Remote File Systems.1594machine # [ 49.532701] systemd[1]: Starting linger-users.service...1595machine # [ 49.543923] systemd[1]: Reached target Local File Systems.1596machine # [ 49.545880] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1597machine # [ 49.552616] systemd[1]: Starting Create SUID/SGID Wrappers...1598machine # [ 49.553511] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1599machine # [ 49.554703] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671600machine # [ 49.571757] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1601machine # [ 49.575145] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1602machine # [ 49.577849] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1603machine # [ 49.579096] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1604machine # [ 49.584341] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1605machine # [ 49.620412] systemd[1]: linger-users.service: Deactivated successfully.1606machine # [ 49.623012] systemd[1]: Finished linger-users.service.1607machine # [ 49.758271] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1608machine # [ 49.761289] systemd[1]: Finished Create SUID/SGID Wrappers.1609machine # [ 50.019414] nixos[2827]: finished switching to system configuration /nix/store/1dlg43ns9zjlp31wkn03cvlvarv87sbj-nixos-system-machine-test1610machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 2.14 seconds)1611machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1612machine # [ 50.094472] sudo[2978]: 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.service1613machine # [ 50.117082] sudo[2978]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1614machine # [ 50.132731] sudo[2978]: pam_unix(sudo:session): session closed for user usertest1615machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)1616machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1617machine # [ 50.170513] sudo[2984]: 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.service1618machine # [ 50.183340] sudo[2984]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1619machine # [ 50.202520] sudo[2984]: pam_unix(sudo:session): session closed for user usertest1620machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)1621machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1622machine # [ 50.233380] sudo[2990]: 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-owner1623machine # [ 50.243129] sudo[2990]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1624machine # [ 50.251210] sudo[2990]: pam_unix(sudo:session): session closed for user usertest1625machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1626machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&11627machine # [ 50.445569] nixos[2999]: switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test1628machine # [ 50.447945] systemd[1]: Stopped target Local File Systems.1629machine # [ 50.450266] systemd[1]: Stopped target Remote File Systems.1630machine # [ 50.713205] systemd[1]: Reload requested from client PID 2999 ('.switch-to-conf') (unit backdoor.service)...1631machine # [ 50.715059] systemd[1]: Reloading...1632machine # [ 51.025216] systemd[1]: Reloading finished in 307 ms.1633machine # [ 51.112699] systemd[534]: Stopped migrated.service.1634machine # [ 51.116530] systemd[534]: Reexecution requested from client PID 3068 ('switch-to-confi')...1635machine # [ 51.117927] systemd[534]: Reexecuting.1636machine # [ 51.179685] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1637machine # [ 51.300131] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1638machine # [ 51.306568] systemd[534]: Reload requested from client PID 3068 ('switch-to-confi')...1639machine # [ 51.308830] systemd[534]: Reloading...1640machine # [ 51.421307] systemd[534]: Reloading finished in 111 ms.1641machine # [ 51.436200] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1642machine # [ 51.445507] systemd[534]: Stopped Run user-specific NixOS activation.1643machine # [ 51.447838] systemd[534]: Stopping Run user-specific NixOS activation...1644machine # [ 51.450037] systemd[534]: Starting Run user-specific NixOS activation...1645machine # [ 51.484400] systemd[534]: Finished Run user-specific NixOS activation.1646machine # [ 51.488082] systemd[1]: Stopped target Reactivate sysinit units.1647machine # [ 51.491359] systemd[1]: Stopping Reactivate sysinit units...1648machine # [ 51.496518] systemd[1]: Reached target Reactivate sysinit units.1649machine # [ 51.497511] systemd[1]: Reached target Local File Systems.1650machine # [ 51.498298] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1651machine # [ 51.503394] systemd[1]: Starting Create SUID/SGID Wrappers...1652machine # [ 51.506628] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1653machine # [ 51.507925] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671654machine # [ 51.520154] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1655machine # [ 51.521846] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1656machine # [ 51.523127] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1657machine # [ 51.524476] systemd[1]: Reached target Remote File Systems.1658machine # [ 51.525278] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1659machine # [ 51.526579] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1660machine # [ 51.689199] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1661machine # [ 51.691791] systemd[1]: Finished Create SUID/SGID Wrappers.1662machine # [ 51.696302] systemd[1]: Starting linger-users.service...1663machine # [ 51.737964] systemd[1]: linger-users.service: Deactivated successfully.1664machine # [ 51.741108] systemd[1]: Finished linger-users.service.1665machine # [ 51.999414] nixos[2999]: finished switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test1666machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.76 seconds)1667machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1668machine # [ 52.080704] sudo[3136]: 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.service1669machine # [ 52.104653] sudo[3136]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1670machine # [ 52.122987] sudo[3136]: pam_unix(sudo:session): session closed for user usertest1671machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds)1672machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1673machine # [ 52.165396] sudo[3142]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1674machine # [ 52.179371] sudo[3142]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1675machine # [ 52.189367] sudo[3142]: pam_unix(sudo:session): session closed for user usertest1676machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)1677machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service1678machine # [ 52.221166] sudo[3148]: 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.service1679machine # [ 52.232860] sudo[3148]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1680machine # [ 52.240794] sudo[3148]: pam_unix(sudo:session): session closed for user usertest1681machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1682machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1683machine # [ 52.269432] sudo[3154]: 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.service1684machine # [ 52.281229] sudo[3154]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1685machine # [ 52.289347] sudo[3154]: pam_unix(sudo:session): session closed for user usertest1686machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)1687machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1688machine # [ 52.317309] sudo[3160]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1689machine # [ 52.327003] sudo[3160]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1690machine # [ 52.335533] systemd[534]: Reload requested from client PID 3165 ('systemctl')...1691machine # [ 52.339643] systemd[534]: Reloading...1692machine # [ 52.453791] systemd[534]: Reloading finished in 115 ms.1693machine # [ 52.468172] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1694machine # [ 52.481917] sudo[3160]: pam_unix(sudo:session): session closed for user usertest1695machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)1696machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1697machine # [ 52.510667] sudo[3170]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1698machine # [ 52.520500] sudo[3170]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1699machine # [ 52.533778] systemd[534]: Starting migrated.service...1700machine # [ 52.559353] systemd[534]: Finished migrated.service.1701machine # [ 52.563258] sudo[3170]: pam_unix(sudo:session): session closed for user usertest1702machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)1703machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1704machine # [ 52.591888] sudo[3178]: 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.service1705machine # [ 52.601853] sudo[3178]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1706machine # [ 52.612282] sudo[3178]: pam_unix(sudo:session): session closed for user usertest1707machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1708machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1709machine # [ 52.640480] sudo[3184]: 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-owner1710machine # [ 52.650250] sudo[3184]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1711machine # [ 52.658406] sudo[3184]: pam_unix(sudo:session): session closed for user usertest1712machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1713machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1714machine # [ 52.686697] sudo[3190]: 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.service1715machine # [ 52.696831] sudo[3190]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1716machine # [ 52.713539] sudo[3190]: pam_unix(sudo:session): session closed for user usertest1717machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)1718machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&11719machine # [ 52.915321] nixos[3199]: switching to system configuration /nix/store/3gwk8v51wpgrmzjqvgdvyyffv0mpw6c2-nixos-system-machine-test1720machine # [ 52.918175] systemd[1]: Stopped target Remote File Systems.1721machine # [ 52.920564] systemd[1]: Stopped target Local File Systems.1722machine # [ 53.208802] systemd[1]: Reload requested from client PID 3199 ('.switch-to-conf') (unit backdoor.service)...1723machine # [ 53.210749] systemd[1]: Reloading...1724machine # [ 53.533214] systemd[1]: Reloading finished in 320 ms.1725machine # [ 53.625754] systemd[534]: Reexecution requested from client PID 3268 ('switch-to-confi')...1726machine # [ 53.629508] systemd[534]: Reexecuting.1727machine # [ 53.683512] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1728machine # [ 53.806033] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1729machine # [ 53.813041] systemd[534]: Reload requested from client PID 3268 ('switch-to-confi')...1730machine # [ 53.816818] systemd[534]: Reloading...1731machine # [ 53.930716] systemd[534]: Reloading finished in 114 ms.1732machine # [ 53.944199] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1733machine # [ 53.953442] systemd[534]: Stopped Run user-specific NixOS activation.1734machine # [ 53.955766] systemd[534]: Stopping Run user-specific NixOS activation...1735machine # [ 53.957956] systemd[534]: Starting Run user-specific NixOS activation...1736machine # [ 53.998867] systemd[534]: Reload requested from client PID 3280 ('systemctl') (unit nixos-activation.service)...1737machine # [ 54.003136] systemd[534]: Reloading...1738machine # [ 54.123145] systemd[534]: Reloading finished in 121 ms.1739machine # [ 54.140199] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1740machine # [ 54.159805] systemd[534]: Finished Run user-specific NixOS activation.1741machine # [ 54.162769] systemd[534]: Reload requested from client PID 3268 ('switch-to-confi')...1742machine # [ 54.166731] systemd[534]: Reloading...1743machine # [ 54.281556] systemd[534]: Reloading finished in 116 ms.1744machine # [ 54.296191] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1745machine # [ 54.306516] systemd[534]: Stopped migrated.service.1746machine # [ 54.308584] systemd[534]: Stopping migrated.service...1747machine # [ 54.310536] systemd[534]: Starting migrated.service...1748machine # [ 54.339612] systemd[534]: Finished migrated.service.1749machine # [ 54.346576] systemd[1]: Stopped target Reactivate sysinit units.1750machine # [ 54.349953] systemd[1]: Stopping Reactivate sysinit units...1751machine # [ 54.350879] systemd[1]: Reached target Reactivate sysinit units.1752machine # [ 54.353757] systemd[1]: Reached target Remote File Systems.1753machine # [ 54.358001] systemd[1]: Reached target Local File Systems.1754machine # [ 54.360631] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1755machine # [ 54.369189] systemd[1]: Starting Create SUID/SGID Wrappers...1756machine # [ 54.370210] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1757machine # [ 54.371454] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671758machine # [ 54.381219] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1759machine # [ 54.382938] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1760machine # [ 54.385427] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1761machine # [ 54.386645] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1762machine # [ 54.387922] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1763machine # [ 54.549918] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1764machine # [ 54.554788] systemd[1]: Finished Create SUID/SGID Wrappers.1765machine # [ 54.557130] systemd[1]: Starting linger-users.service...1766machine # [ 54.597524] systemd[1]: linger-users.service: Deactivated successfully.1767machine # [ 54.600658] systemd[1]: Finished linger-users.service.1768machine # [ 54.858459] nixos[3199]: finished switching to system configuration /nix/store/3gwk8v51wpgrmzjqvgdvyyffv0mpw6c2-nixos-system-machine-test1769machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 2.16 seconds)1770machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1771machine # [ 54.947924] sudo[3349]: 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.service1772machine # [ 54.970391] sudo[3349]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1773machine # [ 54.987081] sudo[3349]: pam_unix(sudo:session): session closed for user usertest1774machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.12 seconds)1775machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1776machine # [ 55.025348] sudo[3355]: 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.service1777machine # [ 55.039337] sudo[3355]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1778machine # [ 55.060271] sudo[3355]: pam_unix(sudo:session): session closed for user usertest1779machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)1780machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1781machine # [ 55.090966] sudo[3361]: 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-owner1782machine # [ 55.101237] sudo[3361]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1783machine # [ 55.110027] sudo[3361]: pam_unix(sudo:session): session closed for user usertest1784machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1785machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&11786machine # [ 55.313171] nixos[3370]: switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test1787machine # [ 55.315748] systemd[1]: Stopped target Local File Systems.1788machine # [ 55.318100] systemd[1]: Stopped target Remote File Systems.1789machine # [ 55.588581] systemd[1]: Reload requested from client PID 3370 ('.switch-to-conf') (unit backdoor.service)...1790machine # [ 55.590595] systemd[1]: Reloading...1791machine # [ 55.929466] systemd[1]: Reloading finished in 337 ms.1792machine # [ 56.024636] systemd[534]: Stopped migrated.service.1793machine # [ 56.028292] systemd[534]: Reexecution requested from client PID 3439 ('switch-to-confi')...1794machine # [ 56.029669] systemd[534]: Reexecuting.1795machine # [ 56.092361] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1796machine # [ 56.220943] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1797machine # [ 56.228279] systemd[534]: Reload requested from client PID 3439 ('switch-to-confi')...1798machine # [ 56.230627] systemd[534]: Reloading...1799machine # [ 56.353570] systemd[534]: Reloading finished in 121 ms.1800machine # [ 56.368174] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1801machine # [ 56.381882] systemd[534]: Stopped Run user-specific NixOS activation.1802machine # [ 56.384374] systemd[534]: Stopping Run user-specific NixOS activation...1803machine # [ 56.388202] systemd[534]: Starting Run user-specific NixOS activation...1804machine # [ 56.422424] systemd[534]: Finished Run user-specific NixOS activation.1805machine # [ 56.426984] systemd[1]: Stopped target Reactivate sysinit units.1806machine # [ 56.428807] systemd[1]: Stopping Reactivate sysinit units...1807machine # [ 56.429714] systemd[1]: Reached target Reactivate sysinit units.1808machine # [ 56.435093] systemd[1]: Reached target Local File Systems.1809machine # [ 56.436000] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1810machine # [ 56.448461] systemd[1]: Starting Create SUID/SGID Wrappers...1811machine # [ 56.449418] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1812machine # [ 56.450673] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671813machine # [ 56.454454] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1814machine # [ 56.458193] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1815machine # [ 56.459584] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1816machine # [ 56.472185] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1817machine # [ 56.473649] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1818machine # [ 56.475079] systemd[1]: Reached target Remote File Systems.1819machine # [ 56.649678] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1820machine # [ 56.652164] systemd[1]: Finished Create SUID/SGID Wrappers.1821machine # [ 56.657572] systemd[1]: Starting linger-users.service...1822machine # [ 56.701131] systemd[1]: linger-users.service: Deactivated successfully.1823machine # [ 56.703273] systemd[1]: Finished linger-users.service.1824machine # [ 56.962330] nixos[3370]: finished switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test1825machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.86 seconds)1826machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1827machine # [ 57.036798] sudo[3507]: 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.service1828machine # [ 57.057449] sudo[3507]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1829machine # [ 57.077137] sudo[3507]: pam_unix(sudo:session): session closed for user usertest1830machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds)1831machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1832machine # [ 57.116911] sudo[3513]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1833machine # [ 57.130242] sudo[3513]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1834machine # [ 57.139717] sudo[3513]: pam_unix(sudo:session): session closed for user usertest1835machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)1836machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service1837machine # [ 57.170277] sudo[3519]: 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.service1838machine # [ 57.181132] sudo[3519]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1839machine # [ 57.189338] sudo[3519]: pam_unix(sudo:session): session closed for user usertest1840machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1841machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1842machine # [ 57.215101] sudo[3525]: 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.service1843machine # [ 57.224655] sudo[3525]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1844machine # [ 57.231933] sudo[3525]: pam_unix(sudo:session): session closed for user usertest1845machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.04 seconds)1846machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1847machine # [ 57.257006] sudo[3531]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1848machine # [ 57.265918] sudo[3531]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1849machine # [ 57.273605] systemd[534]: Reload requested from client PID 3536 ('systemctl')...1850machine # [ 57.277309] systemd[534]: Reloading...1851machine # [ 57.385195] systemd[534]: Reloading finished in 108 ms.1852machine # [ 57.404163] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1853machine # [ 57.417034] sudo[3531]: pam_unix(sudo:session): session closed for user usertest1854machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)1855machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1856machine # [ 57.443804] sudo[3541]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1857machine # [ 57.453269] sudo[3541]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1858machine # [ 57.465672] systemd[534]: Starting migrated.service...1859machine # [ 57.490306] systemd[534]: Finished migrated.service.1860machine # [ 57.493987] sudo[3541]: pam_unix(sudo:session): session closed for user usertest1861machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)1862machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1863machine # [ 57.520757] sudo[3549]: 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.service1864machine # [ 57.530397] sudo[3549]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1865machine # [ 57.540093] sudo[3549]: pam_unix(sudo:session): session closed for user usertest1866machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1867machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1868machine # [ 57.566741] sudo[3555]: 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-owner1869machine # [ 57.576761] sudo[3555]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1870machine # [ 57.583661] sudo[3555]: pam_unix(sudo:session): session closed for user usertest1871machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.04 seconds)1872machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1873machine # [ 57.609672] sudo[3561]: 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.service1874machine # [ 57.619101] sudo[3561]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1875machine # [ 57.634025] sudo[3561]: pam_unix(sudo:session): session closed for user usertest1876machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)1877machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&11878machine # [ 57.822231] nixos[3570]: switching to system configuration /nix/store/qa6cy9d3a1f75my8dfbwgckwrynk084n-nixos-system-machine-test1879machine # [ 57.824962] systemd[1]: Stopped target Remote File Systems.1880machine # [ 57.827162] systemd[1]: Stopped target Local File Systems.1881machine # [ 58.094232] systemd[1]: Reload requested from client PID 3570 ('.switch-to-conf') (unit backdoor.service)...1882machine # [ 58.096247] systemd[1]: Reloading...1883machine # [ 58.401856] systemd[1]: Reloading finished in 304 ms.1884machine # [ 58.491460] systemd[534]: Reexecution requested from client PID 3638 ('switch-to-confi')...1885machine # [ 58.493655] systemd[534]: Reexecuting.1886machine # [ 58.538868] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1887machine # [ 58.651994] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1888machine # [ 58.659191] systemd[534]: Reload requested from client PID 3638 ('switch-to-confi')...1889machine # [ 58.662592] systemd[534]: Reloading...1890machine # [ 58.770969] systemd[534]: Reloading finished in 109 ms.1891machine # [ 58.788155] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1892machine # [ 58.796728] systemd[534]: Stopped Run user-specific NixOS activation.1893machine # [ 58.798943] systemd[534]: Stopping Run user-specific NixOS activation...1894machine # [ 58.800965] systemd[534]: Starting Run user-specific NixOS activation...1895machine # [ 58.835640] systemd[534]: Reload requested from client PID 3651 ('systemctl') (unit nixos-activation.service)...1896machine # [ 58.838467] systemd[534]: Reloading...1897machine # [ 58.953285] systemd[534]: Reloading finished in 113 ms.1898machine # [ 58.968223] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1899machine # [ 58.987891] systemd[534]: Finished Run user-specific NixOS activation.1900machine # [ 58.991136] systemd[534]: Reload requested from client PID 3638 ('switch-to-confi')...1901machine # [ 58.995254] systemd[534]: Reloading...1902machine # [ 59.104797] systemd[534]: Reloading finished in 110 ms.1903machine # [ 59.120175] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1904machine # [ 59.130050] systemd[534]: Reloading migrated.service...1905machine # [ 59.156821] systemd[534]: Reloaded migrated.service.1906machine # [ 59.163139] systemd[1]: Stopped target Reactivate sysinit units.1907machine # [ 59.166012] systemd[1]: Stopping Reactivate sysinit units...1908machine # [ 59.166893] systemd[1]: Reached target Reactivate sysinit units.1909machine # [ 59.167694] systemd[1]: Reached target Local File Systems.1910machine # [ 59.172981] systemd[1]: Reached target Remote File Systems.1911machine # [ 59.173837] systemd[1]: Starting linger-users.service...1912machine # [ 59.180488] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1913machine # [ 59.181825] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1914machine # [ 59.187054] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1915machine # [ 59.196460] systemd[1]: Starting Create SUID/SGID Wrappers...1916machine # [ 59.197341] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1917machine # [ 59.198516] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671918machine # [ 59.211484] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1919machine # [ 59.217492] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1920machine # [ 59.223001] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1921machine # [ 59.257308] systemd[1]: linger-users.service: Deactivated successfully.1922machine # [ 59.260114] systemd[1]: Finished linger-users.service.1923machine # [ 59.386409] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1924machine # [ 59.389785] systemd[1]: Finished Create SUID/SGID Wrappers.1925machine # [ 59.646259] nixos[3570]: finished switching to system configuration /nix/store/qa6cy9d3a1f75my8dfbwgckwrynk084n-nixos-system-machine-test1926machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 2.02 seconds)1927machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1928machine # [ 59.714799] sudo[3720]: 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.service1929machine # [ 59.731131] sudo[3720]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1930machine # [ 59.744818] sudo[3720]: pam_unix(sudo:session): session closed for user usertest1931machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.09 seconds)1932machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1933machine # [ 59.776817] sudo[3726]: 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-owner1934machine # [ 59.789001] sudo[3726]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1935machine # [ 59.797503] sudo[3726]: pam_unix(sudo:session): session closed for user usertest1936machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1937machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&11938machine # [ 59.991186] nixos[3735]: switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test1939machine # [ 59.994709] systemd[1]: Stopped target Remote File Systems.1940machine # [ 59.995526] systemd[1]: Stopped target Local File Systems.1941machine # [ 60.241490] systemd[1]: Reload requested from client PID 3735 ('.switch-to-conf') (unit backdoor.service)...1942machine # [ 60.243309] systemd[1]: Reloading...1943machine # [ 60.544165] systemd[1]: Reloading finished in 299 ms.1944machine # [ 60.630944] systemd[534]: Stopped migrated.service.1945machine # [ 60.634763] systemd[534]: Reexecution requested from client PID 3804 ('switch-to-confi')...1946machine # [ 60.636823] systemd[534]: Reexecuting.1947machine # [ 60.695407] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1948machine # [ 60.814091] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1949machine # [ 60.820371] systemd[534]: Reload requested from client PID 3804 ('switch-to-confi')...1950machine # [ 60.823729] systemd[534]: Reloading...1951machine # [ 60.929997] systemd[534]: Reloading finished in 107 ms.1952machine # [ 60.944167] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1953machine # [ 60.953847] systemd[534]: Stopped Run user-specific NixOS activation.1954machine # [ 60.954849] systemd[534]: Stopping Run user-specific NixOS activation...1955machine # [ 60.957365] systemd[534]: Starting Run user-specific NixOS activation...1956machine # [ 60.986397] systemd[534]: Finished Run user-specific NixOS activation.1957machine # [ 60.989969] systemd[1]: Stopped target Reactivate sysinit units.1958machine # [ 60.991698] systemd[1]: Stopping Reactivate sysinit units...1959machine # [ 60.995622] systemd[1]: Reached target Reactivate sysinit units.1960machine # [ 60.996823] systemd[1]: Reached target Remote File Systems.1961machine # [ 60.998625] systemd[1]: Starting linger-users.service...1962machine # [ 61.008485] systemd[1]: Reached target Local File Systems.1963machine # [ 61.009372] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1964machine # [ 61.019376] systemd[1]: Starting Create SUID/SGID Wrappers...1965machine # [ 61.023776] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1966machine # [ 61.028159] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671967machine # [ 61.030396] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1968machine # [ 61.040120] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1969machine # [ 61.041509] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1970machine # [ 61.047645] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1971machine # [ 61.051055] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1972machine # [ 61.085769] systemd[1]: linger-users.service: Deactivated successfully.1973machine # [ 61.088366] systemd[1]: Finished linger-users.service.1974machine # [ 61.216514] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1975machine # [ 61.218935] systemd[1]: Finished Create SUID/SGID Wrappers.1976machine # [ 61.477887] nixos[3735]: finished switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test1977machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.69 seconds)1978machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1979machine # [ 61.554834] sudo[3872]: 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.service1980machine # [ 61.577103] sudo[3872]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1981machine # [ 61.594241] sudo[3872]: pam_unix(sudo:session): session closed for user usertest1982machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds)1983machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1984machine # [ 61.632294] sudo[3878]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1985machine # [ 61.644683] sudo[3878]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1986machine # [ 61.654503] sudo[3878]: pam_unix(sudo:session): session closed for user usertest1987machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)1988machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service1989machine # [ 61.686743] sudo[3884]: 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.service1990machine # [ 61.697917] sudo[3884]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1991machine # [ 61.706122] sudo[3884]: pam_unix(sudo:session): session closed for user usertest1992machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1993machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1994machine # [ 61.733051] sudo[3890]: 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.service1995machine # [ 61.742696] sudo[3890]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1996machine # [ 61.750569] sudo[3890]: pam_unix(sudo:session): session closed for user usertest1997machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.04 seconds)1998machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1999machine # [ 61.777266] sudo[3896]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2000machine # [ 61.786587] sudo[3896]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2001machine # [ 61.794818] systemd[534]: Reload requested from client PID 3901 ('systemctl')...2002machine # [ 61.798771] systemd[534]: Reloading...2003machine # [ 61.909809] systemd[534]: Reloading finished in 112 ms.2004machine # [ 61.925599] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2005machine # [ 61.933706] sudo[3896]: pam_unix(sudo:session): session closed for user usertest2006machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.18 seconds)2007machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2008machine # [ 61.961315] sudo[3906]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2009machine # [ 61.971068] sudo[3906]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2010machine # [ 61.983953] systemd[534]: Starting migrated.service...2011machine # [ 62.010708] systemd[534]: Finished migrated.service.2012machine # [ 62.014640] sudo[3906]: pam_unix(sudo:session): session closed for user usertest2013machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)2014machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2015machine # [ 62.042158] sudo[3914]: 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.service2016machine # [ 62.051624] sudo[3914]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2017machine # [ 62.061521] sudo[3914]: pam_unix(sudo:session): session closed for user usertest2018machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)2019machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2020machine # [ 62.088207] sudo[3920]: 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-owner2021machine # [ 62.097406] sudo[3920]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2022machine # [ 62.104843] sudo[3920]: pam_unix(sudo:session): session closed for user usertest2023machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.04 seconds)2024machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2025machine # [ 62.130606] sudo[3926]: 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.service2026machine # [ 62.140672] sudo[3926]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2027machine # [ 62.155669] sudo[3926]: pam_unix(sudo:session): session closed for user usertest2028machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)2029machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&12030machine # [ 62.347306] nixos[3935]: switching to system configuration /nix/store/ak9v5fxw7kjqaby9ks1w7nawv9y2pc4s-nixos-system-machine-test2031machine # [ 62.350083] systemd[1]: Stopped target Remote File Systems.2032machine # [ 62.352518] systemd[1]: Stopped target Local File Systems.2033machine # [ 62.624423] systemd[1]: Reload requested from client PID 3935 ('.switch-to-conf') (unit backdoor.service)...2034machine # [ 62.626019] systemd[1]: Reloading...2035machine # [ 62.928626] systemd[1]: Reloading finished in 301 ms.2036machine # [ 63.019500] systemd[534]: Reexecution requested from client PID 4003 ('switch-to-confi')...2037machine # [ 63.021739] systemd[534]: Reexecuting.2038machine # [ 63.075073] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2039machine # [ 63.191600] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2040machine # [ 63.198190] systemd[534]: Reload requested from client PID 4003 ('switch-to-confi')...2041machine # [ 63.201668] systemd[534]: Reloading...2042machine # [ 63.308255] systemd[534]: Reloading finished in 107 ms.2043machine # [ 63.324162] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2044machine # [ 63.332719] systemd[534]: Stopped Run user-specific NixOS activation.2045machine # [ 63.334970] systemd[534]: Stopping Run user-specific NixOS activation...2046machine # [ 63.337063] systemd[534]: Starting Run user-specific NixOS activation...2047machine # [ 63.371436] systemd[534]: Reload requested from client PID 4016 ('systemctl') (unit nixos-activation.service)...2048machine # [ 63.375575] systemd[534]: Reloading...2049machine # [ 63.488399] systemd[534]: Reloading finished in 113 ms.2050machine # [ 63.505979] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2051machine # [ 63.521223] systemd[534]: Finished Run user-specific NixOS activation.2052machine # [ 63.523709] systemd[534]: Reload requested from client PID 4003 ('switch-to-confi')...2053machine # [ 63.527520] systemd[534]: Reloading...2054machine # [ 63.637231] systemd[534]: Reloading finished in 110 ms.2055machine # [ 63.652154] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2056machine # [ 63.664712] systemd[1]: Stopped target Reactivate sysinit units.2057machine # [ 63.666410] systemd[1]: Stopping Reactivate sysinit units...2058machine # [ 63.667239] systemd[1]: Reached target Reactivate sysinit units.2059machine # [ 63.671309] systemd[1]: Reached target Remote File Systems.2060machine # [ 63.673445] systemd[1]: Starting linger-users.service...2061machine # [ 63.678129] systemd[1]: Reached target Local File Systems.2062machine # [ 63.678990] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2063machine # [ 63.682887] systemd[1]: Starting Create SUID/SGID Wrappers...2064machine # [ 63.683719] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2065machine # [ 63.693098] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672066machine # [ 63.695358] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2067machine # [ 63.703951] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2068machine # [ 63.705582] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2069machine # [ 63.706767] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2070machine # [ 63.707991] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2071machine # [ 63.759597] systemd[1]: linger-users.service: Deactivated successfully.2072machine # [ 63.762976] systemd[1]: Finished linger-users.service.2073machine # [ 63.893436] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2074machine # [ 63.897004] systemd[1]: Finished Create SUID/SGID Wrappers.2075machine # [ 64.155201] nixos[3935]: finished switching to system configuration /nix/store/ak9v5fxw7kjqaby9ks1w7nawv9y2pc4s-nixos-system-machine-test2076machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 2.01 seconds)2077machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2078machine # [ 64.226577] sudo[4083]: 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.service2079machine # [ 64.245694] sudo[4083]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2080machine # [ 64.260446] sudo[4083]: pam_unix(sudo:session): session closed for user usertest2081machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)2082machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2083machine # [ 64.295195] sudo[4089]: 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-owner2084machine # [ 64.307882] sudo[4089]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2085machine # [ 64.317044] sudo[4089]: pam_unix(sudo:session): session closed for user usertest2086machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)2087machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&12088machine # [ 64.512172] nixos[4098]: switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test2089machine # [ 64.514603] systemd[1]: Stopped target Remote File Systems.2090machine # [ 64.517119] systemd[1]: Stopped target Local File Systems.2091machine # [ 64.776422] systemd[1]: Reload requested from client PID 4098 ('.switch-to-conf') (unit backdoor.service)...2092machine # [ 64.778290] systemd[1]: Reloading...2093machine # [ 65.087236] systemd[1]: Reloading finished in 307 ms.2094machine # [ 65.175423] systemd[534]: Stopped migrated.service.2095machine # [ 65.179267] systemd[534]: Reexecution requested from client PID 4167 ('switch-to-confi')...2096machine # [ 65.181325] systemd[534]: Reexecuting.2097machine # [ 65.239573] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2098machine # [ 65.356454] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2099machine # [ 65.363143] systemd[534]: Reload requested from client PID 4167 ('switch-to-confi')...2100machine # [ 65.365535] systemd[534]: Reloading...2101machine # [ 65.476207] systemd[534]: Reloading finished in 109 ms.2102machine # [ 65.488152] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2103machine # [ 65.498450] systemd[534]: Stopped Run user-specific NixOS activation.2104machine # [ 65.499452] systemd[534]: Stopping Run user-specific NixOS activation...2105machine # [ 65.502232] systemd[534]: Starting Run user-specific NixOS activation...2106machine # [ 65.531096] systemd[534]: Finished Run user-specific NixOS activation.2107machine # [ 65.534796] systemd[1]: Stopped target Reactivate sysinit units.2108machine # [ 65.537152] systemd[1]: Stopping Reactivate sysinit units...2109machine # [ 65.538020] systemd[1]: Reached target Reactivate sysinit units.2110machine # [ 65.541987] systemd[1]: Reached target Remote File Systems.2111machine # [ 65.544228] systemd[1]: Starting linger-users.service...2112machine # [ 65.555031] systemd[1]: Reached target Local File Systems.2113machine # [ 65.557538] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2114machine # [ 65.564676] systemd[1]: Starting Create SUID/SGID Wrappers...2115machine # [ 65.565555] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2116machine # [ 65.566765] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672117machine # [ 65.580163] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2118machine # [ 65.582460] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2119machine # [ 65.584217] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2120machine # [ 65.587442] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2121machine # [ 65.590612] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2122machine # [ 65.631126] systemd[1]: linger-users.service: Deactivated successfully.2123machine # [ 65.635861] systemd[1]: Finished linger-users.service.2124machine # [ 65.768578] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2125machine # [ 65.771621] systemd[1]: Finished Create SUID/SGID Wrappers.2126machine # [ 66.027921] nixos[4098]: finished switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test2127machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.72 seconds)2128machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2129machine # [ 66.099179] sudo[4235]: 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.service2130machine # [ 66.117318] sudo[4235]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2131machine # [ 66.132999] sudo[4235]: pam_unix(sudo:session): session closed for user usertest2132machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.09 seconds)2133machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user2134machine # [ 66.166720] sudo[4241]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user2135machine # [ 66.178408] sudo[4241]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2136machine # [ 66.187729] sudo[4241]: pam_unix(sudo:session): session closed for user usertest2137machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.05 seconds)2138machine: must succeed: sudo -u usertest cp /nix/store/kbn5jd7gqb07qksa4hgqhsx409fjnskk-migrated.service ~usertest/.local/share/systemd/user/migrated.service2139machine # [ 66.217603] sudo[4247]: 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.service2140machine # [ 66.228955] sudo[4247]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2141machine # [ 66.236362] sudo[4247]: pam_unix(sudo:session): session closed for user usertest2142machine: (finished: must succeed: sudo -u usertest cp /nix/store/kbn5jd7gqb07qksa4hgqhsx409fjnskk-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.05 seconds)2143machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2144machine # [ 66.262305] sudo[4253]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2145machine # [ 66.271369] sudo[4253]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2146machine # [ 66.279386] systemd[534]: Reload requested from client PID 4258 ('systemctl')...2147machine # [ 66.283255] systemd[534]: Reloading...2148machine # [ 66.393906] systemd[534]: Reloading finished in 111 ms.2149machine # [ 66.408145] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2150machine # [ 66.419229] sudo[4253]: pam_unix(sudo:session): session closed for user usertest2151machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.18 seconds)2152machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2153machine # [ 66.446775] sudo[4263]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2154machine # [ 66.456662] sudo[4263]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2155machine # [ 66.471820] systemd[534]: Starting migrated.service...2156machine # [ 66.498454] systemd[534]: Finished migrated.service.2157machine # [ 66.502277] sudo[4263]: pam_unix(sudo:session): session closed for user usertest2158machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)2159machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2160machine # [ 66.529018] sudo[4271]: 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.service2161machine # [ 66.538317] sudo[4271]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2162machine # [ 66.547686] sudo[4271]: pam_unix(sudo:session): session closed for user usertest2163machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)2164machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2165machine # [ 66.573840] sudo[4277]: 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-owner2166machine # [ 66.582905] sudo[4277]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2167machine # [ 66.590365] sudo[4277]: pam_unix(sudo:session): session closed for user usertest2168machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.04 seconds)2169machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2170machine # [ 66.615887] sudo[4283]: 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.service2171machine # [ 66.625562] sudo[4283]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2172machine # [ 66.647567] sudo[4283]: pam_unix(sudo:session): session closed for user usertest2173machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)2174machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12175machine # [ 66.846305] nixos[4292]: switching to system configuration /nix/store/4cqfm7hcai8v7x9r42nfq25h1ykjsrns-nixos-system-machine-test2176machine # [ 66.849181] systemd[1]: Stopped target Remote File Systems.2177machine # [ 66.851522] systemd[1]: Stopped target Local File Systems.2178machine # [ 67.126080] systemd[1]: Reload requested from client PID 4292 ('.switch-to-conf') (unit backdoor.service)...2179machine # [ 67.127977] systemd[1]: Reloading...2180machine # [ 67.436905] systemd[1]: Reloading finished in 307 ms.2181machine # [ 67.527514] systemd[534]: Reexecution requested from client PID 4360 ('switch-to-confi')...2182machine # [ 67.529737] systemd[534]: Reexecuting.2183machine # [ 67.578844] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2184machine # [ 67.694041] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2185machine # [ 67.700996] systemd[534]: Reload requested from client PID 4360 ('switch-to-confi')...2186machine # [ 67.702342] systemd[534]: Reloading...2187machine # [ 67.812161] systemd[534]: Reloading finished in 108 ms.2188machine # [ 67.828160] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2189machine # [ 67.837156] systemd[534]: Stopped Run user-specific NixOS activation.2190machine # [ 67.839442] systemd[534]: Stopping Run user-specific NixOS activation...2191machine # [ 67.841611] systemd[534]: Starting Run user-specific NixOS activation...2192machine # [ 67.867366] systemd[534]: Finished Run user-specific NixOS activation.2193machine # [ 67.869512] systemd[534]: Reload requested from client PID 4360 ('switch-to-confi')...2194machine # [ 67.870735] systemd[534]: Reloading...2195machine # [ 67.983041] systemd[534]: Reloading finished in 110 ms.2196machine # [ 68.000160] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2197machine # [ 68.008372] systemd[534]: Stopped migrated.service.2198machine # [ 68.010400] systemd[534]: Stopping migrated.service...2199machine # [ 68.012273] systemd[534]: Starting migrated.service...2200machine # [ 68.038854] systemd[534]: Finished migrated.service.2201machine # [ 68.044921] systemd[1]: Stopped target Reactivate sysinit units.2202machine # [ 68.046917] systemd[1]: Stopping Reactivate sysinit units...2203machine # [ 68.047749] systemd[1]: Reached target Reactivate sysinit units.2204machine # [ 68.052507] systemd[1]: Reached target Remote File Systems.2205machine # [ 68.054882] systemd[1]: Starting linger-users.service...2206machine # [ 68.058481] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2207machine # [ 68.059786] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2208machine # [ 68.070226] systemd[1]: Reached target Local File Systems.2209machine # [ 68.071698] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2210machine # [ 68.080662] systemd[1]: Starting Create SUID/SGID Wrappers...2211machine # [ 68.081589] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2212machine # [ 68.082799] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672213machine # [ 68.096736] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2214machine # [ 68.100740] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2215machine # [ 68.102105] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2216machine # [ 68.143466] systemd[1]: linger-users.service: Deactivated successfully.2217machine # [ 68.147547] systemd[1]: Finished linger-users.service.2218machine # [ 68.291824] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2219machine # [ 68.295986] systemd[1]: Finished Create SUID/SGID Wrappers.2220machine # [ 68.552510] nixos[4292]: finished switching to system configuration /nix/store/4cqfm7hcai8v7x9r42nfq25h1ykjsrns-nixos-system-machine-test2221machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.92 seconds)2222machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2223machine # [ 68.623744] sudo[4435]: 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.service2224machine # [ 68.641667] sudo[4435]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2225machine # [ 68.656099] sudo[4435]: pam_unix(sudo:session): session closed for user usertest2226machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.09 seconds)2227machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2228machine # [ 68.690252] sudo[4441]: 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.service2229machine # [ 68.703074] sudo[4441]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2230machine # [ 68.723630] sudo[4441]: pam_unix(sudo:session): session closed for user usertest2231machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)2232machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2233machine # [ 68.754629] sudo[4447]: 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-owner2234machine # [ 68.764958] sudo[4447]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2235machine # [ 68.772930] sudo[4447]: pam_unix(sudo:session): session closed for user usertest2236machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2237machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12238machine # [ 68.962780] nixos[4456]: switching to system configuration /nix/store/4cqfm7hcai8v7x9r42nfq25h1ykjsrns-nixos-system-machine-test2239machine # [ 68.965248] systemd[1]: Stopped target Remote File Systems.2240machine # [ 68.967097] systemd[1]: Stopped target Local File Systems.2241machine # [ 69.222566] systemd[1]: Reload requested from client PID 4456 ('.switch-to-conf') (unit backdoor.service)...2242machine # [ 69.224710] systemd[1]: Reloading...2243machine # [ 69.534083] systemd[1]: Reloading finished in 308 ms.2244machine # [ 69.623783] systemd[534]: Reexecution requested from client PID 4525 ('switch-to-confi')...2245machine # [ 69.625973] systemd[534]: Reexecuting.2246machine # [ 69.683492] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2247machine # [ 69.801544] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2248machine # [ 69.808437] systemd[534]: Reload requested from client PID 4525 ('switch-to-confi')...2249machine # [ 69.809792] systemd[534]: Reloading...2250machine # [ 69.920255] systemd[534]: Reloading finished in 109 ms.2251machine # [ 69.936167] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2252machine # [ 69.945039] systemd[534]: Stopped Run user-specific NixOS activation.2253machine # [ 69.947343] systemd[534]: Stopping Run user-specific NixOS activation...2254machine # [ 69.949431] systemd[534]: Starting Run user-specific NixOS activation...2255machine # [ 69.979723] systemd[534]: Finished Run user-specific NixOS activation.2256machine # [ 69.983396] systemd[1]: Stopped target Reactivate sysinit units.2257machine # [ 69.984934] systemd[1]: Stopping Reactivate sysinit units...2258machine # [ 69.985783] systemd[1]: Reached target Reactivate sysinit units.2259machine # [ 69.989202] systemd[1]: Reached target Remote File Systems.2260machine # [ 69.994492] systemd[1]: Reached target Local File Systems.2261machine # [ 69.995420] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2262machine # [ 70.006737] systemd[1]: Starting Create SUID/SGID Wrappers...2263machine # [ 70.007663] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2264machine # [ 70.014888] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672265machine # [ 70.017513] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2266machine # [ 70.019082] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2267machine # [ 70.020441] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2268machine # [ 70.021649] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2269machine # [ 70.022904] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2270machine # [ 70.179520] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2271machine # [ 70.181491] systemd[1]: Finished Create SUID/SGID Wrappers.2272machine # [ 70.186343] systemd[1]: Starting linger-users.service...2273machine # [ 70.224917] systemd[1]: linger-users.service: Deactivated successfully.2274machine # [ 70.227707] systemd[1]: Finished linger-users.service.2275machine # [ 70.485595] nixos[4456]: finished switching to system configuration /nix/store/4cqfm7hcai8v7x9r42nfq25h1ykjsrns-nixos-system-machine-test2276machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.72 seconds)2277machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd2278machine # [ 70.556797] sudo[4593]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd2279machine # [ 70.574003] sudo[4593]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2280machine # [ 70.586061] sudo[4593]: pam_unix(sudo:session): session closed for user usertest2281machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.09 seconds)2282machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2283machine # [ 70.621221] sudo[4599]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2284machine # [ 70.633868] sudo[4599]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2285machine # [ 70.644274] systemd[534]: Reload requested from client PID 4604 ('systemctl')...2286machine # [ 70.648289] systemd[534]: Reloading...2287machine # [ 70.762442] systemd[534]: Reloading finished in 114 ms.2288machine # [ 70.776183] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2289machine # [ 70.787392] sudo[4599]: pam_unix(sudo:session): session closed for user usertest2290machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.20 seconds)2291machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&12292machine # [ 70.981249] nixos[4612]: switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test2293machine # [ 70.983725] systemd[1]: Stopped target Local File Systems.2294machine # [ 70.986006] systemd[1]: Stopped target Remote File Systems.2295machine # [ 71.240106] systemd[1]: Reload requested from client PID 4612 ('.switch-to-conf') (unit backdoor.service)...2296machine # [ 71.242021] systemd[1]: Reloading...2297machine # [ 71.549217] systemd[1]: Reloading finished in 305 ms.2298machine # [ 71.632955] systemd[534]: Stopped migrated.service.2299machine # [ 71.634654] systemd[534]: Reexecution requested from client PID 4681 ('switch-to-confi')...2300machine # [ 71.638285] systemd[534]: Reexecuting.2301machine # [ 71.696075] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2302machine # [ 71.814570] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2303machine # [ 71.821007] systemd[534]: Reload requested from client PID 4681 ('switch-to-confi')...2304machine # [ 71.824126] systemd[534]: Reloading...2305machine # [ 71.932588] systemd[534]: Reloading finished in 108 ms.2306machine # [ 71.952189] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2307machine # [ 71.962950] systemd[534]: Stopped Run user-specific NixOS activation.2308machine # [ 71.963950] systemd[534]: Stopping Run user-specific NixOS activation...2309machine # [ 71.966798] systemd[534]: Starting Run user-specific NixOS activation...2310machine # [ 71.995652] systemd[534]: Finished Run user-specific NixOS activation.2311machine # [ 71.999415] systemd[1]: Stopped target Reactivate sysinit units.2312machine # [ 72.002132] systemd[1]: Stopping Reactivate sysinit units...2313machine # [ 72.002983] systemd[1]: Reached target Reactivate sysinit units.2314machine # [ 72.007458] systemd[1]: Reached target Local File Systems.2315machine # [ 72.010936] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2316machine # [ 72.016544] systemd[1]: Starting Create SUID/SGID Wrappers...2317machine # [ 72.017445] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2318machine # [ 72.018619] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672319machine # [ 72.027149] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2320machine # [ 72.033980] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2321machine # [ 72.035774] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2322machine # [ 72.038470] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2323machine # [ 72.040334] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2324machine # [ 72.041600] systemd[1]: Reached target Remote File Systems.2325machine # [ 72.192386] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2326machine # [ 72.194187] systemd[1]: Finished Create SUID/SGID Wrappers.2327machine # [ 72.199076] systemd[1]: Starting linger-users.service...2328machine # [ 72.240411] systemd[1]: linger-users.service: Deactivated successfully.2329machine # [ 72.242047] systemd[1]: Finished linger-users.service.2330machine # [ 72.500813] nixos[4612]: finished switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test2331machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.72 seconds)2332machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2333machine # [ 72.571042] sudo[4749]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2334machine # [ 72.588897] sudo[4749]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2335machine # [ 72.599885] sudo[4749]: pam_unix(sudo:session): session closed for user usertest2336machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.09 seconds)2337machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service2338machine # [ 72.634588] sudo[4755]: 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.service2339machine # [ 72.647737] sudo[4755]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2340machine # [ 72.657331] sudo[4755]: pam_unix(sudo:session): session closed for user usertest2341machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.06 seconds)2342machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2343machine # [ 72.687147] sudo[4761]: 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.service2344machine # [ 72.697952] sudo[4761]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2345machine # [ 72.705897] sudo[4761]: pam_unix(sudo:session): session closed for user usertest2346machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)2347machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2348machine # [ 72.732389] sudo[4767]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2349machine # [ 72.741491] sudo[4767]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2350machine # [ 72.749567] systemd[534]: Reload requested from client PID 4772 ('systemctl')...2351machine # [ 72.753382] systemd[534]: Reloading...2352machine # [ 72.862084] systemd[534]: Reloading finished in 109 ms.2353machine # [ 72.876158] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2354machine # [ 72.888917] sudo[4767]: pam_unix(sudo:session): session closed for user usertest2355machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.18 seconds)2356machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2357machine # [ 72.917149] sudo[4777]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2358machine # [ 72.927026] sudo[4777]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2359machine # [ 72.941544] systemd[534]: Starting migrated.service...2360machine # [ 72.972879] systemd[534]: Finished migrated.service.2361machine # [ 72.977485] sudo[4777]: pam_unix(sudo:session): session closed for user usertest2362machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)2363machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2364machine # [ 73.010174] sudo[4785]: 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.service2365machine # [ 73.020616] sudo[4785]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2366machine # [ 73.031830] sudo[4785]: pam_unix(sudo:session): session closed for user usertest2367machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)2368machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2369machine # [ 73.063681] sudo[4791]: 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-owner2370machine # [ 73.073299] sudo[4791]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2371machine # [ 73.081067] sudo[4791]: pam_unix(sudo:session): session closed for user usertest2372machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2373machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2374machine # [ 73.107658] sudo[4797]: 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.service2375machine # [ 73.117389] sudo[4797]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2376machine # [ 73.133228] sudo[4797]: pam_unix(sudo:session): session closed for user usertest2377machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)2378machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12379machine # [ 73.323917] nixos[4806]: switching to system configuration /nix/store/4cqfm7hcai8v7x9r42nfq25h1ykjsrns-nixos-system-machine-test2380machine # [ 73.326754] systemd[1]: Stopped target Local File Systems.2381machine # [ 73.328936] systemd[1]: Stopped target Remote File Systems.2382machine # [ 73.579227] systemd[1]: Reload requested from client PID 4806 ('.switch-to-conf') (unit backdoor.service)...2383machine # [ 73.581272] systemd[1]: Reloading...2384machine # [ 73.883315] systemd[1]: Reloading finished in 300 ms.2385machine # [ 73.967152] systemd[534]: Reexecution requested from client PID 4875 ('switch-to-confi')...2386machine # [ 73.971157] systemd[534]: Reexecuting.2387machine # [ 74.023323] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2388machine # [ 74.135142] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2389machine # [ 74.141475] systemd[534]: Reload requested from client PID 4875 ('switch-to-confi')...2390machine # [ 74.144160] systemd[534]: Reloading...2391machine # [ 74.251368] systemd[534]: Reloading finished in 107 ms.2392machine # [ 74.268164] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2393machine # [ 74.278424] systemd[534]: Stopped Run user-specific NixOS activation.2394machine # [ 74.280767] systemd[534]: Stopping Run user-specific NixOS activation...2395machine # [ 74.282786] systemd[534]: Starting Run user-specific NixOS activation...2396machine # [ 74.315246] systemd[534]: Finished Run user-specific NixOS activation.2397machine # [ 74.316841] systemd[534]: Reload requested from client PID 4875 ('switch-to-confi')...2398machine # [ 74.320189] systemd[534]: Reloading...2399machine # [ 74.426136] systemd[534]: Reloading finished in 106 ms.2400machine # [ 74.440152] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2401machine # [ 74.455068] systemd[1]: Stopped target Reactivate sysinit units.2402machine # [ 74.458373] systemd[1]: Stopping Reactivate sysinit units...2403machine # [ 74.459208] systemd[1]: Reached target Reactivate sysinit units.2404machine # [ 74.461966] systemd[1]: Reached target Local File Systems.2405machine # [ 74.465559] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2406machine # [ 74.472133] systemd[1]: Starting Create SUID/SGID Wrappers...2407machine # [ 74.473109] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2408machine # [ 74.474308] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672409machine # [ 74.488440] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2410machine # [ 74.490108] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2411machine # [ 74.491357] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2412machine # [ 74.494235] systemd[1]: Reached target Remote File Systems.2413machine # [ 74.495112] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2414machine # [ 74.496465] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2415machine # [ 74.661869] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2416machine # [ 74.666678] systemd[1]: Finished Create SUID/SGID Wrappers.2417machine # [ 74.669159] systemd[1]: Starting linger-users.service...2418machine # [ 74.708666] systemd[1]: linger-users.service: Deactivated successfully.2419machine # [ 74.711468] systemd[1]: Finished linger-users.service.2420machine # [ 74.970947] nixos[4806]: finished switching to system configuration /nix/store/4cqfm7hcai8v7x9r42nfq25h1ykjsrns-nixos-system-machine-test2421machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.85 seconds)2422machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2423machine # [ 75.041471] sudo[4947]: 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.service2424machine # [ 75.065127] sudo[4947]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2425machine # [ 75.089934] sudo[4947]: pam_unix(sudo:session): session closed for user usertest2426machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.11 seconds)2427machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2428machine # [ 75.124564] sudo[4953]: 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-owner2429machine # [ 75.137567] sudo[4953]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2430machine # [ 75.146729] sudo[4953]: pam_unix(sudo:session): session closed for user usertest2431machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)2432machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd2433machine # [ 75.176899] sudo[4959]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd2434machine # [ 75.186592] sudo[4959]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2435machine # [ 75.194598] sudo[4959]: pam_unix(sudo:session): session closed for user usertest2436machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.05 seconds)2437machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2438machine # [ 75.220453] sudo[4965]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2439machine # [ 75.229577] sudo[4965]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2440machine # [ 75.237613] systemd[534]: Reload requested from client PID 4970 ('systemctl')...2441machine # [ 75.241441] systemd[534]: Reloading...2442machine # [ 75.347429] systemd[534]: Reloading finished in 107 ms.2443machine # [ 75.364181] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2444machine # [ 75.376951] sudo[4965]: pam_unix(sudo:session): session closed for user usertest2445machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.18 seconds)2446machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service2447machine # [ 75.404326] sudo[4975]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user stop migrated.service2448machine # [ 75.413468] sudo[4975]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2449machine # [ 75.424370] systemd[534]: Stopped migrated.service.2450machine # [ 75.428087] sudo[4975]: pam_unix(sudo:session): session closed for user usertest2451machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.05 seconds)2452machine: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&12453machine # [ 75.618877] nixos[4984]: switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test2454machine # [ 75.621535] systemd[1]: Stopped target Remote File Systems.2455machine # [ 75.623503] systemd[1]: Stopped target Local File Systems.2456machine # [ 75.875696] systemd[1]: Reload requested from client PID 4984 ('.switch-to-conf') (unit backdoor.service)...2457machine # [ 75.877947] systemd[1]: Reloading...2458machine # [ 76.182989] systemd[1]: Reloading finished in 303 ms.2459machine # [ 76.268064] systemd[534]: Reexecution requested from client PID 5053 ('switch-to-confi')...2460machine # [ 76.272151] systemd[534]: Reexecuting.2461machine # [ 76.318841] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2462machine # [ 76.428926] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2463machine # [ 76.435284] systemd[534]: Reload requested from client PID 5053 ('switch-to-confi')...2464machine # [ 76.438530] systemd[534]: Reloading...2465machine # [ 76.544442] systemd[534]: Reloading finished in 106 ms.2466machine # [ 76.560149] systemd[534]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2467machine # [ 76.568622] systemd[534]: Stopped Run user-specific NixOS activation.2468machine # [ 76.570811] systemd[534]: Stopping Run user-specific NixOS activation...2469machine # [ 76.572906] systemd[534]: Starting Run user-specific NixOS activation...2470machine # [ 76.602503] systemd[534]: Finished Run user-specific NixOS activation.2471machine # [ 76.606218] systemd[1]: Stopped target Reactivate sysinit units.2472machine # [ 76.607374] systemd[1]: Stopping Reactivate sysinit units...2473machine # [ 76.609264] systemd[1]: Reached target Reactivate sysinit units.2474machine # [ 76.611175] systemd[1]: Reached target Local File Systems.2475machine # [ 76.613823] systemd[1]: Reached target Remote File Systems.2476machine # [ 76.617566] systemd[1]: Starting linger-users.service...2477machine # [ 76.624683] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2478machine # [ 76.632448] systemd[1]: Starting Create SUID/SGID Wrappers...2479machine # [ 76.636839] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2480machine # [ 76.638040] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672481machine # [ 76.649676] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2482machine # [ 76.651267] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2483machine # [ 76.658613] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2484machine # [ 76.663504] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2485machine # [ 76.666920] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2486machine # [ 76.701712] systemd[1]: linger-users.service: Deactivated successfully.2487machine # [ 76.705446] systemd[1]: Finished linger-users.service.2488machine # [ 76.831259] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2489machine # [ 76.833206] systemd[1]: Finished Create SUID/SGID Wrappers.2490machine # [ 77.091753] nixos[4984]: finished switching to system configuration /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test2491machine: (finished: must succeed: /nix/store/rsbzd9av235p30kpbwdi17w9y2swqm1a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.67 seconds)2492(finished: subtest: user services, in 49.95 seconds)2493(finished: run the VM test script, in 78.11 seconds)2494test script finished in 78.19s2495cleanup2496kill QemuMachine (pid 45)2497machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2498machine # [2026-09-24T02:51:51Z INFO virtiofsd] Client disconnected, shutting down2499machine # [2026-09-24T02:51:51Z INFO virtiofsd] Client disconnected, shutting down2500machine # [2026-09-24T02:51:51Z INFO virtiofsd] Client disconnected, shutting down2501(finished: cleanup, in 0.17 seconds)2502additionally exposed symbols:2503 machine, other,2504 vlan1,2505 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