vm-test-run-switch-test-user
checks.aarch64-linux.switchTest-user
· build #51
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7machine: waiting for unit multi-user.target8machine: waiting for the VM to finish booting9machine: starting vm10machine # Disk image does not exist, creating the virtualisation disk image...11machine: QEMU running (pid 45)12machine # Formatting '/build/vm-state-machine/tmp.ikM3T8DwbC', 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: 6824feaf-3581-46cf-b05e-1214a945b08917machine # 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-10-02T03:44:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28machine # [2026-10-02T03:44:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29machine # [2026-10-02T03:44:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)30machine # [2026-10-02T03:44:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether31machine # [2026-10-02T03:44:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)32machine # [2026-10-02T03:44:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether33machine # [2026-10-02T03:44:24Z INFO virtiofsd] Waiting for vhost-user socket connection...34machine # [2026-10-02T03:44:24Z INFO virtiofsd] Waiting for vhost-user socket connection...35machine # [2026-10-02T03:44:24Z INFO virtiofsd] Waiting for vhost-user socket connection...36machine # [2026-10-02T03:44:24Z INFO virtiofsd] Client connected, servicing requests37machine # [2026-10-02T03:44:24Z INFO virtiofsd] Client connected, servicing requests38machine # [2026-10-02T03:44:24Z INFO virtiofsd] Client connected, servicing requests39machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]40machine # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 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 0x7fded700-0x7fdf0e7f]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/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/init regInfo=/nix/.ro-store/9rmv5cv7cfm51bklgiajnj7amv5q6lf3-closure-info/registration console=ttyAMA0,115200n8 console=tty074machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/9rmv5cv7cfm51bklgiajnj7amv5q6lf3-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 75026 entries in 294 pages88machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups89machine # [ 0.000000] rcu: Hierarchical RCU implementation.90machine # [ 0.000000] rcu: RCU event tracing is enabled.91machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.92machine # [ 0.000000] Trampoline variant of Tasks RCU enabled.93machine # [ 0.000000] Rude variant of Tasks RCU enabled.94machine # [ 0.000000] Tracing variant of Tasks RCU enabled.95machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.96machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=197machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.98machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.99machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.100machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0101machine # [ 0.000000] GICv3: 256 SPIs implemented102machine # [ 0.000000] GICv3: 0 Extended SPIs implemented103machine # [ 0.000000] Root IRQ handler: gic_handle_irq104machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI105machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0106machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000107machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]108machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)109machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)110machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000111machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000112machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.113machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns114machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).115machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns116machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns117machine # [ 0.000033] arm-pv: using stolen time PV118machine # [ 0.000407] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)119machine # [ 0.000624] Console: colour dummy device 80x25120machine # [ 0.000632] printk: legacy console [tty0] enabled121machine # [ 0.000826] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)122machine # [ 0.000834] pid_max: default: 32768 minimum: 301123machine # [ 0.000911] LSM: initializing lsm=capability,landlock,yama,bpf,ima124machine # [ 0.001045] landlock: Up and running.125machine # [ 0.001048] Yama: becoming mindful.126machine # [ 0.001522] LSM support for eBPF active127machine # [ 0.001661] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)128machine # [ 0.001683] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)129machine # [ 0.003822] rcu: Hierarchical SRCU implementation.130machine # [ 0.003829] rcu: Max phase no-delay instances is 1000.131machine # [ 0.005157] fsl-mc MSI: its@8080000 domain created132machine # [ 0.005286] EFI services will not be available.133machine # [ 0.005419] smp: Bringing up secondary CPUs ...134machine # [ 0.005430] smp: Brought up 1 node, 1 CPU135machine # [ 0.005433] SMP: Total of 1 processors activated.136machine # [ 0.005436] CPU: All CPU(s) started at EL1137machine # [ 0.005446] CPU features: detected: Branch Target Identification138machine # [ 0.005455] CPU features: detected: ARMv8.4 Translation Table Level139machine # [ 0.005458] CPU features: detected: Instruction cache invalidation not required for I/D coherence140machine # [ 0.005461] CPU features: detected: Data cache clean to the PoU not required for I/D coherence141machine # [ 0.005465] CPU features: detected: Common not Private translations142machine # [ 0.005469] CPU features: detected: CRC32 instructions143machine # [ 0.005472] CPU features: detected: Data cache clean to Point of Deep Persistence144machine # [ 0.005475] CPU features: detected: Data cache clean to Point of Persistence145machine # [ 0.005479] CPU features: detected: Data independent timing control (DIT)146machine # [ 0.005482] CPU features: detected: E0PD147machine # [ 0.005485] CPU features: detected: Enhanced Counter Virtualization148machine # [ 0.005488] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)149machine # [ 0.005491] CPU features: detected: Enhanced Virtualization Traps150machine # [ 0.005494] CPU features: detected: Fine Grained Traps151machine # [ 0.005497] CPU features: detected: Generic authentication (architected QARMA5 algorithm)152machine # [ 0.005502] CPU features: detected: RCpc load-acquire (LDAPR)153machine # [ 0.005505] CPU features: detected: LSE atomic instructions154machine # [ 0.005508] CPU features: detected: Privileged Access Never155machine # [ 0.005510] CPU features: detected: PMUv3156machine # [ 0.005513] CPU features: detected: RAS Extension Support157machine # [ 0.005516] CPU features: detected: RASv1p1 Extension Support158machine # [ 0.005518] CPU features: detected: Random Number Generator159machine # [ 0.005521] CPU features: detected: Speculation barrier (SB)160machine # [ 0.005524] CPU features: detected: Stage-2 Force Write-Back161machine # [ 0.005527] CPU features: detected: TLB range maintenance instructions162machine # [ 0.005532] CPU features: detected: Speculative Store Bypassing Safe (SSBS)163machine # [ 0.005571] alternatives: applying system-wide alternatives164machine # [ 0.008724] CPU features: detected: BBM Level 2 without TLB conflict abort165machine # [ 0.008931] Memory: 893636K/1048576K available (24512K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 113604K reserved, 32768K cma-reserved)166machine # [ 0.009338] devtmpfs: initialized167machine # [ 0.011199] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)168machine # [ 0.011224] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).169machine # [ 0.011443] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL170machine # [ 0.011447] 0 pages in range for non-PLT usage171machine # [ 0.011448] 508208 pages in range for PLT usage172machine # [ 0.011564] pinctrl core: initialized pinctrl subsystem173machine # [ 0.012486] DMI not present or invalid.174machine # [ 0.015802] NET: Registered PF_NETLINK/PF_ROUTE protocol family175machine # [ 0.018252] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations176machine # [ 0.018398] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations177machine # [ 0.018557] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations178machine # [ 0.018586] audit: initializing netlink subsys (disabled)179machine # [ 0.019219] thermal_sys: Registered thermal governor 'fair_share'180machine # [ 0.019221] thermal_sys: Registered thermal governor 'bang_bang'181machine # [ 0.019225] thermal_sys: Registered thermal governor 'step_wise'182machine # [ 0.019228] thermal_sys: Registered thermal governor 'user_space'183machine # [ 0.019231] thermal_sys: Registered thermal governor 'power_allocator'184machine # [ 0.019280] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1185machine # [ 0.019290] cpuidle: using governor ladder186machine # [ 0.019296] cpuidle: using governor menu187machine # [ 0.019494] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.188machine # [ 0.019511] ASID allocator initialised with 65536 entries189machine # [ 0.020917] Serial: AMBA PL011 UART driver190machine # [ 0.026735] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1191machine # [ 0.026880] printk: console [ttyAMA0] enabled192machine # [ 0.157481] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages193machine # [ 0.157490] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page194machine # [ 0.157493] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages195machine # [ 0.157496] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page196machine # [ 0.157499] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages197machine # [ 0.157502] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page198machine # [ 0.157504] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages199machine # [ 0.157507] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page200machine # [ 0.159089] fbcon: Taking over console201machine # [ 0.159100] ACPI: Interpreter disabled.202machine # [ 0.168943] iommu: Default domain type: Translated203machine # [ 0.168950] iommu: DMA domain TLB invalidation policy: strict mode204machine # [ 0.169375] SCSI subsystem initialized205machine # [ 0.171552] usbcore: registered new interface driver usbfs206machine # [ 0.171585] usbcore: registered new interface driver hub207machine # [ 0.171613] usbcore: registered new device driver usb208machine # [ 0.172015] pps_core: LinuxPPS API ver. 1 registered209machine # [ 0.172019] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>210machine # [ 0.172026] PTP clock support registered211machine # [ 0.172077] EDAC MC: Ver: 3.0.0212machine # [ 0.178467] scmi_core: SCMI protocol bus registered213machine # [ 0.178861] FPGA manager framework214machine # [ 0.182020] vgaarb: loaded215machine # [ 0.183742] clocksource: Switched to clocksource arch_sys_counter216machine # [ 0.189311] VFS: Disk quotas dquot_6.6.0217machine # [ 0.189342] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)218machine # [ 0.191888] netfs: FS-Cache loaded219machine # [ 0.192019] pnp: PnP ACPI: disabled220machine # [ 0.197953] NET: Registered PF_INET protocol family221machine # [ 0.198110] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)222machine # [ 0.229018] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)223machine # [ 0.229060] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)224machine # [ 0.229089] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)225machine # [ 0.229136] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)226machine # [ 0.229211] TCP: Hash tables configured (established 8192 bind 8192)227machine # [ 0.229332] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)228machine # [ 0.229365] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)229machine # [ 0.229386] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)230machine # [ 0.229468] NET: Registered PF_UNIX/PF_LOCAL protocol family231machine # [ 0.229486] NET: Registered PF_XDP protocol family232machine # [ 0.229503] PCI: CLS 0 bytes, default 64233machine # [ 0.229791] Trying to unpack rootfs image as initramfs...234machine # [ 0.242076] kvm [1]: HYP mode not available235machine # [ 0.351273] Initialise system trusted keyrings236machine # [ 0.351417] workingset: timestamp_bits=42 max_order=18 bucket_order=0237machine # [ 0.353332] squashfs: version 4.0 (2009/01/31) Phillip Lougher238machine # [ 0.353390] 9p: Installing v9fs 9p2000 file system support239machine # [ 0.381644] Key type asymmetric registered240machine # [ 0.381663] Asymmetric key parser 'x509' registered241machine # [ 0.381748] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)242machine # [ 0.381874] io scheduler mq-deadline registered243machine # [ 0.381879] io scheduler kyber registered244machine # [ 0.394414] pl061_gpio 9030000.pl061: PL061 GPIO chip registered245machine # [ 0.395036] ledtrig-cpu: registered to indicate activity on CPUs246machine # [ 0.395435] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:247machine # [ 0.395458] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000248machine # [ 0.395469] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000249machine # [ 0.395474] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000250machine # [ 0.395493] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits251machine # [ 0.395512] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]252machine # [ 0.395595] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00253machine # [ 0.395602] pci_bus 0000:00: root bus resource [bus 00-ff]254machine # [ 0.395607] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]255machine # [ 0.395610] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]256machine # [ 0.395613] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]257machine # [ 0.415794] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint258machine # [ 0.416356] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint259machine # [ 0.416553] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]260machine # [ 0.416566] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]261machine # [ 0.416593] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]262machine # [ 0.416605] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]263machine # [ 0.417109] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint264machine # [ 0.417307] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]265machine # [ 0.417319] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]266machine # [ 0.417346] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]267machine # [ 0.417842] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint268machine # [ 0.418029] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]269machine # [ 0.418041] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]270machine # [ 0.418066] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]271machine # [ 0.418534] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint272machine # [ 0.418728] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]273machine # [ 0.418739] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]274machine # [ 0.418765] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275machine # [ 0.418776] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]276machine # [ 0.419261] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint277machine # [ 0.419463] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]278machine # [ 0.419489] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279machine # [ 0.420099] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint280machine # [ 0.420293] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.420319] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.420734] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint283machine # [ 0.420922] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]284machine # [ 0.421186] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint285machine # [ 0.421376] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]286machine # [ 0.421403] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]287machine # [ 0.421878] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint288machine # [ 0.422076] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]289machine # [ 0.422102] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]290machine # [ 0.422582] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint291machine # [ 0.422774] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]292machine # [ 0.422802] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]293machine # [ 0.423299] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint294machine # [ 0.423595] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]295machine # [ 0.423606] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]296machine # [ 0.423632] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]297machine # [ 0.424153] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint298machine # [ 0.424341] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]299machine # [ 0.424352] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]300machine # [ 0.424378] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]301machine # [ 0.425004] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned302machine # [ 0.425010] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned303machine # [ 0.425014] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned304machine # [ 0.425057] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned305machine # [ 0.425101] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned306machine # [ 0.425144] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned307machine # [ 0.425187] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned308machine # [ 0.425231] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned309machine # [ 0.425275] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned310machine # [ 0.425319] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned311machine # [ 0.425362] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned312machine # [ 0.425405] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned313machine # [ 0.425480] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned314machine # [ 0.425524] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned315machine # [ 0.425542] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned316machine # [ 0.425559] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned317machine # [ 0.425577] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned318machine # [ 0.425594] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned319machine # [ 0.425611] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned320machine # [ 0.425629] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned321machine # [ 0.425647] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned322machine # [ 0.425665] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned323machine # [ 0.425686] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned324machine # [ 0.425704] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned325machine # [ 0.425722] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned326machine # [ 0.425739] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned327machine # [ 0.425756] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned328machine # [ 0.425773] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned329machine # [ 0.425790] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned330machine # [ 0.425807] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned331machine # [ 0.425824] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned332machine # [ 0.425846] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]333machine # [ 0.425850] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]334machine # [ 0.425853] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]335machine # [ 0.426709] pci 0000:00:07.0: enabling device (0000 -> 0002)336machine # [ 0.524388] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)337machine # [ 0.526516] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)338machine # [ 0.544452] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)339machine # [ 0.546613] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)340machine # [ 0.550469] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)341machine # [ 0.553303] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)342machine # [ 0.563968] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)343machine # [ 0.566079] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)344machine # [ 0.570185] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)345machine # [ 0.580354] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)346machine # [ 0.582805] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)347machine # [ 0.597189] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled348machine # [ 0.599150] msm_serial: driver initialized349machine # [ 0.599287] SuperH (H)SCI(F) driver initialized350machine # [ 0.599344] STM32 USART driver initialized351machine # [ 0.641865] loop: module loaded352machine # [ 0.642070] virtio_blk virtio2: 1/0/0 default/read/poll queues353machine # [ 0.643050] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)354machine # [ 0.648421] megasas: 07.734.00.00-rc1355machine # [ 0.649186] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]356machine # [ 0.660930] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000357machine # [ 0.660950] Intel/Sharp Extended Query Table at 0x0031358machine # [ 0.662673] Using buffer write method359machine # [ 0.662708] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]360machine # [ 0.668281] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000361machine # [ 0.668296] Intel/Sharp Extended Query Table at 0x0031362machine # [ 0.669685] Using buffer write method363machine # [ 0.669701] Concatenating MTD devices:364machine # [ 0.669704] (0): "0.flash"365machine # [ 0.669706] (1): "0.flash"366machine # [ 0.669708] into device "0.flash"367machine # [ 0.982790] Freeing initrd memory: 26424K368machine # [ 0.989434] tun: Universal TUN/TAP device driver, 1.6369machine # [ 0.993548] thunder_xcv, ver 1.0370machine # [ 0.993576] thunder_bgx, ver 1.0371machine # [ 0.993594] nicpf, ver 1.0372machine # [ 0.994164] e1000: Intel(R) PRO/1000 Network Driver373machine # [ 0.994168] e1000: Copyright (c) 1999-2006 Intel Corporation.374machine # [ 0.994194] e1000e: Intel(R) PRO/1000 Network Driver375machine # [ 0.994206] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.376machine # [ 0.994230] igb: Intel(R) Gigabit Ethernet Network Driver377machine # [ 0.994233] igb: Copyright (c) 2007-2014 Intel Corporation.378machine # [ 0.994253] igbvf: Intel(R) Gigabit Virtual Function Network Driver379machine # [ 0.994257] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.380machine # [ 0.994406] sky2: driver version 1.30381machine # [ 1.004436] ehci-pci 0000:00:07.0: EHCI Host Controller382machine # [ 1.004462] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1383machine # [ 1.004670] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000384machine # [ 1.004924] usbcore: registered new interface driver usb-storage385machine # [ 1.004973] usbcore: registered new interface driver usbserial_generic386machine # [ 1.004984] usbserial: USB Serial support registered for generic387machine # [ 1.005630] hv_vmbus: registering driver hyperv_keyboard388machine # [ 1.006482] rtc-pl031 9010000.pl031: registered as rtc0389machine # [ 1.006506] rtc-pl031 9010000.pl031: setting system clock to 2026-10-02T03:44:26 UTC (1790912666)390machine # [ 1.006818] i2c_dev: i2c /dev entries driver391machine # [ 1.010170] sdhci: Secure Digital Host Controller Interface driver392machine # [ 1.010177] sdhci: Copyright(c) Pierre Ossman393machine # [ 1.010463] Synopsys Designware Multimedia Card Interface Driver394machine # [ 1.010854] sdhci-pltfm: SDHCI platform and OF driver helper395machine # [ 1.020538] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00396machine # [ 1.020951] hub 1-0:1.0: USB hub found397machine # [ 1.020968] hub 1-0:1.0: 6 ports detected398machine # [ 1.026257] hid: raw HID events driver (C) Jiri Kosina399machine # [ 1.026502] usbcore: registered new interface driver usbhid400machine # [ 1.026506] usbhid: USB HID core driver401machine # [ 1.027784] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available402machine # [ 1.029417] drop_monitor: Initializing network drop monitor service403machine # [ 1.029614] NET: Registered PF_INET6 protocol family404machine # [ 1.034913] Segment Routing with IPv6405machine # [ 1.034927] In-situ OAM (IOAM) with IPv6406machine # [ 1.034955] NET: Registered PF_PACKET protocol family407machine # [ 1.035021] 9pnet: Installing 9P2000 support408machine # [ 1.035065] Key type dns_resolver registered409machine # [ 1.044386] registered taskstats version 1410machine # [ 1.044538] Loading compiled-in X.509 certificates411machine # [ 1.053296] Demotion targets for Node 0: null412machine # [ 1.053414] Key type .fscrypt registered413machine # [ 1.053418] Key type fscrypt-provisioning registered414machine # [ 1.053519] ima: No TPM chip found, activating TPM-bypass!415machine # [ 1.053536] ima: Allocated hash algorithm: sha1416machine # [ 1.053557] ima: No architecture policies found417machine # [ 1.054244] input: gpio-keys as /devices/platform/gpio-keys/input/input0418machine # [ 1.076819] clk: Disabling unused clocks419machine # [ 1.076835] PM: genpd: Disabling unused power domains420machine # [ 1.081116] Freeing unused kernel memory: 4736K421machine # [ 1.081270] Run /init as init process422machine # [ 1.098614] systemd[1]: Successfully made /usr/ read-only.423machine # [ 1.099072] systemd[1]: systemd 263-b43fed8 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)424machine # [ 1.099086] systemd[1]: Detected virtualization qemu.425machine # [ 1.099127] systemd[1]: Detected architecture arm64.426machine # [ 1.099132] systemd[1]: Running in initrd.427machine # [ 1.099431] systemd[1]: Initializing machine ID from random generator.428machine # [ 1.099526] systemd[1]: Hostname set to <machine>.429machine # [ 1.202335] systemd[1]: bpf-restrict-fs: LSM BPF program attached430machine # [ 1.261130] systemd[1]: Queued start job for default target Initrd Default Target.431machine # [ 1.267444] systemd[1]: Created slice Slice /system/modprobe.432machine # [ 1.267647] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.433machine # [ 1.267838] usb 1-1: new high-speed USB device number 2 using ehci-pci434machine # [ 1.270945] systemd[1]: Expecting device /dev/disk/by-label/nixos...435machine # [ 1.270982] systemd[1]: Reached target Path Units.436machine # [ 1.271003] systemd[1]: Reached target Slice Units.437machine # [ 1.271018] systemd[1]: Reached target Swaps.438machine # [ 1.271034] systemd[1]: Reached target Timer Units.439machine # [ 1.271223] systemd[1]: Listening on D-Bus System Message Bus Socket.440machine # [ 1.271354] systemd[1]: Listening on Journal Socket (/dev/log).441machine # [ 1.271481] systemd[1]: Listening on Journal Sockets.442machine # [ 1.271569] systemd[1]: Listening on udev Kernel Socket.443machine # [ 1.271584] systemd[1]: Reached target Socket Units.444machine # [ 1.280766] systemd[1]: Starting Create List of Static Device Nodes...445machine # [ 1.280840] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs446machine # [ 1.288002] systemd[1]: Mounting Kernel Configuration File System...447machine # [ 1.301444] systemd[1]: Starting Journal Service...448machine # [ 1.309613] systemd[1]: Starting Load Kernel Modules...449machine # [ 1.309750] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os450machine # [ 1.324082] systemd[1]: Starting Coldplug All udev Devices...451machine # [ 1.344114] systemd[1]: Finished Create List of Static Device Nodes.452machine # [ 1.357422] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...453machine # [ 1.358148] systemd[1]: Mounted Kernel Configuration File System.454machine # [ 1.371598] systemd-journald[71]: Collecting audit messages is disabled.455machine # [ 1.422838] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.456machine # [ 1.430005] systemd[1]: Starting Create Static Device Nodes in /dev...457machine # [ 1.444254] 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/input1458machine # [ 1.447246] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.459machine # [ 1.459974] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev460machine # [ 1.478713] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0461machine # [ 1.478968] [drm] features: -virgl +edid -resource_blob -host_visible462machine # [ 1.478971] [drm] features: -context_init463machine # [ 1.491833] [drm] number of scanouts: 1464machine # [ 1.491848] [drm] number of cap sets: 0465machine # [ 1.493745] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic466machine # [ 1.493757] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0467machine # [ 1.516577] systemd[1]: Finished Create Static Device Nodes in /dev.468machine # [ 1.516829] systemd[1]: Reached target Preparation for Local File Systems.469machine # [ 1.516854] systemd[1]: Reached target Local File Systems.470machine # [ 1.518467] systemd[1]: Starting Rule-based Manager for Device Events and Files...471machine # [ 1.523445] Console: switching to colour frame buffer device 160x50472machine # [ 1.530093] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device473machine # [ 1.532066] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0474machine # [ 1.548143] systemd[1]: Finished Load Kernel Modules.475machine # [ 1.559913] systemd[1]: Starting Apply Kernel Variables...476machine # [ 1.561131] systemd-modules-load[72]: Inserted module 'dm_mod'477machine # [ 1.582203] systemd[1]: Started Journal Service.478machine # [ 1.568354] systemd-modules-load[72]: Module 'virtio_balloon' is built in479machine # [ 1.580111] systemd-modules-load[72]: Module 'virtio_console' is built in480machine # [ 1.584415] systemd-modules-load[72]: Inserted module 'virtio_gpu'481machine # [ 1.588353] systemd-modules-load[72]: Module 'virtio_rng' is built in482machine # [ 1.593329] systemd[1]: Starting Create System Files and Directories...483machine # [ 1.622286] systemd[1]: Finished Apply Kernel Variables.484machine # [ 1.626031] systemd-udevd[78]: Using default interface naming scheme 'v261'.485machine # [ 1.651998] usb 1-2: new high-speed USB device number 3 using ehci-pci486machine # [ 1.643880] systemd[1]: Finished Create System Files and Directories.487machine # [ 1.666198] systemd[1]: Started Rule-based Manager for Device Events and Files.488machine # [ 1.776102] systemd[1]: Starting Virtual Console Setup...489machine # [ 1.815118] 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/input2490machine # [ 1.815381] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0491machine # [ 1.852559] systemd-vconsole-setup[102]: Configuration of first virtual console was skipped, ignoring remaining ones.492machine # [ 1.860190] systemd[1]: Finished Virtual Console Setup.493machine # [ 2.573815] systemd[1]: Finished Coldplug All udev Devices.494machine # [ 2.574834] systemd[1]: Reached target System Initialization.495machine # [ 2.575694] systemd[1]: Reached target Basic System.496machine # [ 2.757634] (udev-worker)[91]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.497machine # [ 2.761530] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.498machine # [ 2.833756] systemd[1]: Found device /dev/disk/by-label/nixos.499machine # [ 2.836261] systemd[1]: Reached target Initrd Root Device.500machine # [ 2.840413] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...501machine # [ 2.898927] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks502machine # [ 2.908087] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.503machine # [ 2.911144] systemd[1]: Mounting /sysroot...504machine # [ 2.971393] EXT4-fs (vda): mounted filesystem 6824feaf-3581-46cf-b05e-1214a945b089 r/w with ordered data mode. Quota mode: none.505machine # [ 2.960318] systemd[1]: Mounted /sysroot.506machine # [ 2.961466] systemd[1]: Reached target Initrd Root File System.507machine # [ 2.965363] systemd[1]: Starting Mountpoints Configured in the Real Root...508machine # [ 2.992496] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.509machine # [ 2.999633] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...510machine # [ 3.004209] systemd[1]: Reloading...511machine # [ 3.197170] systemd[1]: Reloading finished in 193 ms.512machine # [ 3.231816] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...513machine # [ 3.236258] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...514machine # [ 3.241396] systemd[1]: initrd-parse-etc.service: Deactivated successfully.515machine # [ 3.243243] systemd[1]: Finished Mountpoints Configured in the Real Root.516machine # [ 3.245514] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.517machine # [ 3.321378] systemd[1]: Mounting /sysroot/nix/.ro-store...518machine # [ 3.338881] systemd[1]: Mounting /sysroot/nix/.rw-store...519machine # [ 3.353100] systemd[1]: Mounting /sysroot/run...520machine # [ 3.379072] systemd[1]: Mounting /sysroot/tmp/shared...521machine # [ 3.389483] systemd[1]: Mounting /sysroot/tmp/xchg...522machine # [ 3.445899] fuse: init (API version 7.45)523machine # [ 3.459268] virtiofs virtio6: discovered new tag: nix-store524machine # [ 3.444078] systemd[1]: Mounted /sysroot/nix/.rw-store.525machine # [ 3.470541] virtiofs virtio6: virtio_fs_setup_dax: No cache capability526machine # [ 3.485504] virtiofs virtio7: discovered new tag: shared527machine # [ 3.486292] virtiofs virtio7: virtio_fs_setup_dax: No cache capability528machine # [ 3.490896] virtiofs virtio8: discovered new tag: xchg529machine # [ 3.491639] virtiofs virtio8: virtio_fs_setup_dax: No cache capability530machine # [ 3.490930] systemd[1]: Starting rw-sysroot-nix-store.service...531machine # [ 3.504138] systemd[1]: Mounted /sysroot/run.532machine # [ 3.509667] systemd[1]: Mounted /sysroot/nix/.ro-store.533machine # [ 3.512235] systemd[1]: Mounted /sysroot/tmp/shared.534machine # [ 3.521421] systemd[1]: Mounted /sysroot/tmp/xchg.535machine # [ 3.538790] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.536machine # [ 3.540522] systemd[1]: Finished rw-sysroot-nix-store.service.537machine # [ 4.177766] (udev-worker)[92]: mtd0ro: Failed to find and pin callout binary "/nix/store/mnkwyhxgd0iwrkbpkm3jyw0bls43s8n4-systemd-263-b43fed8/lib/udev/mtd_probe": No such file or directory538machine # [ 4.183170] (udev-worker)[92]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory539machine # [ 4.221192] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.540machine # [ 4.225432] systemd[1]: Stopped Virtual Console Setup.541machine # [ 4.228141] systemd[1]: Stopping Virtual Console Setup...542machine # [ 4.232149] systemd[1]: Starting Virtual Console Setup...543machine # [ 4.242530] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.544machine # [ 4.244163] systemd[1]: Stopped Virtual Console Setup.545machine # [ 4.247788] systemd[1]: Starting Virtual Console Setup...546machine # [ 4.278760] systemd-vconsole-setup[152]: Configuration of first virtual console was skipped, ignoring remaining ones.547machine # [ 4.282453] systemd[1]: Finished Virtual Console Setup.548machine # [ 4.320610] systemd[1]: Mounting /sysroot/nix/store...549machine # [ 4.360265] systemd[1]: Mounted /sysroot/nix/store.550machine # [ 4.363873] systemd[1]: Reached target Initrd File Systems.551machine # [ 4.369720] systemd[1]: Starting Find NixOS closure...552machine # [ 4.371356] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...553machine # [ 4.414904] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.554machine # [ 4.417378] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.555machine # [ 4.428333] systemd[1]: Finished Find NixOS closure.556machine # [ 4.431820] systemd[1]: Reached target Initrd Default Target.557machine # [ 4.436686] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...558machine # [ 4.463699] systemd[1]: Stopped target Initrd Default Target.559machine # [ 4.468331] systemd[1]: Stopped target Basic System.560machine # [ 4.469192] systemd[1]: Stopped target Initrd Root Device.561machine # [ 4.470058] systemd[1]: Stopped target Path Units.562machine # [ 4.470786] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.563machine # [ 4.471883] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.564machine # [ 4.476321] systemd[1]: Stopped target Slice Units.565machine # [ 4.480313] systemd[1]: Stopped target Socket Units.566machine # [ 4.481274] systemd[1]: Stopped target System Initialization.567machine # [ 4.482179] systemd[1]: Stopped target Swaps.568machine # [ 4.484000] systemd[1]: Stopped target Timer Units.569machine # [ 4.485734] systemd[1]: dbus.socket: Deactivated successfully.570machine # [ 4.487836] systemd[1]: Closed D-Bus System Message Bus Socket.571machine # [ 4.491953] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.572machine # [ 4.493363] systemd[1]: Stopped Find NixOS closure.573machine # [ 4.497727] systemd[1]: Starting rw-sysroot-nix-store.service...574machine # [ 4.507997] systemd[1]: systemd-sysctl.service: Deactivated successfully.575machine # [ 4.509184] systemd[1]: Stopped Apply Kernel Variables.576machine # [ 4.510104] systemd[1]: systemd-modules-load.service: Deactivated successfully.577machine # [ 4.511117] systemd[1]: Stopped Load Kernel Modules.578machine # [ 4.511847] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.579machine # [ 4.523671] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.580machine # [ 4.529281] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.581machine # [ 4.531470] systemd[1]: Stopped Create System Files and Directories.582machine # [ 4.534566] systemd[1]: Stopped target Local File Systems.583machine # [ 4.535402] systemd[1]: Stopped target Preparation for Local File Systems.584machine # [ 4.540661] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.585machine # [ 4.541749] systemd[1]: Stopped Coldplug All udev Devices.586machine # [ 4.542567] systemd[1]: Stopping Rule-based Manager for Device Events and Files...587machine # [ 4.543636] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.588machine # [ 4.547244] systemd[1]: Stopped Virtual Console Setup.589machine # [ 4.548327] systemd[1]: initrd-cleanup.service: Deactivated successfully.590machine # [ 4.549303] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.591machine # [ 4.550264] systemd[1]: systemd-udevd.service: Deactivated successfully.592machine # [ 4.551226] systemd[1]: Stopped Rule-based Manager for Device Events and Files.593machine # [ 4.552350] systemd[1]: systemd-udevd.service: Consumed 1.623s CPU time over 3.030s wall clock time, 22.8M memory peak.594machine # [ 4.553820] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.595machine # [ 4.554839] systemd[1]: Finished rw-sysroot-nix-store.service.596machine # [ 4.555684] systemd[1]: Starting Cleanup udev Database...597machine # [ 4.561255] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.598machine # [ 4.563482] systemd[1]: Stopped Create Static Device Nodes in /dev.599machine # [ 4.568448] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.600machine # [ 4.569649] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.601machine # [ 4.570671] systemd[1]: kmod-static-nodes.service: Deactivated successfully.602machine # [ 4.571660] systemd[1]: Stopped Create List of Static Device Nodes.603machine # [ 4.607335] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.604machine # [ 4.610600] systemd[1]: Finished Cleanup udev Database.605machine # [ 4.611409] systemd[1]: Reached target Switch Root.606machine # [ 4.616222] systemd[1]: Starting NixOS Activation...607machine # [ 4.692811] initrd-nixos-activation-start[175]: booting system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test608machine # [ 4.725895] initrd-nixos-activation-start[175]: running activation script...609machine # [ 4.973309] initrd-nixos-activation-start[198]: setting up /etc...610machine # [ 5.086768] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.611machine # [ 5.089768] systemd[1]: Finished NixOS Activation.612machine # [ 5.091035] systemd[1]: Starting Switch Root...613machine # [ 5.116364] systemd[1]: Switching root.614machine # [ 5.309202] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).615machine # [ 5.466821] systemd[1]: systemd 263-b43fed8 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)616machine # [ 5.467676] systemd[1]: Detected virtualization qemu.617machine # [ 5.476426] systemd[1]: Detected architecture arm64.618machine # [ 5.476528] systemd[1]: Detected first boot.619machine # [ 5.477891] systemd[1]: Initializing machine ID from random generator.620machine # [ 5.598712] systemd[1]: bpf-restrict-fs: LSM BPF program attached621machine # [ 5.741300] systemd[1]: Applying preset policy.622machine # [ 5.944518] systemd[1]: Populated /etc with preset unit settings.623machine # [ 6.141967] systemd[1]: initrd-switch-root.service: Deactivated successfully.624machine # [ 6.142778] systemd[1]: Stopped initrd-switch-root.service.625machine # [ 6.147369] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.626machine # [ 6.150842] systemd[1]: Created slice Slice /system/getty.627machine # [ 6.153019] systemd[1]: Created slice User and Session Slice.628machine # [ 6.153558] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.629machine # [ 6.154087] systemd[1]: Started Forward Password Requests to Wall Directory Watch.630machine # [ 6.154541] systemd[1]: Expecting device /dev/hvc0...631machine # [ 6.155027] systemd[1]: Expecting device /dev/ttyAMA0...632machine # [ 6.155550] systemd[1]: Reached target Local Encrypted Volumes.633machine # [ 6.160767] systemd[1]: Stopped target initrd-fs.target.634machine # [ 6.161246] systemd[1]: Stopped target initrd-root-fs.target.635machine # [ 6.161733] systemd[1]: Stopped target initrd-switch-root.target.636machine # [ 6.162233] systemd[1]: Reached target Virtual Machines and Containers.637machine # [ 6.162746] systemd[1]: Reached target Path Units.638machine # [ 6.163234] systemd[1]: Reached target Remote File Systems.639machine # [ 6.168593] systemd[1]: Reached target Slice Units.640machine # [ 6.168990] systemd[1]: Reached target Swaps.641machine # [ 6.170709] systemd[1]: Listening on Query the User Interactively for a Password.642machine # [ 6.174883] systemd[1]: Listening on Process Core Dump Socket.643machine # [ 6.177160] systemd[1]: Listening on Credential Encryption/Decryption.644machine # [ 6.178408] systemd[1]: Listening on Factory Reset Management.645machine # [ 6.178917] systemd[1]: Listening on Hostname Service Socket.646machine # [ 6.184379] systemd[1]: Starting Journal Log Access Socket...647machine # [ 6.185851] systemd[1]: Listening on Journal Audit Socket.648machine # [ 6.189286] systemd[1]: Listening on Console Output Muting Service Socket.649machine # [ 6.189897] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.650machine # [ 6.190245] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os651machine # [ 6.190497] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki652machine # [ 6.200090] systemd[1]: Listening on Disk Repartitioning Service Socket.653machine # [ 6.200737] systemd[1]: Listening on udev Varlink Socket.654machine # [ 6.203532] systemd[1]: Mounting Huge Pages File System...655machine # [ 6.212290] systemd[1]: Mounting POSIX Message Queue File System...656machine # [ 6.231941] systemd[1]: Mounting Kernel Debug File System...657machine # [ 6.238258] systemd[1]: Mounting Kernel Trace File System...658machine # [ 6.254843] systemd[1]: Starting Create List of Static Device Nodes...659machine # [ 6.255401] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs660machine # [ 6.271927] systemd[1]: Mounting Kernel Configuration File System...661machine # [ 6.276759] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm662machine # [ 6.284469] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore663machine # [ 6.284972] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse664machine # [ 6.307028] systemd[1]: Mounting FUSE Control File System...665machine # [ 6.307569] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67666machine # [ 6.337095] systemd[1]: Starting Journal Service...667machine # [ 6.363240] systemd[1]: Starting Load Kernel Modules...668machine # [ 6.384273] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...669machine # [ 6.404508] systemd[1]: Starting Remount Root and Kernel File Systems...670machine # [ 6.404909] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os671machine # [ 6.421380] systemd[1]: Starting Coldplug All udev Devices...672machine # [ 6.442069] systemd[1]: Listening on Journal Log Access Socket.673machine # [ 6.442627] systemd[1]: Mounted Huge Pages File System.674machine # [ 6.443116] systemd[1]: Mounted POSIX Message Queue File System.675machine # [ 6.443523] systemd[1]: Mounted Kernel Debug File System.676machine # [ 6.475302] systemd[1]: Mounted Kernel Trace File System.677machine # [ 6.477119] systemd-journald[269]: Collecting audit messages is enabled.678machine # [ 6.497413] systemd[1]: Finished Create List of Static Device Nodes.679machine # [ 6.498969] systemd[1]: Started Journal Service.680machine # [ 6.481702] systemd[1]: Queued start job for default target Multi-User System.681machine # [ 6.484965] systemd[1]: systemd-journald.service: Deactivated successfully.682machine # [ 6.486459] systemd-modules-load[270]: Module 'atkbd' is built in683machine # [ 6.487542] systemd-modules-load[270]: Module 'loop' is built in684machine # [ 6.508669] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...685machine # [ 6.509915] systemd[1]: Mounted Kernel Configuration File System.686machine # [ 6.515490] systemd[1]: Finished Load Kernel Modules.687machine # [ 6.529418] systemd[1]: Mounted FUSE Control File System.688machine # [ 6.567865] systemd-oomd[271]: No swap; memory pressure usage will be degraded689machine # [ 6.578894] systemd[1]: Starting Firewall...690machine # [ 6.586211] systemd[1]: Starting Apply Kernel Variables...691machine # [ 6.606998] EXT4-fs (vda): re-mounted 6824feaf-3581-46cf-b05e-1214a945b089.692machine # [ 6.592132] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.693machine # [ 6.630871] systemd[1]: Finished Remount Root and Kernel File Systems.694machine # [ 6.634590] systemd[1]: Listening on Disk Image Download Service Socket.695machine # [ 6.643080] systemd[1]: Starting Flush Journal to Persistent Storage...696machine # [ 6.650744] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore697machine # [ 6.663620] systemd[1]: Starting Load/Save OS Random Seed...698machine # [ 6.672627] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os699machine # [ 6.752126] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.700machine # [ 6.761893] systemd[1]: Starting Create Static Device Nodes in /dev...701machine # [ 6.795432] systemd-journald[269]: Received client request to flush runtime journal.702machine # [ 6.848475] systemd[1]: Finished Load/Save OS Random Seed.703machine # [ 6.849559] systemd[1]: Reached target First Boot Complete.704machine # [ 6.857118] systemd[1]: Finished Apply Kernel Variables.705machine # [ 6.862271] systemd[1]: Finished Flush Journal to Persistent Storage.706machine # [ 7.033665] systemd[1]: Finished Create Static Device Nodes in /dev.707machine # [ 7.036269] systemd[1]: Reached target Preparation for Local File Systems.708machine # [ 7.042557] systemd[1]: Starting Rule-based Manager for Device Events and Files...709machine # [ 7.132467] systemd[1]: Mounting /run/wrappers...710machine # [ 7.187532] systemd-udevd[304]: Using default interface naming scheme 'v261'.711machine # [ 7.207539] systemd[1]: Mounted /run/wrappers.712machine # [ 7.208925] systemd[1]: Reached target Local File Systems.713machine # [ 7.213452] systemd[1]: Listening on Boot Loader Control Service Socket.714machine # [ 7.222091] systemd[1]: Starting Create SUID/SGID Wrappers...715machine # [ 7.223037] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.716machine # [ 7.227776] systemd[1]: Starting Save Transient machine-id to Disk...717machine # [ 7.236266] systemd[1]: Starting Create System Files and Directories...718machine # [ 7.383796] systemd[1]: Started Rule-based Manager for Device Events and Files.719machine # [ 7.416153] systemd[1]: Finished Create System Files and Directories.720machine # [ 7.427131] systemd[1]: Starting Rebuild Journal Catalog...721machine # [ 7.438050] systemd[1]: Starting Record System Boot/Shutdown in UTMP...722machine # [ 7.534838] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.723machine # [ 7.551004] systemd[1]: Finished Save Transient machine-id to Disk.724machine # [ 7.573501] systemd[1]: Finished Record System Boot/Shutdown in UTMP.725machine # [ 7.606333] systemd[1]: Finished Rebuild Journal Catalog.726machine # [ 7.608729] systemd[1]: Starting Update is Completed...727machine # [ 7.685548] systemd[1]: Finished Update is Completed.728machine # [ 8.221435] systemd[1]: Finished Coldplug All udev Devices.729machine # [ 8.251785] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.730machine # [ 8.257682] systemd[1]: Finished Create SUID/SGID Wrappers.731machine # [ 8.259640] systemd[1]: Reached target System Initialization.732machine # [ 8.264649] systemd[1]: Started Discard unused filesystem blocks once a week.733machine # [ 8.265723] systemd[1]: Started Daily Cleanup of Temporary Directories.734machine # [ 8.266712] systemd[1]: Reached target Timer Units.735machine # [ 8.267466] systemd[1]: Listening on D-Bus System Message Bus Socket.736machine # [ 8.276305] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.737machine # [ 8.277635] systemd[1]: Reached target Socket Units.738machine # [ 8.278415] systemd[1]: Reached target Basic System.739machine # [ 8.279195] systemd[1]: Starting Import lastlog data into lastlog2 database...740machine # [ 8.290477] systemd[1]: Starting Name Service Cache Daemon (nsncd)...741machine # [ 8.296125] systemd[1]: Starting Post-Boot Actions...742machine # [ 8.325935] systemd[1]: Started Reset console on configuration changes.743machine # [ 8.363183] systemd[1]: Starting resolvconf update...744machine # [ 8.441130] systemd[1]: Starting D-Bus System Message Bus...745machine # [ 8.446015] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs746machine # [ 8.468151] systemd[1]: Started Name Service Cache Daemon (nsncd).747machine # [ 8.473693] nsncd[424]: Oct 02 03:44:33.971 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"748machine # [ 8.486972] systemd[1]: Finished Post-Boot Actions.749machine # [ 8.506681] systemd[1]: Reached target Host and Network Name Lookups.750machine # [ 8.513287] systemd[1]: Reached target User and Group Name Lookups.751machine # [ 8.514317] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse752machine # [ 8.534394] systemd[1]: Starting User Login Management...753machine # [ 8.606320] systemd[1]: Finished Import lastlog data into lastlog2 database.754machine # [ 8.731939] dbus-broker-launch[435]: Looking up NSS user entry for 'systemd-timesync'...755machine # [ 8.741227] dbus-broker-launch[435]: NSS returned no entry for 'systemd-timesync'756machine # [ 8.742346] dbus-broker-launch[435]: Invalid user-name in /nix/store/gd69sb1w7vq9gs3milyn1l3fs9gjfada-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"757machine # [ 8.770854] systemd-logind[451]: New seat seat0.758machine # [ 8.780497] systemd[1]: Started User Login Management.759machine # [ 8.790032] systemd[1]: Starting linger-users.service...760machine # [ 8.790935] systemd[1]: Started D-Bus System Message Bus.761machine # [ 8.851109] dbus-broker-launch[435]: Ready762machine # [ 8.910281] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.763machine # [ 8.917719] systemd[1]: Stopped target Host and Network Name Lookups.764machine # [ 8.918904] systemd[1]: Stopping Host and Network Name Lookups...765machine # [ 8.919830] systemd[1]: Stopped target User and Group Name Lookups.766machine # [ 8.937822] systemd[1]: Stopping User and Group Name Lookups...767machine # [ 8.938850] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...768machine # [ 8.939824] systemd[1]: nscd.service: Deactivated successfully.769machine # [ 8.950566] systemd[1]: Stopped Name Service Cache Daemon (nsncd).770machine # [ 9.007837] systemd[1]: Created slice Slice /user/1001.771machine # [ 9.020284] systemd[1]: Starting Name Service Cache Daemon (nsncd)...772machine # [ 9.031131] systemd[1]: Starting User Runtime Directory /run/user/1001...773machine # [ 9.094111] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.774machine # [ 9.114718] systemd[1]: Finished resolvconf update.775machine # [ 9.123172] systemd[1]: linger-users.service: Deactivated successfully.776machine # [ 9.127231] systemd[1]: Finished linger-users.service.777machine # [ 9.151300] systemd[1]: Started backdoor.service.778machine # [ 9.156659] nsncd[528]: Oct 02 03:44:34.666 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"779machine # [ 9.165712] systemd[1]: Starting DHCP Client...780machine # [ 9.169647] systemd[1]: Started Name Service Cache Daemon (nsncd).781machine # [ 9.178239] systemd[1]: Reached target Host and Network Name Lookups.782machine # [ 9.179400] systemd[1]: Reached target User and Group Name Lookups.783machine # [ 9.260279] systemd[1]: Finished User Runtime Directory /run/user/1001.784machine # [ 9.294474] systemd[1]: Starting User Manager for UID 1001...785machine # [ 9.338866] (udev-worker)[390]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.786machine # [ 9.399049] (udev-worker)[390]: Network interface NamePolicy= disabled on kernel command line.787machine # connecting to host...788machine # [ 9.497952] (systemd)[563]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)789machine # [ 9.533388] systemd-logind[451]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.790machine # [ 9.605594] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy791machine: Guest shell says: b'Spawning backdoor root shell...\n'792machine: connected to guest root shell793machine: (connecting took 9.96 seconds)794machine: (finished: waiting for the VM to finish booting, in 10.36 seconds)795machine # [ 9.900460] mousedev: PS/2 mouse device common for all mice796machine # [ 9.908676] systemd[1]: Condition check resulted in Virtio network device being skipped.797machine # [ 9.923363] systemd-logind[451]: Watching system buttons on /dev/input/event0 (gpio-keys)798machine # [ 9.932237] systemd[563]: Queued start job for default target Main User Target.799machine # [ 9.933716] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy800machine # [ 9.936473] systemd[563]: Created slice User Application Slice.801machine # [ 9.937392] systemd[563]: Started Daily Cleanup of User's Temporary Directories.802machine # [ 9.938438] systemd[563]: Reached target Paths.803machine # [ 9.939118] systemd[563]: Reached target Timers.804machine # [ 9.939813] systemd[563]: Starting D-Bus User Message Bus Socket...805machine # [ 9.976528] systemd[563]: Starting Create User Files and Directories...806machine # [ 9.977595] systemd[563]: Finished Create User Files and Directories.807machine # [ 10.016725] systemd[563]: Listening on D-Bus User Message Bus Socket.808machine # [ 10.017819] systemd[563]: Reached target Sockets.809machine # [ 10.018534] systemd[563]: Reached target Basic System.810machine # [ 10.019314] systemd[1]: Started User Manager for UID 1001.811machine # [ 10.037904] systemd[563]: Starting Run user-specific NixOS activation...812machine # [ 10.088599] systemd[563]: Finished Run user-specific NixOS activation.813machine # [ 10.089756] systemd[563]: Reached target Main User Target.814machine # [ 10.090571] systemd[563]: Startup finished in 524ms.815machine # [ 10.164450] dhcpcd[575]: dhcpcd-10.3.2 starting816machine # [ 10.192603] systemd-logind[451]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)817machine # [ 10.194404] dhcpcd[602]: dev: loaded udev818machine # [ 10.239819] 8021q: 802.1Q VLAN Support v1.8819machine # [ 10.257298] systemd[1]: Finished Firewall.820machine # [ 10.261687] systemd[1]: Reached target Preparation for Network.821machine # [ 10.268496] systemd[1]: Starting Address configuration of eth1...822machine # [ 10.295682] systemd[1]: Starting Extra networking commands....823machine # [ 10.359474] cfg80211: Loading compiled-in X.509 certificates for regulatory database824machine # [ 10.392575] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'825machine # [ 10.393089] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'826machine # [ 10.394113] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2827machine # [ 10.394428] cfg80211: failed to load regulatory.db828machine # [ 10.469554] 8021q: adding VLAN 0 to HW filter on device eth1829machine # [ 10.534960] network-addresses-eth1-start[612]: adding address 192.168.1.1/24... done830machine # [ 10.562752] dhcpcd[627]: /nix/store/92w4pf1lqszlcwfq9ma2y2vaad5dssks-openresolv-3.17.4/sbin/.resolvconf-wrapped: line 1250: kill: (619) - Operation not permitted831machine # [ 10.596637] dhcpcd[627]: .resolvconf-wrapped: clearing stale lock pid 619832machine # [ 10.622899] network-addresses-eth1-start[612]: adding address 2001:db8:1::1/64... done833machine # [ 10.735743] systemd[1]: Finished Address configuration of eth1.834machine # [ 10.847252] 8021q: adding VLAN 0 to HW filter on device eth0835machine # [ 10.840490] dhcpcd[602]: eth0: waiting for carrier836machine # [ 10.841332] dhcpcd[602]: libudev: received NULL device837machine # [ 10.842068] dhcpcd[602]: libudev: received NULL device838machine # [ 10.842938] dhcpcd[602]: eth0: carrier acquired839machine # [ 10.932958] dhcpcd[602]: DUID 00:01:00:01:32:51:e5:24:52:54:00:12:34:56840machine # [ 10.934037] dhcpcd[602]: eth0: IAID 00:12:34:56841machine # [ 10.934710] dhcpcd[602]: eth0: adding address fe80::5054:ff:fe12:3456842machine # [ 11.132696] dhcpcd[602]: eth0: soliciting a DHCP lease843machine # [ 11.142682] dhcpcd[602]: eth0: offered 10.0.2.15 from 10.0.2.2844machine # [ 11.156106] dhcpcd[602]: eth0: probing address 10.0.2.15/24845machine # [ 11.260584] systemd[1]: Finished Extra networking commands..846machine # [ 11.261570] systemd[1]: Reached target Network.847machine # [ 11.262260] systemd[1]: Starting Permit User Sessions...848machine # [ 11.340727] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3849machine # [ 11.515486] systemd[1]: Finished Permit User Sessions.850machine # [ 11.529633] systemd[1]: Started Getty on tty1.851machine # [ 11.540129] systemd[1]: Reached target Login Prompts.852machine # [ 11.760390] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.853machine # [ 11.781190] systemd[1]: Starting Virtual Console Setup...854machine # [ 11.839975] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.855machine # [ 11.841299] systemd[1]: Stopped Virtual Console Setup.856machine # [ 11.842329] systemd-logind[451]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)857machine # [ 11.853068] systemd[1]: Starting Virtual Console Setup...858machine # [ 11.956380] systemd-vconsole-setup[713]: Configuration of first virtual console was skipped, ignoring remaining ones.859machine # [ 11.963173] systemd[1]: Finished Virtual Console Setup.860machine # [ 12.309367] dhcpcd[602]: eth0: soliciting an IPv6 router861machine # [ 12.311466] dhcpcd[602]: eth0: Router Advertisement from fe80::2862machine # [ 12.313069] dhcpcd[602]: eth0: adding address fec0::5054:ff:fe12:3456/64863machine # [ 12.314530] dhcpcd[602]: eth0: adding route to fec0::/64864machine # [ 12.315736] dhcpcd[602]: eth0: adding default route via fe80::2865machine # [ 16.437927] dhcpcd[602]: eth0: leased 10.0.2.15 for 86400 seconds866machine # [ 16.439953] dhcpcd[602]: eth0: adding route to 10.0.2.0/24867machine # [ 16.442862] dhcpcd[602]: eth0: adding default route via 10.0.2.2868machine # [ 16.556703] systemd[1]: Started DHCP Client.869machine # [ 16.559005] systemd[1]: Reached target Multi-User System.870machine # [ 16.564402] systemd[1]: Startup finished in 1.066s (kernel) + 4.296s (initrd) + 11.195s (userspace) = 16.558s.871machine: (finished: waiting for unit multi-user.target, in 17.88 seconds)872machine: must succeed: /nix/store/vyagcp08nk4jxixq8lbwf1aba9f9a1qj-stderr-runner /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test873machine # Checking switch inhibitors... done874machine # [ 17.431417] nixos[821]: switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test875machine # [ 17.434666] systemd[1]: Stopped target Local File Systems.876machine # activating the configuration...877machine # [ 17.438467] systemd[1]: Stopped target Remote File Systems.878machine # setting up /etc...879machine # [ 17.751584] systemd[1]: Reload requested from client PID 821 ('.switch-to-conf') (unit backdoor.service)...880machine # [ 17.753945] systemd[1]: Reloading...881machine # [ 18.077565] systemd[1]: Reloading finished in 322 ms.882machine # reloading user units for usertest...883machine # [ 18.127046] systemd[563]: Created slice User Core Session Slice.884machine # [ 18.130393] systemd[563]: Starting D-Bus User Message Bus...885machine # [ 18.164427] dbus-broker-launch[891]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/gd69sb1w7vq9gs3milyn1l3fs9gjfada-system-path/share/dbus-1/services/org.freedesktop.import1.service'886machine # [ 18.167652] dbus-broker-launch[891]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/gd69sb1w7vq9gs3milyn1l3fs9gjfada-system-path/share/dbus-1/services/org.freedesktop.machine1.service'887machine # [ 18.170990] dbus-broker-launch[891]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/gd69sb1w7vq9gs3milyn1l3fs9gjfada-system-path/share/dbus-1/services/org.freedesktop.portable1.service'888machine # [ 18.174129] dbus-broker-launch[891]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/gd69sb1w7vq9gs3milyn1l3fs9gjfada-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'889machine # [ 18.178951] dbus-broker-launch[891]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored890machine # [ 18.182121] dbus-broker-launch[891]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored891machine # [ 18.183946] systemd[563]: Started D-Bus User Message Bus.892machine # [ 18.188099] dbus-broker-launch[891]: Ready893machine # [ 18.231095] systemd[563]: Reexecution requested from client PID 890 ('switch-to-confi')...894machine # [ 18.235596] systemd[563]: Reexecuting.895machine # [ 18.296164] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy896machine # [ 18.418419] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy897machine # [ 18.426427] systemd[563]: Reload requested from client PID 890 ('switch-to-confi')...898machine # [ 18.429161] systemd[563]: Reloading...899machine # [ 18.543113] systemd[563]: Reloading finished in 112 ms.900machine # [ 18.561962] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy901machine # restarting the following user units: nixos-activation.service902machine # [ 18.571898] systemd[563]: Stopped Run user-specific NixOS activation.903machine # [ 18.574699] systemd[563]: Stopping Run user-specific NixOS activation...904machine # [ 18.577187] systemd[563]: Starting Run user-specific NixOS activation...905machine # [ 18.613807] systemd[563]: Finished Run user-specific NixOS activation.906machine # restarting sysinit-reactivation.target907machine # [ 18.627706] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....908machine # [ 18.720523] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..909machine # [ 18.721888] systemd[1]: Reached target Reactivate sysinit units.910machine # [ 18.729122] systemd[1]: Reached target Local File Systems.911machine # [ 18.732917] systemd[1]: Reached target Remote File Systems.912machine # [ 18.733917] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os913machine # [ 18.735270] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki914machine # [ 18.737291] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore915machine # [ 18.741693] systemd[1]: Starting Create SUID/SGID Wrappers...916machine # [ 18.742659] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.917machine # [ 18.752637] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67918machine # [ 18.755014] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore919machine # [ 18.760501] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os920machine # [ 18.761900] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os921machine # [ 18.931767] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.922machine # [ 18.934844] systemd[1]: Finished Create SUID/SGID Wrappers.923machine # [ 18.940283] systemd[1]: Starting linger-users.service...924machine # [ 18.989536] systemd[1]: linger-users.service: Deactivated successfully.925machine # [ 18.992859] systemd[1]: Finished linger-users.service.926machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service927machine # [ 19.250908] nixos[821]: finished switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test928machine: (finished: must succeed: /nix/store/vyagcp08nk4jxixq8lbwf1aba9f9a1qj-stderr-runner /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test, in 2.06 seconds)929machine: must succeed: rm /etc/NIXOS930machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds)931machine: must succeed: /nix/store/vyagcp08nk4jxixq8lbwf1aba9f9a1qj-stderr-runner /nix/store/b6zkgj7abs0az1vjknyf6v70nc20i0vl-nixos-system-other-test/bin/switch-to-configuration test932machine # this should succeed (config: /nix/store/b6zkgj7abs0az1vjknyf6v70nc20i0vl-nixos-system-other-test, action: test)933machine # Checking switch inhibitors... done934machine # [ 19.533060] nixos[968]: switching to system configuration /nix/store/b6zkgj7abs0az1vjknyf6v70nc20i0vl-nixos-system-other-test935machine # [ 19.535648] systemd[1]: Stopped target Remote File Systems.936machine # activating the configuration...937machine # [ 19.539199] systemd[1]: Stopped target Local File Systems.938machine # removing user ‘usertest’939machine # setting up /etc...940machine # [ 19.826974] systemd[1]: Reload requested from client PID 968 ('.switch-to-conf') (unit backdoor.service)...941machine # [ 19.829150] systemd[1]: Reloading...942machine # [ 20.159911] systemd[1]: Reloading finished in 329 ms.943machine # reloading user units for usertest...944machine # [ 20.296765] systemd[563]: Reexecution requested from client PID 1039 ('switch-to-confi')...945machine # [ 20.301640] systemd[563]: Reexecuting.946machine # [ 20.356107] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy947machine # [ 20.478743] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy948machine # [ 20.486105] systemd[563]: Reload requested from client PID 1039 ('switch-to-confi')...949machine # [ 20.490827] systemd[563]: Reloading...950machine # [ 20.600711] systemd[563]: Reloading finished in 111 ms.951machine # [ 20.616227] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy952machine # restarting the following user units: nixos-activation.service953machine # [ 20.627402] systemd[563]: Stopped Run user-specific NixOS activation.954machine # [ 20.629965] systemd[563]: Stopping Run user-specific NixOS activation...955machine # [ 20.632473] systemd[563]: Starting Run user-specific NixOS activation...956machine # [ 20.667302] systemd[563]: Finished Run user-specific NixOS activation.957machine # restarting sysinit-reactivation.target958machine # [ 20.671912] systemd[1]: Stopped target Reactivate sysinit units.959machine # [ 20.673339] systemd[1]: Stopping Reactivate sysinit units...960machine # [ 20.674214] systemd[1]: Reached target Reactivate sysinit units.961machine # reloading the following units: dbus-broker.service962machine # [ 20.678391] systemd[1]: Reloading D-Bus System Message Bus...963machine # [ 20.679662] dbus-broker-launch[435]: Caught SIGHUP, trigger reload.964machine # [ 20.686105] dbus-broker-launch[435]: Looking up NSS user entry for 'systemd-timesync'...965machine # [ 20.688444] dbus-broker-launch[435]: NSS returned no entry for 'systemd-timesync'966machine # [ 20.689541] dbus-broker-launch[435]: Invalid user-name in /nix/store/qzyzdicfn2s1rfzv47bbff6rf94vd1f2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"967machine # [ 20.709819] systemd[1]: Reloaded D-Bus System Message Bus.968machine # restarting the following units: network-addresses-eth1.service969machine # [ 20.716281] systemd[1]: Stopping Address configuration of eth1...970machine # [ 20.747989] network-addresses-eth1-pre-stop[1052]: deleting address 192.168.1.1/24... done971machine # [ 20.754512] network-addresses-eth1-pre-stop[1052]: deleting address 2001:db8:1::1/64... done972machine # [ 20.761719] systemd[1]: network-addresses-eth1.service: Deactivated successfully.973machine # [ 20.763979] systemd[1]: Stopped Address configuration of eth1.974machine # [ 20.769083] systemd[1]: Starting Address configuration of eth1...975machine # [ 20.811028] network-addresses-eth1-start[1057]: adding address 192.168.1.2/24... done976machine # [ 20.818442] network-addresses-eth1-start[1057]: adding address 2001:db8:1::2/64... done977machine # [ 20.828362] systemd[1]: Finished Address configuration of eth1.978machine # [ 20.832303] systemd[1]: Reached target Remote File Systems.979machine # [ 20.836337] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os980machine # [ 20.840179] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki981machine # [ 20.847187] systemd[1]: Starting linger-users.service...982machine # [ 20.859943] systemd[1]: Reached target Local File Systems.983machine # [ 20.865171] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore984machine # [ 20.866864] systemd[1]: Starting Create SUID/SGID Wrappers...985machine # [ 20.867712] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.986machine # [ 20.876732] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67987machine # [ 20.881245] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore988machine # [ 20.883541] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os989machine # [ 20.888602] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os990machine # [ 20.915903] linger-users-start[1071]: id: ‘usertest’: no such user991machine # [ 20.919538] linger-users-start[1068]: Removing linger for missing user usertest992machine # [ 20.928727] systemd[1]: linger-users.service: Deactivated successfully.993machine # [ 20.930471] systemd[1]: Finished linger-users.service.994machine # [ 21.095729] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.995machine # [ 21.099395] systemd[1]: Finished Create SUID/SGID Wrappers.996machine # [ 21.353541] nixos[968]: finished switching to system configuration /nix/store/b6zkgj7abs0az1vjknyf6v70nc20i0vl-nixos-system-other-test997machine: (finished: must succeed: /nix/store/vyagcp08nk4jxixq8lbwf1aba9f9a1qj-stderr-runner /nix/store/b6zkgj7abs0az1vjknyf6v70nc20i0vl-nixos-system-other-test/bin/switch-to-configuration test, in 2.07 seconds)998machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&1999machine # [ 21.585680] nixos[1135]: switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test1000machine # [ 21.588580] systemd[1]: Stopped target Remote File Systems.1001machine # [ 21.590302] systemd[1]: Stopped target Local File Systems.1002machine # [ 21.874293] systemd[1]: Reload requested from client PID 1135 ('.switch-to-conf') (unit backdoor.service)...1003machine # [ 21.875910] systemd[1]: Reloading...1004machine # [ 22.349697] systemd[1]: Reloading finished in 472 ms.1005machine # [ 22.443264] systemd[563]: Reexecution requested from client PID 1204 ('switch-to-confi')...1006machine # [ 22.447553] systemd[563]: Reexecuting.1007machine # [ 22.496196] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1008machine # [ 22.617447] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1009machine # [ 22.625314] systemd[563]: Reload requested from client PID 1204 ('switch-to-confi')...1010machine # [ 22.629882] systemd[563]: Reloading...1011machine # [ 22.765808] systemd[563]: Reloading finished in 137 ms.1012machine # [ 22.780389] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1013machine # [ 22.791565] systemd[563]: Stopped Run user-specific NixOS activation.1014machine # [ 22.794635] systemd[563]: Stopping Run user-specific NixOS activation...1015machine # [ 22.797206] systemd[563]: Starting Run user-specific NixOS activation...1016machine # [ 22.831574] systemd[563]: Finished Run user-specific NixOS activation.1017machine # [ 22.836721] systemd[1]: Stopped target Reactivate sysinit units.1018machine # [ 22.837820] systemd[1]: Stopping Reactivate sysinit units...1019machine # [ 22.838660] systemd[1]: Reached target Reactivate sysinit units.1020machine # [ 22.842205] dbus-broker-launch[435]: Caught SIGHUP, trigger reload.1021machine # [ 22.845043] systemd[1]: Reloading D-Bus System Message Bus...1022machine # [ 22.849871] dbus-broker-launch[435]: Looking up NSS user entry for 'systemd-timesync'...1023machine # [ 22.852321] dbus-broker-launch[435]: NSS returned no entry for 'systemd-timesync'1024machine # [ 22.853424] dbus-broker-launch[435]: Invalid user-name in /nix/store/gd69sb1w7vq9gs3milyn1l3fs9gjfada-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1025machine # [ 22.873223] systemd[1]: Reloaded D-Bus System Message Bus.1026machine # [ 22.879652] systemd[1]: Stopping Address configuration of eth1...1027machine # [ 22.913107] network-addresses-eth1-pre-stop[1216]: deleting address 192.168.1.2/24... done1028machine # [ 22.920317] network-addresses-eth1-pre-stop[1216]: deleting address 2001:db8:1::2/64... done1029machine # [ 22.928158] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1030machine # [ 22.930648] systemd[1]: Stopped Address configuration of eth1.1031machine # [ 22.935418] systemd[1]: Starting Address configuration of eth1...1032machine # [ 22.980892] network-addresses-eth1-start[1221]: adding address 192.168.1.1/24... done1033machine # [ 22.988313] network-addresses-eth1-start[1221]: adding address 2001:db8:1::1/64... done1034machine # [ 22.998928] systemd[1]: Finished Address configuration of eth1.1035machine # [ 23.003441] systemd[1]: Reached target Remote File Systems.1036machine # [ 23.007408] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1037machine # [ 23.012475] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1038machine # [ 23.016961] systemd[1]: Starting linger-users.service...1039machine # [ 23.020875] systemd[1]: Reached target Local File Systems.1040machine # [ 23.028158] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1041machine # [ 23.040176] systemd[1]: Starting Create SUID/SGID Wrappers...1042machine # [ 23.041220] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1043machine # [ 23.042564] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671044machine # [ 23.052880] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1045machine # [ 23.054665] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1046machine # [ 23.064373] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1047machine # [ 23.104147] systemd[1]: linger-users.service: Deactivated successfully.1048machine # [ 23.106585] systemd[1]: Finished linger-users.service.1049machine # [ 23.329723] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1050machine # [ 23.334020] systemd[1]: Finished Create SUID/SGID Wrappers.1051machine # [ 23.590513] nixos[1135]: finished switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test1052machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.23 seconds)1053subtest: user services1054machine: waiting for unit user@1001.service1055machine: (finished: waiting for unit user@1001.service, in 0.07 seconds)1056machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11057machine # [ 23.899994] nixos[1295]: switching to system configuration /nix/store/qv0brs3kvzm8vysp1wxg2hp56amw3pif-nixos-system-machine-test1058machine # [ 23.903305] systemd[1]: Stopped target Remote File Systems.1059machine # [ 23.906605] systemd[1]: Stopped target Local File Systems.1060machine # [ 24.225673] systemd[1]: Reload requested from client PID 1295 ('.switch-to-conf') (unit backdoor.service)...1061machine # [ 24.227810] systemd[1]: Reloading...1062machine # [ 24.594157] systemd[1]: Reloading finished in 364 ms.1063machine # [ 24.698842] systemd[563]: Reexecution requested from client PID 1364 ('switch-to-confi')...1064machine # [ 24.701587] systemd[563]: Reexecuting.1065machine # [ 24.764655] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1066machine # [ 24.899265] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1067machine # [ 24.908261] systemd[563]: Reload requested from client PID 1364 ('switch-to-confi')...1068machine # [ 24.910833] systemd[563]: Reloading...1069machine # [ 25.033868] systemd[563]: Reloading finished in 121 ms.1070machine # [ 25.048271] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1071machine # [ 25.060842] systemd[563]: Starting usertest.service...1072machine # [ 25.088242] systemd[563]: Finished usertest.service.1073machine # [ 25.092175] systemd[563]: Stopped Run user-specific NixOS activation.1074machine # [ 25.093244] systemd[563]: Stopping Run user-specific NixOS activation...1075machine # [ 25.096823] systemd[563]: Starting Run user-specific NixOS activation...1076machine # [ 25.135014] systemd[563]: Finished Run user-specific NixOS activation.1077machine # [ 25.140932] systemd[1]: Stopped target Reactivate sysinit units.1078machine # [ 25.144686] systemd[1]: Stopping Reactivate sysinit units...1079machine # [ 25.145634] systemd[1]: Reached target Reactivate sysinit units.1080machine # [ 25.147119] systemd[1]: Reached target Local File Systems.1081machine # [ 25.152110] systemd[1]: Reached target Remote File Systems.1082machine # [ 25.153029] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1083machine # [ 25.154401] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1084machine # [ 25.162428] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1085machine # [ 25.171645] systemd[1]: Starting Create SUID/SGID Wrappers...1086machine # [ 25.178335] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1087machine # [ 25.179774] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671088machine # [ 25.186129] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1089machine # [ 25.187912] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1090machine # [ 25.189498] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1091machine # [ 25.393446] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1092machine # [ 25.395541] systemd[1]: Finished Create SUID/SGID Wrappers.1093machine # [ 25.402555] systemd[1]: Starting linger-users.service...1094machine # [ 25.452156] systemd[1]: linger-users.service: Deactivated successfully.1095machine # [ 25.454555] systemd[1]: Finished linger-users.service.1096machine # [ 25.713693] nixos[1295]: finished switching to system configuration /nix/store/qv0brs3kvzm8vysp1wxg2hp56amw3pif-nixos-system-machine-test1097machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.05 seconds)1098machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1099machine # [ 25.767405] sudo[1434]: 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.service1100machine # [ 25.783313] sudo[1434]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1101machine # [ 25.797073] sudo[1434]: pam_unix(sudo:session): session closed for user usertest1102machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1103machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11104machine # [ 26.026462] nixos[1443]: switching to system configuration /nix/store/qv0brs3kvzm8vysp1wxg2hp56amw3pif-nixos-system-machine-test1105machine # [ 26.029391] systemd[1]: Stopped target Remote File Systems.1106machine # [ 26.032325] systemd[1]: Stopped target Local File Systems.1107machine # [ 26.371503] systemd[1]: Reload requested from client PID 1443 ('.switch-to-conf') (unit backdoor.service)...1108machine # [ 26.373876] systemd[1]: Reloading...1109machine # [ 26.749716] systemd[1]: Reloading finished in 372 ms.1110machine # [ 26.841268] systemd[563]: Reexecution requested from client PID 1512 ('switch-to-confi')...1111machine # [ 26.845506] systemd[563]: Reexecuting.1112machine # [ 26.901819] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1113machine # [ 27.016152] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1114machine # [ 27.024139] systemd[563]: Reload requested from client PID 1512 ('switch-to-confi')...1115machine # [ 27.028084] systemd[563]: Reloading...1116machine # [ 27.148829] systemd[563]: Reloading finished in 121 ms.1117machine # [ 27.164195] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1118machine # [ 27.175999] systemd[563]: Stopped Run user-specific NixOS activation.1119machine # [ 27.178616] systemd[563]: Stopping Run user-specific NixOS activation...1120machine # [ 27.182182] systemd[563]: Starting Run user-specific NixOS activation...1121machine # [ 27.218126] systemd[563]: Finished Run user-specific NixOS activation.1122machine # [ 27.222962] systemd[1]: Stopped target Reactivate sysinit units.1123machine # [ 27.225251] systemd[1]: Stopping Reactivate sysinit units...1124machine # [ 27.226132] systemd[1]: Reached target Reactivate sysinit units.1125machine # [ 27.230280] systemd[1]: Reached target Local File Systems.1126machine # [ 27.234499] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1127machine # [ 27.238373] systemd[1]: Starting Create SUID/SGID Wrappers...1128machine # [ 27.239306] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1129machine # [ 27.248625] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671130machine # [ 27.251046] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1131machine # [ 27.259433] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1132machine # [ 27.263509] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1133machine # [ 27.268491] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1134machine # [ 27.269934] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1135machine # [ 27.273139] systemd[1]: Reached target Remote File Systems.1136machine # [ 27.442118] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1137machine # [ 27.445472] systemd[1]: Finished Create SUID/SGID Wrappers.1138machine # [ 27.450099] systemd[1]: Starting linger-users.service...1139machine # [ 27.496912] systemd[1]: linger-users.service: Deactivated successfully.1140machine # [ 27.499964] systemd[1]: Finished linger-users.service.1141machine # [ 27.755870] nixos[1443]: finished switching to system configuration /nix/store/qv0brs3kvzm8vysp1wxg2hp56amw3pif-nixos-system-machine-test1142machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.96 seconds)1143machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&11144machine # [ 27.990124] nixos[1584]: switching to system configuration /nix/store/y590nyc43cx1i3ar011j0kgm1v9kq6rx-nixos-system-machine-test1145machine # [ 27.992871] systemd[1]: Stopped target Local File Systems.1146machine # [ 27.995419] systemd[1]: Stopped target Remote File Systems.1147machine # [ 28.311596] systemd[1]: Reload requested from client PID 1584 ('.switch-to-conf') (unit backdoor.service)...1148machine # [ 28.313910] systemd[1]: Reloading...1149machine # [ 28.652646] systemd[1]: Reloading finished in 336 ms.1150machine # [ 28.750355] systemd[563]: Stopped usertest.service.1151machine # [ 28.754337] systemd[563]: Reexecution requested from client PID 1653 ('switch-to-confi')...1152machine # [ 28.755892] systemd[563]: Reexecuting.1153machine # [ 28.816411] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1154machine # [ 28.937383] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1155machine # [ 28.944719] systemd[563]: Reload requested from client PID 1653 ('switch-to-confi')...1156machine # [ 28.947369] systemd[563]: Reloading...1157machine # [ 29.064227] systemd[563]: Reloading finished in 115 ms.1158machine # [ 29.080189] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1159machine # [ 29.092976] systemd[563]: Starting usertest.service...1160machine # [ 29.118267] systemd[563]: Finished usertest.service.1161machine # [ 29.121643] systemd[563]: Stopped Run user-specific NixOS activation.1162machine # [ 29.124001] systemd[563]: Stopping Run user-specific NixOS activation...1163machine # [ 29.126275] systemd[563]: Starting Run user-specific NixOS activation...1164machine # [ 29.161518] systemd[563]: Finished Run user-specific NixOS activation.1165machine # [ 29.166060] systemd[1]: Stopped target Reactivate sysinit units.1166machine # [ 29.168896] systemd[1]: Stopping Reactivate sysinit units...1167machine # [ 29.169813] systemd[1]: Reached target Reactivate sysinit units.1168machine # [ 29.174942] systemd[1]: Reached target Local File Systems.1169machine # [ 29.179436] systemd[1]: Reached target Remote File Systems.1170machine # [ 29.180681] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1171machine # [ 29.184902] systemd[1]: Starting Create SUID/SGID Wrappers...1172machine # [ 29.185847] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1173machine # [ 29.187128] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671174machine # [ 29.202935] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1175machine # [ 29.209358] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1176machine # [ 29.210785] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1177machine # [ 29.212066] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1178machine # [ 29.213407] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1179machine # [ 29.427863] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1180machine # [ 29.430108] systemd[1]: Finished Create SUID/SGID Wrappers.1181machine # [ 29.437684] systemd[1]: Starting linger-users.service...1182machine # [ 29.492287] systemd[1]: linger-users.service: Deactivated successfully.1183machine # [ 29.494233] systemd[1]: Finished linger-users.service.1184machine # [ 29.750726] nixos[1584]: finished switching to system configuration /nix/store/y590nyc43cx1i3ar011j0kgm1v9kq6rx-nixos-system-machine-test1185machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 1.99 seconds)1186machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1187machine # [ 29.801103] sudo[1723]: 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.service1188machine # [ 29.819318] sudo[1723]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1189machine # [ 29.835917] sudo[1723]: pam_unix(sudo:session): session closed for user usertest1190machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1191machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&11192machine # [ 30.047088] nixos[1732]: switching to system configuration /nix/store/d8b3i4ppjz9vwacyvd9hixijb5a5a2gk-nixos-system-machine-test1193machine # [ 30.050321] systemd[1]: Stopped target Local File Systems.1194machine # [ 30.052939] systemd[1]: Stopped target Remote File Systems.1195machine # [ 30.433866] systemd[1]: Reload requested from client PID 1732 ('.switch-to-conf') (unit backdoor.service)...1196machine # [ 30.435961] systemd[1]: Reloading...1197machine # [ 30.787636] systemd[1]: Reloading finished in 349 ms.1198machine # [ 30.890484] systemd[563]: Reexecution requested from client PID 1801 ('switch-to-confi')...1199machine # [ 30.893079] systemd[563]: Reexecuting.1200machine # [ 30.956160] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1201machine # [ 31.089051] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1202machine # [ 31.096456] systemd[563]: Reload requested from client PID 1801 ('switch-to-confi')...1203machine # [ 31.098922] systemd[563]: Reloading...1204machine # [ 31.218356] systemd[563]: Reloading finished in 118 ms.1205machine # [ 31.236202] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1206machine # [ 31.245537] systemd[563]: Stopped usertest.service.1207machine # [ 31.246490] systemd[563]: Stopping usertest.service...1208machine # [ 31.248727] systemd[563]: Starting usertest.service...1209machine # [ 31.270611] systemd[563]: Finished usertest.service.1210machine # [ 31.277517] systemd[563]: Stopped Run user-specific NixOS activation.1211machine # [ 31.278659] systemd[563]: Stopping Run user-specific NixOS activation...1212machine # [ 31.281388] systemd[563]: Starting Run user-specific NixOS activation...1213machine # [ 31.318126] systemd[563]: Finished Run user-specific NixOS activation.1214machine # [ 31.323038] systemd[1]: Stopped target Reactivate sysinit units.1215machine # [ 31.325966] systemd[1]: Stopping Reactivate sysinit units...1216machine # [ 31.326881] systemd[1]: Reached target Reactivate sysinit units.1217machine # [ 31.329298] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1218machine # [ 31.330684] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1219machine # [ 31.335535] systemd[1]: Reached target Remote File Systems.1220machine # [ 31.343404] systemd[1]: Starting linger-users.service...1221machine # [ 31.348650] systemd[1]: Reached target Local File Systems.1222machine # [ 31.359133] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1223machine # [ 31.368315] systemd[1]: Starting Create SUID/SGID Wrappers...1224machine # [ 31.369346] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1225machine # [ 31.370661] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671226machine # [ 31.378933] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1227machine # [ 31.384319] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1228machine # [ 31.390233] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1229machine # [ 31.442186] systemd[1]: linger-users.service: Deactivated successfully.1230machine # [ 31.447102] systemd[1]: Finished linger-users.service.1231machine # [ 31.590031] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1232machine # [ 31.592304] systemd[1]: Finished Create SUID/SGID Wrappers.1233machine # [ 31.849690] nixos[1732]: finished switching to system configuration /nix/store/d8b3i4ppjz9vwacyvd9hixijb5a5a2gk-nixos-system-machine-test1234machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 2.02 seconds)1235machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1236machine # [ 31.898618] sudo[1872]: 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.service1237machine # [ 31.915028] sudo[1872]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1238machine # [ 31.930447] sudo[1872]: pam_unix(sudo:session): session closed for user usertest1239machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1240machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&11241machine # [ 32.151333] nixos[1881]: switching to system configuration /nix/store/kllflk9mr5nm23dq0050lmzpzxmhavlc-nixos-system-machine-test1242machine # [ 32.154159] systemd[1]: Stopped target Remote File Systems.1243machine # [ 32.156887] systemd[1]: Stopped target Local File Systems.1244machine # [ 32.490097] systemd[1]: Reload requested from client PID 1881 ('.switch-to-conf') (unit backdoor.service)...1245machine # [ 32.492243] systemd[1]: Reloading...1246machine # [ 32.865083] systemd[1]: Reloading finished in 371 ms.1247machine # [ 32.963718] systemd[563]: Reexecution requested from client PID 1950 ('switch-to-confi')...1248machine # [ 32.969405] systemd[563]: Reexecuting.1249machine # [ 33.024005] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1250machine # [ 33.153965] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1251machine # [ 33.162209] systemd[563]: Reload requested from client PID 1950 ('switch-to-confi')...1252machine # [ 33.166859] systemd[563]: Reloading...1253machine # [ 33.290247] systemd[563]: Reloading finished in 124 ms.1254machine # [ 33.304236] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1255machine # [ 33.314305] systemd[563]: Reloading usertest.service...1256machine # [ 33.343262] systemd[563]: Reloaded usertest.service.1257machine # [ 33.349635] systemd[563]: Stopped Run user-specific NixOS activation.1258machine # [ 33.352304] systemd[563]: Stopping Run user-specific NixOS activation...1259machine # [ 33.355120] systemd[563]: Starting Run user-specific NixOS activation...1260machine # [ 33.390118] systemd[563]: Finished Run user-specific NixOS activation.1261machine # [ 33.394700] systemd[1]: Stopped target Reactivate sysinit units.1262machine # [ 33.397549] systemd[1]: Stopping Reactivate sysinit units...1263machine # [ 33.398490] systemd[1]: Reached target Reactivate sysinit units.1264machine # [ 33.401190] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1265machine # [ 33.405125] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1266machine # [ 33.407188] systemd[1]: Reached target Remote File Systems.1267machine # [ 33.413000] systemd[1]: Reached target Local File Systems.1268machine # [ 33.413906] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1269machine # [ 33.417713] systemd[1]: Starting Create SUID/SGID Wrappers...1270machine # [ 33.418664] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1271machine # [ 33.420001] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671272machine # [ 33.431247] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1273machine # [ 33.435824] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1274machine # [ 33.442079] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1275machine # [ 33.695100] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1276machine # [ 33.698755] systemd[1]: Finished Create SUID/SGID Wrappers.1277machine # [ 33.704643] systemd[1]: Starting linger-users.service...1278machine # [ 33.750884] systemd[1]: linger-users.service: Deactivated successfully.1279machine # [ 33.753022] systemd[1]: Finished linger-users.service.1280machine # [ 34.010800] nixos[1881]: finished switching to system configuration /nix/store/kllflk9mr5nm23dq0050lmzpzxmhavlc-nixos-system-machine-test1281machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 2.08 seconds)1282machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1283machine # [ 34.061553] sudo[2021]: 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.service1284machine # [ 34.078200] sudo[2021]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1285machine # [ 34.094466] sudo[2021]: pam_unix(sudo:session): session closed for user usertest1286machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1287machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11288machine # [ 34.301745] nixos[2030]: switching to system configuration /nix/store/qv0brs3kvzm8vysp1wxg2hp56amw3pif-nixos-system-machine-test1289machine # [ 34.307398] systemd[1]: Stopped target Remote File Systems.1290machine # [ 34.309886] systemd[1]: Stopped target Local File Systems.1291machine # [ 34.623890] systemd[1]: Reload requested from client PID 2030 ('.switch-to-conf') (unit backdoor.service)...1292machine # [ 34.626297] systemd[1]: Reloading...1293machine # [ 35.019138] systemd[1]: Reloading finished in 391 ms.1294machine # [ 35.122552] systemd[563]: Stopped usertest.service.1295machine # [ 35.126639] systemd[563]: Reexecution requested from client PID 2099 ('switch-to-confi')...1296machine # [ 35.129716] systemd[563]: Reexecuting.1297machine # [ 35.196980] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1298machine # [ 35.325815] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1299machine # [ 35.333828] systemd[563]: Reload requested from client PID 2099 ('switch-to-confi')...1300machine # [ 35.338597] systemd[563]: Reloading...1301machine # [ 35.460501] systemd[563]: Reloading finished in 123 ms.1302machine # [ 35.476212] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1303machine # [ 35.487539] systemd[563]: Starting usertest.service...1304machine # [ 35.519487] systemd[563]: Finished usertest.service.1305machine # [ 35.522938] systemd[563]: Stopped Run user-specific NixOS activation.1306machine # [ 35.525569] systemd[563]: Stopping Run user-specific NixOS activation...1307machine # [ 35.527958] systemd[563]: Starting Run user-specific NixOS activation...1308machine # [ 35.562994] systemd[563]: Finished Run user-specific NixOS activation.1309machine # [ 35.567521] systemd[1]: Stopped target Reactivate sysinit units.1310machine # [ 35.570616] systemd[1]: Stopping Reactivate sysinit units...1311machine # [ 35.571547] systemd[1]: Reached target Reactivate sysinit units.1312machine # [ 35.574719] systemd[1]: Reached target Remote File Systems.1313machine # [ 35.579097] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1314machine # [ 35.586780] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1315machine # [ 35.588454] systemd[1]: Reached target Local File Systems.1316machine # [ 35.589271] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1317machine # [ 35.591851] systemd[1]: Starting Create SUID/SGID Wrappers...1318machine # [ 35.596535] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1319machine # [ 35.597918] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671320machine # [ 35.609831] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1321machine # [ 35.611594] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1322machine # [ 35.613226] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1323machine # [ 35.794603] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1324machine # [ 35.798007] systemd[1]: Finished Create SUID/SGID Wrappers.1325machine # [ 35.803216] systemd[1]: Starting linger-users.service...1326machine # [ 35.850275] systemd[1]: linger-users.service: Deactivated successfully.1327machine # [ 35.852347] systemd[1]: Finished linger-users.service.1328machine # [ 36.110363] nixos[2030]: finished switching to system configuration /nix/store/qv0brs3kvzm8vysp1wxg2hp56amw3pif-nixos-system-machine-test1329machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.02 seconds)1330machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1331machine # [ 36.158977] sudo[2169]: 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.service1332machine # [ 36.171677] sudo[2169]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1333machine # [ 36.186118] sudo[2169]: pam_unix(sudo:session): session closed for user usertest1334machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1335machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&11336machine # [ 36.410974] nixos[2178]: switching to system configuration /nix/store/81x8xnrhv4jzxhik13w9q6n97dyr4wzc-nixos-system-machine-test1337machine # [ 36.417501] systemd[1]: Stopped target Remote File Systems.1338machine # [ 36.418518] systemd[1]: Stopped target Local File Systems.1339machine # [ 36.763543] systemd[1]: Reload requested from client PID 2178 ('.switch-to-conf') (unit backdoor.service)...1340machine # [ 36.765970] systemd[1]: Reloading...1341machine # [ 37.546208] systemd[1]: Reloading finished in 777 ms.1342machine # [ 37.696645] systemd[563]: Reexecution requested from client PID 2247 ('switch-to-confi')...1343machine # [ 37.701322] systemd[563]: Reexecuting.1344machine # [ 37.756165] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1345machine # [ 37.926032] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1346machine # [ 37.933712] systemd[563]: Reload requested from client PID 2247 ('switch-to-confi')...1347machine # [ 37.937940] systemd[563]: Reloading...1348machine # [ 38.056355] systemd[563]: Reloading finished in 119 ms.1349machine # [ 38.072213] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1350machine # [ 38.081452] systemd[563]: Reloading usertest.service...1351machine # [ 38.106429] systemd[563]: Reloaded usertest.service.1352machine # [ 38.113518] systemd[563]: Stopped Run user-specific NixOS activation.1353machine # [ 38.116383] systemd[563]: Stopping Run user-specific NixOS activation...1354machine # [ 38.118930] systemd[563]: Starting Run user-specific NixOS activation...1355machine # [ 38.153710] systemd[563]: Finished Run user-specific NixOS activation.1356machine # [ 38.158120] systemd[1]: Stopped target Reactivate sysinit units.1357machine # [ 38.159326] systemd[1]: Stopping Reactivate sysinit units...1358machine # [ 38.164933] systemd[1]: Reached target Reactivate sysinit units.1359machine # [ 38.165920] systemd[1]: Reached target Remote File Systems.1360machine # [ 38.169802] systemd[1]: Starting linger-users.service...1361machine # [ 38.173728] systemd[1]: Reached target Local File Systems.1362machine # [ 38.181051] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1363machine # [ 38.182602] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1364machine # [ 38.196346] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1365machine # [ 38.198034] systemd[1]: Starting Create SUID/SGID Wrappers...1366machine # [ 38.198893] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1367machine # [ 38.208930] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671368machine # [ 38.211331] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1369machine # [ 38.214237] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1370machine # [ 38.215751] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1371machine # [ 38.278247] systemd[1]: linger-users.service: Deactivated successfully.1372machine # [ 38.280792] systemd[1]: Finished linger-users.service.1373machine # [ 38.426549] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1374machine # [ 38.428823] systemd[1]: Finished Create SUID/SGID Wrappers.1375machine # [ 38.686491] nixos[2178]: finished switching to system configuration /nix/store/81x8xnrhv4jzxhik13w9q6n97dyr4wzc-nixos-system-machine-test1376machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 2.50 seconds)1377machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1378machine # [ 38.737611] sudo[2318]: 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.service1379machine # [ 38.755256] sudo[2318]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1380machine # [ 38.770215] sudo[2318]: pam_unix(sudo:session): session closed for user usertest1381machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1382machine: must fail: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&11383machine # [ 38.980468] nixos[2327]: switching to system configuration /nix/store/r69gvzi0bch6nh2cmy7cpz7vfblhs9jj-nixos-system-machine-test1384machine # [ 38.983465] systemd[1]: Stopped target Local File Systems.1385machine # [ 38.986195] systemd[1]: Stopped target Remote File Systems.1386machine # [ 39.316736] systemd[1]: Reload requested from client PID 2327 ('.switch-to-conf') (unit backdoor.service)...1387machine # [ 39.318860] systemd[1]: Reloading...1388machine # [ 39.672932] systemd[1]: Reloading finished in 352 ms.1389machine # [ 39.773504] systemd[563]: Stopped usertest.service.1390machine # [ 39.777625] systemd[563]: Reexecution requested from client PID 2395 ('switch-to-confi')...1391machine # [ 39.779059] systemd[563]: Reexecuting.1392machine # [ 39.839965] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1393machine # [ 39.965189] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1394machine # [ 39.973353] systemd[563]: Reload requested from client PID 2395 ('switch-to-confi')...1395machine # [ 39.974750] systemd[563]: Reloading...1396machine # [ 40.093683] systemd[563]: Reloading finished in 117 ms.1397machine # [ 40.108214] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1398machine # [ 40.118718] systemd[563]: Starting usertest.service...1399machine # [ 40.145644] systemd[563]: usertest.service: Main process exited, code=exited, status=1/FAILURE1400machine # [ 40.149146] systemd[563]: usertest.service: Failed with result 'exit-code'.1401machine # [ 40.150539] systemd[563]: Failed to start usertest.service.1402machine # [ 40.154858] systemd[563]: Stopped Run user-specific NixOS activation.1403machine # [ 40.155973] systemd[563]: Stopping Run user-specific NixOS activation...1404machine # [ 40.158697] systemd[563]: Starting Run user-specific NixOS activation...1405machine # [ 40.222087] systemd[563]: Finished Run user-specific NixOS activation.1406machine # [ 40.229487] systemd[1]: Stopped target Reactivate sysinit units.1407machine # [ 40.230555] systemd[1]: Stopping Reactivate sysinit units...1408machine # [ 40.231408] systemd[1]: Reached target Reactivate sysinit units.1409machine # [ 40.237014] systemd[1]: Reached target Local File Systems.1410machine # [ 40.239260] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1411machine # [ 40.247838] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1412machine # [ 40.255364] systemd[1]: Reached target Remote File Systems.1413machine # [ 40.256928] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1414machine # [ 40.266451] systemd[1]: Starting Create SUID/SGID Wrappers...1415machine # [ 40.267471] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1416machine # [ 40.279288] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671417machine # [ 40.287772] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1418machine # [ 40.293964] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1419machine # [ 40.301358] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1420machine # [ 40.466991] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1421machine # [ 40.469567] systemd[1]: Finished Create SUID/SGID Wrappers.1422machine # [ 40.475678] systemd[1]: Starting linger-users.service...1423machine # [ 40.522466] systemd[1]: linger-users.service: Deactivated successfully.1424machine # [ 40.525978] systemd[1]: Finished linger-users.service.1425machine # [ 40.780439] nixos[2327]: switching to system configuration /nix/store/r69gvzi0bch6nh2cmy7cpz7vfblhs9jj-nixos-system-machine-test failed (status 4)1426machine: (finished: must fail: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 2.01 seconds)1427machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11428machine # [ 41.014771] nixos[2470]: switching to system configuration /nix/store/qv0brs3kvzm8vysp1wxg2hp56amw3pif-nixos-system-machine-test1429machine # [ 41.020247] systemd[1]: Stopped target Local File Systems.1430machine # [ 41.021214] systemd[1]: Stopped target Remote File Systems.1431machine # [ 41.316689] systemd[1]: Reload requested from client PID 2470 ('.switch-to-conf') (unit backdoor.service)...1432machine # [ 41.318703] systemd[1]: Reloading...1433machine # [ 41.876065] systemd[1]: Reloading finished in 555 ms.1434machine # [ 41.975472] systemd[563]: Reexecution requested from client PID 2538 ('switch-to-confi')...1435machine # [ 41.979855] systemd[563]: Reexecuting.1436machine # [ 42.027724] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1437machine # [ 42.159318] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1438machine # [ 42.168507] systemd[563]: Reload requested from client PID 2538 ('switch-to-confi')...1439machine # [ 42.172346] systemd[563]: Reloading...1440machine # [ 42.298289] systemd[563]: Reloading finished in 125 ms.1441machine # [ 42.312193] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1442machine # [ 42.323742] systemd[563]: Starting usertest.service...1443machine # [ 42.352242] systemd[563]: Finished usertest.service.1444machine # [ 42.355790] systemd[563]: Stopped Run user-specific NixOS activation.1445machine # [ 42.358531] systemd[563]: Stopping Run user-specific NixOS activation...1446machine # [ 42.362854] systemd[563]: Starting Run user-specific NixOS activation...1447machine # [ 42.399864] systemd[563]: Finished Run user-specific NixOS activation.1448machine # [ 42.404959] systemd[1]: Stopped target Reactivate sysinit units.1449machine # [ 42.408321] systemd[1]: Stopping Reactivate sysinit units...1450machine # [ 42.409286] systemd[1]: Reached target Reactivate sysinit units.1451machine # [ 42.412383] systemd[1]: Reached target Remote File Systems.1452machine # [ 42.416620] systemd[1]: Starting linger-users.service...1453machine # [ 42.430518] systemd[1]: Reached target Local File Systems.1454machine # [ 42.431471] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1455machine # [ 42.440863] systemd[1]: Starting Create SUID/SGID Wrappers...1456machine # [ 42.441826] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1457machine # [ 42.443120] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671458machine # [ 42.462784] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1459machine # [ 42.467130] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1460machine # [ 42.472412] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1461machine # [ 42.473917] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1462machine # [ 42.475298] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1463machine # [ 42.530616] systemd[1]: linger-users.service: Deactivated successfully.1464machine # [ 42.533751] systemd[1]: Finished linger-users.service.1465machine # [ 42.685881] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1466machine # [ 42.689312] systemd[1]: Finished Create SUID/SGID Wrappers.1467machine # [ 42.945763] nixos[2470]: finished switching to system configuration /nix/store/qv0brs3kvzm8vysp1wxg2hp56amw3pif-nixos-system-machine-test1468machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.16 seconds)1469machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1470machine # [ 42.994457] sudo[2609]: 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.service1471machine # [ 43.011628] sudo[2609]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1472machine # [ 43.027665] sudo[2609]: pam_unix(sudo:session): session closed for user usertest1473machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1474machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&11475machine # [ 43.230788] nixos[2618]: switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test1476machine # [ 43.235168] systemd[1]: Stopped target Local File Systems.1477machine # [ 43.237457] systemd[1]: Stopped target Remote File Systems.1478machine # [ 43.617489] systemd[1]: Reload requested from client PID 2618 ('.switch-to-conf') (unit backdoor.service)...1479machine # [ 43.619556] systemd[1]: Reloading...1480machine # [ 43.963217] systemd[1]: Reloading finished in 341 ms.1481machine # [ 44.056432] systemd[563]: Stopped usertest.service.1482machine # [ 44.060506] systemd[563]: Reexecution requested from client PID 2688 ('switch-to-confi')...1483machine # [ 44.061978] systemd[563]: Reexecuting.1484machine # [ 44.119414] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1485machine # [ 44.238657] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1486machine # [ 44.246692] systemd[563]: Reload requested from client PID 2688 ('switch-to-confi')...1487machine # [ 44.250873] systemd[563]: Reloading...1488machine # [ 44.366584] systemd[563]: Reloading finished in 116 ms.1489machine # [ 44.384210] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1490machine # [ 44.395670] systemd[563]: Stopped Run user-specific NixOS activation.1491machine # [ 44.398689] systemd[563]: Stopping Run user-specific NixOS activation...1492machine # [ 44.401509] systemd[563]: Starting Run user-specific NixOS activation...1493machine # [ 44.433652] systemd[563]: Finished Run user-specific NixOS activation.1494machine # [ 44.438814] systemd[1]: Stopped target Reactivate sysinit units.1495machine # [ 44.443300] systemd[1]: Stopping Reactivate sysinit units...1496machine # [ 44.446560] systemd[1]: Reached target Reactivate sysinit units.1497machine # [ 44.447516] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1498machine # [ 44.452680] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1499machine # [ 44.454066] systemd[1]: Reached target Local File Systems.1500machine # [ 44.454904] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1501machine # [ 44.472987] systemd[1]: Starting Create SUID/SGID Wrappers...1502machine # [ 44.473939] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1503machine # [ 44.475198] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671504machine # [ 44.480349] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1505machine # [ 44.482031] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1506machine # [ 44.483337] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1507machine # [ 44.484696] systemd[1]: Reached target Remote File Systems.1508machine # [ 44.652206] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1509machine # [ 44.656614] systemd[1]: Finished Create SUID/SGID Wrappers.1510machine # [ 44.660428] systemd[1]: Starting linger-users.service...1511machine # [ 44.703870] systemd[1]: linger-users.service: Deactivated successfully.1512machine # [ 44.706067] systemd[1]: Finished linger-users.service.1513machine # [ 44.963102] nixos[2618]: finished switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test1514machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.94 seconds)1515machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1516machine # [ 45.010298] sudo[2756]: 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.service1517machine # [ 45.027436] sudo[2756]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1518machine # [ 45.043570] sudo[2756]: pam_unix(sudo:session): session closed for user usertest1519machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1520machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1521machine # [ 45.077358] sudo[2762]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1522machine # [ 45.089202] sudo[2762]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1523machine # [ 45.098709] sudo[2762]: pam_unix(sudo:session): session closed for user usertest1524machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.05 seconds)1525machine: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service1526machine # [ 45.128528] sudo[2768]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service /home/usertest/.config/systemd/user/migrated.service1527machine # [ 45.139666] sudo[2768]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1528machine # [ 45.149088] sudo[2768]: pam_unix(sudo:session): session closed for user usertest1529machine: (finished: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1530machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1531machine # [ 45.178588] sudo[2774]: 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.service1532machine # [ 45.189711] sudo[2774]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1533machine # [ 45.198391] sudo[2774]: pam_unix(sudo:session): session closed for user usertest1534machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)1535machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1536machine # [ 45.227743] sudo[2780]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1537machine # [ 45.238187] sudo[2780]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1538machine # [ 45.247403] systemd[563]: Reload requested from client PID 2785 ('systemctl')...1539machine # [ 45.251738] systemd[563]: Reloading...1540machine # [ 45.368964] systemd[563]: Reloading finished in 117 ms.1541machine # [ 45.384207] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1542machine # [ 45.397218] sudo[2780]: pam_unix(sudo:session): session closed for user usertest1543machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.20 seconds)1544machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1545machine # [ 45.428522] sudo[2790]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1546machine # [ 45.439695] sudo[2790]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1547machine # [ 45.454995] systemd[563]: Starting migrated.service...1548machine # [ 45.485884] systemd[563]: Finished migrated.service.1549machine # [ 45.490920] sudo[2790]: pam_unix(sudo:session): session closed for user usertest1550machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)1551machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1552machine # [ 45.523946] sudo[2798]: 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.service1553machine # [ 45.536761] sudo[2798]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1554machine # [ 45.548610] sudo[2798]: pam_unix(sudo:session): session closed for user usertest1555machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)1556machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1557machine # [ 45.581460] sudo[2804]: 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-owner1558machine # [ 45.594729] sudo[2804]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1559machine # [ 45.603760] sudo[2804]: pam_unix(sudo:session): session closed for user usertest1560machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1561machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1562machine # [ 45.635270] sudo[2810]: 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.service1563machine # [ 45.647698] sudo[2810]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1564machine # [ 45.666383] sudo[2810]: pam_unix(sudo:session): session closed for user usertest1565machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)1566machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&11567machine # [ 45.876293] nixos[2819]: switching to system configuration /nix/store/rmr9akxwcv1f99s9pcjqglmwcvb3zb8f-nixos-system-machine-test1568machine # [ 45.883975] systemd[1]: Stopped target Local File Systems.1569machine # [ 45.885218] systemd[1]: Stopped target Remote File Systems.1570machine # [ 46.197412] systemd[1]: Reload requested from client PID 2819 ('.switch-to-conf') (unit backdoor.service)...1571machine # [ 46.199491] systemd[1]: Reloading...1572machine # [ 46.546065] systemd[1]: Reloading finished in 344 ms.1573machine # [ 46.651038] systemd[563]: Reexecution requested from client PID 2888 ('switch-to-confi')...1574machine # [ 46.654827] systemd[563]: Reexecuting.1575machine # [ 46.712869] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1576machine # [ 46.840218] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1577machine # [ 46.847752] systemd[563]: Reload requested from client PID 2888 ('switch-to-confi')...1578machine # [ 46.852461] systemd[563]: Reloading...1579machine # [ 46.971959] systemd[563]: Reloading finished in 120 ms.1580machine # [ 46.988205] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1581machine # [ 46.999880] systemd[563]: Stopped Run user-specific NixOS activation.1582machine # [ 47.002650] systemd[563]: Stopping Run user-specific NixOS activation...1583machine # [ 47.004991] systemd[563]: Starting Run user-specific NixOS activation...1584machine # [ 47.069879] systemd[563]: Reload requested from client PID 2900 ('systemctl') (unit nixos-activation.service)...1585machine # [ 47.071761] systemd[563]: Reloading...1586machine # [ 47.204283] systemd[563]: Reloading finished in 130 ms.1587machine # [ 47.220220] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1588machine # [ 47.243918] systemd[563]: Stopped migrated.service.1589machine # [ 47.257056] systemd[563]: Finished Run user-specific NixOS activation.1590machine # [ 47.260427] systemd[563]: Reload requested from client PID 2888 ('switch-to-confi')...1591machine # [ 47.264274] systemd[563]: Reloading...1592machine # [ 47.382418] systemd[563]: Reloading finished in 118 ms.1593machine # [ 47.400241] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1594machine # [ 47.415117] systemd[563]: Starting migrated.service...1595machine # [ 47.446375] systemd[563]: Finished migrated.service.1596machine # [ 47.451243] systemd[1]: Stopped target Reactivate sysinit units.1597machine # [ 47.456959] systemd[1]: Stopping Reactivate sysinit units...1598machine # [ 47.457856] systemd[1]: Reached target Reactivate sysinit units.1599machine # [ 47.458755] systemd[1]: Reached target Local File Systems.1600machine # [ 47.459602] systemd[1]: Reached target Remote File Systems.1601machine # [ 47.463200] systemd[1]: Starting linger-users.service...1602machine # [ 47.467007] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1603machine # [ 47.476739] systemd[1]: Starting Create SUID/SGID Wrappers...1604machine # [ 47.477706] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1605machine # [ 47.479021] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671606machine # [ 47.498336] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1607machine # [ 47.503641] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1608machine # [ 47.506636] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1609machine # [ 47.507972] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1610machine # [ 47.515312] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1611machine # [ 47.573913] systemd[1]: linger-users.service: Deactivated successfully.1612machine # [ 47.577868] systemd[1]: Finished linger-users.service.1613machine # [ 47.720344] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1614machine # [ 47.723226] systemd[1]: Finished Create SUID/SGID Wrappers.1615machine # [ 47.981901] nixos[2819]: finished switching to system configuration /nix/store/rmr9akxwcv1f99s9pcjqglmwcvb3zb8f-nixos-system-machine-test1616machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 2.32 seconds)1617machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1618machine # [ 48.030808] sudo[2970]: 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.service1619machine # [ 48.050283] sudo[2970]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1620machine # [ 48.066845] sudo[2970]: pam_unix(sudo:session): session closed for user usertest1621machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)1622machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1623machine # [ 48.104834] sudo[2976]: 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.service1624machine # [ 48.117349] sudo[2976]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1625machine # [ 48.138129] sudo[2976]: pam_unix(sudo:session): session closed for user usertest1626machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)1627machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1628machine # [ 48.170236] sudo[2982]: 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-owner1629machine # [ 48.181646] sudo[2982]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1630machine # [ 48.192535] sudo[2982]: pam_unix(sudo:session): session closed for user usertest1631machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1632machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&11633machine # [ 48.386784] nixos[2991]: switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test1634machine # [ 48.389540] systemd[1]: Stopped target Remote File Systems.1635machine # [ 48.392249] systemd[1]: Stopped target Local File Systems.1636machine # [ 48.693121] systemd[1]: Reload requested from client PID 2991 ('.switch-to-conf') (unit backdoor.service)...1637machine # [ 48.695082] systemd[1]: Reloading...1638machine # [ 49.034069] systemd[1]: Reloading finished in 336 ms.1639machine # [ 49.127705] systemd[563]: Stopped migrated.service.1640machine # [ 49.131468] systemd[563]: Reexecution requested from client PID 3060 ('switch-to-confi')...1641machine # [ 49.133489] systemd[563]: Reexecuting.1642machine # [ 49.192163] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1643machine # [ 49.312813] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1644machine # [ 49.320502] systemd[563]: Reload requested from client PID 3060 ('switch-to-confi')...1645machine # [ 49.321860] systemd[563]: Reloading...1646machine # [ 49.437177] systemd[563]: Reloading finished in 114 ms.1647machine # [ 49.453756] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1648machine # [ 49.462701] systemd[563]: Stopped Run user-specific NixOS activation.1649machine # [ 49.465312] systemd[563]: Stopping Run user-specific NixOS activation...1650machine # [ 49.467456] systemd[563]: Starting Run user-specific NixOS activation...1651machine # [ 49.501391] systemd[563]: Finished Run user-specific NixOS activation.1652machine # [ 49.506065] systemd[1]: Stopped target Reactivate sysinit units.1653machine # [ 49.509418] systemd[1]: Stopping Reactivate sysinit units...1654machine # [ 49.510377] systemd[1]: Reached target Reactivate sysinit units.1655machine # [ 49.511241] systemd[1]: Reached target Remote File Systems.1656machine # [ 49.517457] systemd[1]: Reached target Local File Systems.1657machine # [ 49.520646] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1658machine # [ 49.526086] systemd[1]: Starting Create SUID/SGID Wrappers...1659machine # [ 49.527030] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1660machine # [ 49.531184] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671661machine # [ 49.540701] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1662machine # [ 49.542406] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1663machine # [ 49.543816] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1664machine # [ 49.551023] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1665machine # [ 49.552682] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1666machine # [ 49.717950] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1667machine # [ 49.721556] systemd[1]: Finished Create SUID/SGID Wrappers.1668machine # [ 49.726510] systemd[1]: Starting linger-users.service...1669machine # [ 49.772555] systemd[1]: linger-users.service: Deactivated successfully.1670machine # [ 49.774279] systemd[1]: Finished linger-users.service.1671machine # [ 50.031378] nixos[2991]: finished switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test1672machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.84 seconds)1673machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1674machine # [ 50.077501] sudo[3128]: 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.service1675machine # [ 50.094908] sudo[3128]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1676machine # [ 50.110620] sudo[3128]: pam_unix(sudo:session): session closed for user usertest1677machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)1678machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1679machine # [ 50.146514] sudo[3134]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1680machine # [ 50.158834] sudo[3134]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1681machine # [ 50.168925] sudo[3134]: pam_unix(sudo:session): session closed for user usertest1682machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)1683machine: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service1684machine # [ 50.198960] sudo[3140]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service /home/usertest/.config/systemd/user/migrated.service1685machine # [ 50.210157] sudo[3140]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1686machine # [ 50.219162] sudo[3140]: pam_unix(sudo:session): session closed for user usertest1687machine: (finished: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1688machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1689machine # [ 50.248494] sudo[3146]: 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.service1690machine # [ 50.259932] sudo[3146]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1691machine # [ 50.268160] sudo[3146]: pam_unix(sudo:session): session closed for user usertest1692machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)1693machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1694machine # [ 50.297158] sudo[3152]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1695machine # [ 50.307698] sudo[3152]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1696machine # [ 50.317016] systemd[563]: Reload requested from client PID 3157 ('systemctl')...1697machine # [ 50.321495] systemd[563]: Reloading...1698machine # [ 50.436659] systemd[563]: Reloading finished in 116 ms.1699machine # [ 50.452207] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1700machine # [ 50.467266] sudo[3152]: pam_unix(sudo:session): session closed for user usertest1701machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.20 seconds)1702machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1703machine # [ 50.497273] sudo[3162]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1704machine # [ 50.507872] sudo[3162]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1705machine # [ 50.522423] systemd[563]: Starting migrated.service...1706machine # [ 50.552679] systemd[563]: Finished migrated.service.1707machine # [ 50.557511] sudo[3162]: pam_unix(sudo:session): session closed for user usertest1708machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)1709machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1710machine # [ 50.589155] sudo[3170]: 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.service1711machine # [ 50.599964] sudo[3170]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1712machine # [ 50.611388] sudo[3170]: pam_unix(sudo:session): session closed for user usertest1713machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1714machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1715machine # [ 50.641557] sudo[3176]: 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-owner1716machine # [ 50.651990] sudo[3176]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1717machine # [ 50.660961] sudo[3176]: pam_unix(sudo:session): session closed for user usertest1718machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1719machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1720machine # [ 50.690649] sudo[3182]: 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.service1721machine # [ 50.701618] sudo[3182]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1722machine # [ 50.719211] sudo[3182]: pam_unix(sudo:session): session closed for user usertest1723machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)1724machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&11725machine # [ 50.913213] nixos[3191]: switching to system configuration /nix/store/z3zgfx6m02xjifgwkin9b9n5k03zrmh5-nixos-system-machine-test1726machine # [ 50.916414] systemd[1]: Stopped target Remote File Systems.1727machine # [ 50.919048] systemd[1]: Stopped target Local File Systems.1728machine # [ 51.231785] systemd[1]: Reload requested from client PID 3191 ('.switch-to-conf') (unit backdoor.service)...1729machine # [ 51.234227] systemd[1]: Reloading...1730machine # [ 51.624079] systemd[1]: Reloading finished in 388 ms.1731machine # [ 51.724656] systemd[563]: Reexecution requested from client PID 3260 ('switch-to-confi')...1732machine # [ 51.727921] systemd[563]: Reexecuting.1733machine # [ 51.779660] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1734machine # [ 51.899352] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1735machine # [ 51.906355] systemd[563]: Reload requested from client PID 3260 ('switch-to-confi')...1736machine # [ 51.910873] systemd[563]: Reloading...1737machine # [ 52.022840] systemd[563]: Reloading finished in 114 ms.1738machine # [ 52.040192] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1739machine # [ 52.053276] systemd[563]: Stopped Run user-specific NixOS activation.1740machine # [ 52.055932] systemd[563]: Stopping Run user-specific NixOS activation...1741machine # [ 52.058187] systemd[563]: Starting Run user-specific NixOS activation...1742machine # [ 52.098212] systemd[563]: Reload requested from client PID 3272 ('systemctl') (unit nixos-activation.service)...1743machine # [ 52.102839] systemd[563]: Reloading...1744machine # [ 52.224302] systemd[563]: Reloading finished in 122 ms.1745machine # [ 52.240184] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1746machine # [ 52.258945] systemd[563]: Finished Run user-specific NixOS activation.1747machine # [ 52.262113] systemd[563]: Reload requested from client PID 3260 ('switch-to-confi')...1748machine # [ 52.266414] systemd[563]: Reloading...1749machine # [ 52.377627] systemd[563]: Reloading finished in 112 ms.1750machine # [ 52.392178] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1751machine # [ 52.402479] systemd[563]: Stopped migrated.service.1752machine # [ 52.404739] systemd[563]: Stopping migrated.service...1753machine # [ 52.406756] systemd[563]: Starting migrated.service...1754machine # [ 52.435748] systemd[563]: Finished migrated.service.1755machine # [ 52.442872] systemd[1]: Stopped target Reactivate sysinit units.1756machine # [ 52.445237] systemd[1]: Stopping Reactivate sysinit units...1757machine # [ 52.446142] systemd[1]: Reached target Reactivate sysinit units.1758machine # [ 52.448826] systemd[1]: Reached target Local File Systems.1759machine # [ 52.453507] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1760machine # [ 52.464905] systemd[1]: Starting Create SUID/SGID Wrappers...1761machine # [ 52.465900] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1762machine # [ 52.467165] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671763machine # [ 52.475177] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1764machine # [ 52.482282] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1765machine # [ 52.484266] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1766machine # [ 52.488300] systemd[1]: Reached target Remote File Systems.1767machine # [ 52.489189] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1768machine # [ 52.490490] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1769machine # [ 52.649441] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1770machine # [ 52.654575] systemd[1]: Finished Create SUID/SGID Wrappers.1771machine # [ 52.657175] systemd[1]: Starting linger-users.service...1772machine # [ 52.699522] systemd[1]: linger-users.service: Deactivated successfully.1773machine # [ 52.702715] systemd[1]: Finished linger-users.service.1774machine # [ 52.958863] nixos[3191]: finished switching to system configuration /nix/store/z3zgfx6m02xjifgwkin9b9n5k03zrmh5-nixos-system-machine-test1775machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 2.25 seconds)1776machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1777machine # [ 53.008483] sudo[3341]: 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.service1778machine # [ 53.024824] sudo[3341]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1779machine # [ 53.039182] sudo[3341]: pam_unix(sudo:session): session closed for user usertest1780machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)1781machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1782machine # [ 53.075434] sudo[3347]: 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.service1783machine # [ 53.088872] sudo[3347]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1784machine # [ 53.107445] sudo[3347]: pam_unix(sudo:session): session closed for user usertest1785machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)1786machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1787machine # [ 53.138142] sudo[3353]: 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-owner1788machine # [ 53.149899] sudo[3353]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1789machine # [ 53.158312] sudo[3353]: pam_unix(sudo:session): session closed for user usertest1790machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1791machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&11792machine # [ 53.350243] nixos[3362]: switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test1793machine # [ 53.352985] systemd[1]: Stopped target Local File Systems.1794machine # [ 53.355435] systemd[1]: Stopped target Remote File Systems.1795machine # [ 53.660788] systemd[1]: Reload requested from client PID 3362 ('.switch-to-conf') (unit backdoor.service)...1796machine # [ 53.662456] systemd[1]: Reloading...1797machine # [ 54.008913] systemd[1]: Reloading finished in 344 ms.1798machine # [ 54.098857] systemd[563]: Stopped migrated.service.1799machine # [ 54.102765] systemd[563]: Reexecution requested from client PID 3431 ('switch-to-confi')...1800machine # [ 54.105268] systemd[563]: Reexecuting.1801machine # [ 54.163446] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1802machine # [ 54.286298] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1803machine # [ 54.295579] systemd[563]: Reload requested from client PID 3431 ('switch-to-confi')...1804machine # [ 54.299546] systemd[563]: Reloading...1805machine # [ 54.411584] systemd[563]: Reloading finished in 113 ms.1806machine # [ 54.428197] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1807machine # [ 54.438584] systemd[563]: Stopped Run user-specific NixOS activation.1808machine # [ 54.441283] systemd[563]: Stopping Run user-specific NixOS activation...1809machine # [ 54.443481] systemd[563]: Starting Run user-specific NixOS activation...1810machine # [ 54.476972] systemd[563]: Finished Run user-specific NixOS activation.1811machine # [ 54.481337] systemd[1]: Stopped target Reactivate sysinit units.1812machine # [ 54.483978] systemd[1]: Stopping Reactivate sysinit units...1813machine # [ 54.485446] systemd[1]: Reached target Reactivate sysinit units.1814machine # [ 54.490394] systemd[1]: Reached target Local File Systems.1815machine # [ 54.493865] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1816machine # [ 54.498266] systemd[1]: Starting Create SUID/SGID Wrappers...1817machine # [ 54.499185] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1818machine # [ 54.511128] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671819machine # [ 54.518777] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1820machine # [ 54.520899] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1821machine # [ 54.522206] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1822machine # [ 54.523415] systemd[1]: Reached target Remote File Systems.1823machine # [ 54.524279] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1824machine # [ 54.525555] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1825machine # [ 54.689228] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1826machine # [ 54.694832] systemd[1]: Finished Create SUID/SGID Wrappers.1827machine # [ 54.697583] systemd[1]: Starting linger-users.service...1828machine # [ 54.739199] systemd[1]: linger-users.service: Deactivated successfully.1829machine # [ 54.741297] systemd[1]: Finished linger-users.service.1830machine # [ 54.997890] nixos[3362]: finished switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test1831machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.84 seconds)1832machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1833machine # [ 55.043284] sudo[3499]: 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.service1834machine # [ 55.059534] sudo[3499]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1835machine # [ 55.075272] sudo[3499]: pam_unix(sudo:session): session closed for user usertest1836machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1837machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1838machine # [ 55.108798] sudo[3505]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1839machine # [ 55.120869] sudo[3505]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1840machine # [ 55.129679] sudo[3505]: pam_unix(sudo:session): session closed for user usertest1841machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.05 seconds)1842machine: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service1843machine # [ 55.161226] sudo[3511]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service /home/usertest/.config/systemd/user/migrated.service1844machine # [ 55.173493] sudo[3511]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1845machine # [ 55.182026] sudo[3511]: pam_unix(sudo:session): session closed for user usertest1846machine: (finished: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1847machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1848machine # [ 55.213945] sudo[3517]: 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.service1849machine # [ 55.225046] sudo[3517]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1850machine # [ 55.233622] sudo[3517]: pam_unix(sudo:session): session closed for user usertest1851machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)1852machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1853machine # [ 55.262081] sudo[3523]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1854machine # [ 55.272996] sudo[3523]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1855machine # [ 55.281992] systemd[563]: Reload requested from client PID 3528 ('systemctl')...1856machine # [ 55.286369] systemd[563]: Reloading...1857machine # [ 55.405591] systemd[563]: Reloading finished in 120 ms.1858machine # [ 55.420190] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1859machine # [ 55.431154] sudo[3523]: pam_unix(sudo:session): session closed for user usertest1860machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.20 seconds)1861machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1862machine # [ 55.461273] sudo[3533]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1863machine # [ 55.471657] sudo[3533]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1864machine # [ 55.485678] systemd[563]: Starting migrated.service...1865machine # [ 55.515775] systemd[563]: Finished migrated.service.1866machine # [ 55.520744] sudo[3533]: pam_unix(sudo:session): session closed for user usertest1867machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)1868machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1869machine # [ 55.549654] sudo[3541]: 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.service1870machine # [ 55.560006] sudo[3541]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1871machine # [ 55.571509] sudo[3541]: pam_unix(sudo:session): session closed for user usertest1872machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1873machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1874machine # [ 55.600643] sudo[3547]: 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-owner1875machine # [ 55.610688] sudo[3547]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1876machine # [ 55.619159] sudo[3547]: pam_unix(sudo:session): session closed for user usertest1877machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1878machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1879machine # [ 55.647334] sudo[3553]: 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.service1880machine # [ 55.657887] sudo[3553]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1881machine # [ 55.674748] sudo[3553]: pam_unix(sudo:session): session closed for user usertest1882machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)1883machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&11884machine # [ 55.867342] nixos[3562]: switching to system configuration /nix/store/88m25jwflbl90l71v3zn23xknimwl4r7-nixos-system-machine-test1885machine # [ 55.870388] systemd[1]: Stopped target Remote File Systems.1886machine # [ 55.873011] systemd[1]: Stopped target Local File Systems.1887machine # [ 56.172755] systemd[1]: Reload requested from client PID 3562 ('.switch-to-conf') (unit backdoor.service)...1888machine # [ 56.174754] systemd[1]: Reloading...1889machine # [ 56.502795] systemd[1]: Reloading finished in 326 ms.1890machine # [ 56.598958] systemd[563]: Reexecution requested from client PID 3631 ('switch-to-confi')...1891machine # [ 56.602878] systemd[563]: Reexecuting.1892machine # [ 56.655766] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1893machine # [ 56.777579] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1894machine # [ 56.784757] systemd[563]: Reload requested from client PID 3631 ('switch-to-confi')...1895machine # [ 56.788292] systemd[563]: Reloading...1896machine # [ 56.901691] systemd[563]: Reloading finished in 114 ms.1897machine # [ 56.916199] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1898machine # [ 56.926873] systemd[563]: Stopped Run user-specific NixOS activation.1899machine # [ 56.929480] systemd[563]: Stopping Run user-specific NixOS activation...1900machine # [ 56.931585] systemd[563]: Starting Run user-specific NixOS activation...1901machine # [ 56.970500] systemd[563]: Reload requested from client PID 3643 ('systemctl') (unit nixos-activation.service)...1902machine # [ 56.974944] systemd[563]: Reloading...1903machine # [ 57.094062] systemd[563]: Reloading finished in 120 ms.1904machine # [ 57.112183] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1905machine # [ 57.132510] systemd[563]: Finished Run user-specific NixOS activation.1906machine # [ 57.135698] systemd[563]: Reload requested from client PID 3631 ('switch-to-confi')...1907machine # [ 57.139973] systemd[563]: Reloading...1908machine # [ 57.254060] systemd[563]: Reloading finished in 115 ms.1909machine # [ 57.268173] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1910machine # [ 57.279714] systemd[563]: Reloading migrated.service...1911machine # [ 57.306048] systemd[563]: Reloaded migrated.service.1912machine # [ 57.313744] systemd[1]: Stopped target Reactivate sysinit units.1913machine # [ 57.320422] systemd[1]: Stopping Reactivate sysinit units...1914machine # [ 57.321351] systemd[1]: Reached target Reactivate sysinit units.1915machine # [ 57.322210] systemd[1]: Reached target Local File Systems.1916machine # [ 57.323010] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1917machine # [ 57.327081] systemd[1]: Starting Create SUID/SGID Wrappers...1918machine # [ 57.334024] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1919machine # [ 57.335357] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671920machine # [ 57.345424] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1921machine # [ 57.347076] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1922machine # [ 57.354464] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1923machine # [ 57.357838] systemd[1]: Reached target Remote File Systems.1924machine # [ 57.360093] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1925machine # [ 57.361446] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1926machine # [ 57.526281] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1927machine # [ 57.531298] systemd[1]: Finished Create SUID/SGID Wrappers.1928machine # [ 57.534743] systemd[1]: Starting linger-users.service...1929machine # [ 57.583865] systemd[1]: linger-users.service: Deactivated successfully.1930machine # [ 57.587061] systemd[1]: Finished linger-users.service.1931machine # [ 57.843079] nixos[3562]: finished switching to system configuration /nix/store/88m25jwflbl90l71v3zn23xknimwl4r7-nixos-system-machine-test1932machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 2.17 seconds)1933machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1934machine # [ 57.888993] sudo[3712]: 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.service1935machine # [ 57.905945] sudo[3712]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1936machine # [ 57.921464] sudo[3712]: pam_unix(sudo:session): session closed for user usertest1937machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1938machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1939machine # [ 57.958887] sudo[3718]: 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-owner1940machine # [ 57.972737] sudo[3718]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1941machine # [ 57.981390] sudo[3718]: pam_unix(sudo:session): session closed for user usertest1942machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)1943machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&11944machine # [ 58.173722] nixos[3727]: switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test1945machine # [ 58.176505] systemd[1]: Stopped target Remote File Systems.1946machine # [ 58.179336] systemd[1]: Stopped target Local File Systems.1947machine # [ 58.458233] systemd[1]: Reload requested from client PID 3727 ('.switch-to-conf') (unit backdoor.service)...1948machine # [ 58.460322] systemd[1]: Reloading...1949machine # [ 58.783624] systemd[1]: Reloading finished in 321 ms.1950machine # [ 58.874401] systemd[563]: Stopped migrated.service.1951machine # [ 58.878195] systemd[563]: Reexecution requested from client PID 3796 ('switch-to-confi')...1952machine # [ 58.879599] systemd[563]: Reexecuting.1953machine # [ 58.940108] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1954machine # [ 59.065326] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1955machine # [ 59.072541] systemd[563]: Reload requested from client PID 3796 ('switch-to-confi')...1956machine # [ 59.073895] systemd[563]: Reloading...1957machine # [ 59.185337] systemd[563]: Reloading finished in 109 ms.1958machine # [ 59.200162] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1959machine # [ 59.210771] systemd[563]: Stopped Run user-specific NixOS activation.1960machine # [ 59.213134] systemd[563]: Stopping Run user-specific NixOS activation...1961machine # [ 59.215230] systemd[563]: Starting Run user-specific NixOS activation...1962machine # [ 59.248110] systemd[563]: Finished Run user-specific NixOS activation.1963machine # [ 59.251987] systemd[1]: Stopped target Reactivate sysinit units.1964machine # [ 59.253541] systemd[1]: Stopping Reactivate sysinit units...1965machine # [ 59.254400] systemd[1]: Reached target Reactivate sysinit units.1966machine # [ 59.259138] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1967machine # [ 59.264943] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1968machine # [ 59.266318] systemd[1]: Reached target Local File Systems.1969machine # [ 59.267156] systemd[1]: Reached target Remote File Systems.1970machine # [ 59.272505] systemd[1]: Starting linger-users.service...1971machine # [ 59.283215] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1972machine # [ 59.290766] systemd[1]: Starting Create SUID/SGID Wrappers...1973machine # [ 59.291722] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1974machine # [ 59.300116] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671975machine # [ 59.302448] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1976machine # [ 59.311807] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1977machine # [ 59.314548] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1978machine # [ 59.356932] systemd[1]: linger-users.service: Deactivated successfully.1979machine # [ 59.359063] systemd[1]: Finished linger-users.service.1980machine # [ 59.495003] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1981machine # [ 59.498421] systemd[1]: Finished Create SUID/SGID Wrappers.1982machine # [ 59.752428] nixos[3727]: finished switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test1983machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.77 seconds)1984machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1985machine # [ 59.798727] sudo[3864]: 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.service1986machine # [ 59.815709] sudo[3864]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1987machine # [ 59.832124] sudo[3864]: pam_unix(sudo:session): session closed for user usertest1988machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)1989machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1990machine # [ 59.869515] sudo[3870]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1991machine # [ 59.882903] sudo[3870]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1992machine # [ 59.893026] sudo[3870]: pam_unix(sudo:session): session closed for user usertest1993machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)1994machine: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service1995machine # [ 59.925942] sudo[3876]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service /home/usertest/.config/systemd/user/migrated.service1996machine # [ 59.937313] sudo[3876]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1997machine # [ 59.945605] sudo[3876]: pam_unix(sudo:session): session closed for user usertest1998machine: (finished: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1999machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2000machine # [ 59.973933] sudo[3882]: 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.service2001machine # [ 59.984779] sudo[3882]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2002machine # [ 59.992641] sudo[3882]: pam_unix(sudo:session): session closed for user usertest2003machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)2004machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2005machine # [ 60.021141] sudo[3888]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2006machine # [ 60.031153] sudo[3888]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2007machine # [ 60.039814] systemd[563]: Reload requested from client PID 3893 ('systemctl')...2008machine # [ 60.043979] systemd[563]: Reloading...2009machine # [ 60.157630] systemd[563]: Reloading finished in 115 ms.2010machine # [ 60.172171] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2011machine # [ 60.187235] sudo[3888]: pam_unix(sudo:session): session closed for user usertest2012machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)2013machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2014machine # [ 60.215625] sudo[3898]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2015machine # [ 60.226122] sudo[3898]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2016machine # [ 60.239665] systemd[563]: Starting migrated.service...2017machine # [ 60.268073] systemd[563]: Finished migrated.service.2018machine # [ 60.272190] sudo[3898]: pam_unix(sudo:session): session closed for user usertest2019machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)2020machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2021machine # [ 60.301583] sudo[3906]: 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.service2022machine # [ 60.311736] sudo[3906]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2023machine # [ 60.322551] sudo[3906]: pam_unix(sudo:session): session closed for user usertest2024machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)2025machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2026machine # [ 60.350894] sudo[3912]: 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-owner2027machine # [ 60.361724] sudo[3912]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2028machine # [ 60.369775] sudo[3912]: pam_unix(sudo:session): session closed for user usertest2029machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2030machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2031machine # [ 60.397418] sudo[3918]: 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.service2032machine # [ 60.409053] sudo[3918]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2033machine # [ 60.425240] sudo[3918]: pam_unix(sudo:session): session closed for user usertest2034machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)2035machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&12036machine # [ 60.617617] nixos[3927]: switching to system configuration /nix/store/n7hajgqqlgl59g7hx0dr26pa2gpkara3-nixos-system-machine-test2037machine # [ 60.620495] systemd[1]: Stopped target Remote File Systems.2038machine # [ 60.622910] systemd[1]: Stopped target Local File Systems.2039machine # [ 60.930985] systemd[1]: Reload requested from client PID 3927 ('.switch-to-conf') (unit backdoor.service)...2040machine # [ 60.933120] systemd[1]: Reloading...2041machine # [ 61.257548] systemd[1]: Reloading finished in 323 ms.2042machine # [ 61.352550] systemd[563]: Reexecution requested from client PID 3996 ('switch-to-confi')...2043machine # [ 61.356699] systemd[563]: Reexecuting.2044machine # [ 61.403611] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2045machine # [ 61.522509] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2046machine # [ 61.529211] systemd[563]: Reload requested from client PID 3996 ('switch-to-confi')...2047machine # [ 61.532076] systemd[563]: Reloading...2048machine # [ 61.643236] systemd[563]: Reloading finished in 111 ms.2049machine # [ 61.660180] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2050machine # [ 61.671519] systemd[563]: Stopped Run user-specific NixOS activation.2051machine # [ 61.674008] systemd[563]: Stopping Run user-specific NixOS activation...2052machine # [ 61.676157] systemd[563]: Starting Run user-specific NixOS activation...2053machine # [ 61.712902] systemd[563]: Reload requested from client PID 4008 ('systemctl') (unit nixos-activation.service)...2054machine # [ 61.715939] systemd[563]: Reloading...2055machine # [ 61.836480] systemd[563]: Reloading finished in 119 ms.2056machine # [ 61.852179] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2057machine # [ 61.870704] systemd[563]: Finished Run user-specific NixOS activation.2058machine # [ 61.874060] systemd[563]: Reload requested from client PID 3996 ('switch-to-confi')...2059machine # [ 61.875417] systemd[563]: Reloading...2060machine # [ 61.991868] systemd[563]: Reloading finished in 114 ms.2061machine # [ 62.008178] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2062machine # [ 62.023054] systemd[1]: Stopped target Reactivate sysinit units.2063machine # [ 62.025218] systemd[1]: Stopping Reactivate sysinit units...2064machine # [ 62.026095] systemd[1]: Reached target Reactivate sysinit units.2065machine # [ 62.030361] systemd[1]: Reached target Local File Systems.2066machine # [ 62.031262] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2067machine # [ 62.041316] systemd[1]: Starting Create SUID/SGID Wrappers...2068machine # [ 62.042285] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2069machine # [ 62.043560] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672070machine # [ 62.057158] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2071machine # [ 62.058843] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2072machine # [ 62.061017] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2073machine # [ 62.062273] systemd[1]: Reached target Remote File Systems.2074machine # [ 62.063064] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2075machine # [ 62.064857] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2076machine # [ 62.223382] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2077machine # [ 62.228299] systemd[1]: Finished Create SUID/SGID Wrappers.2078machine # [ 62.230927] systemd[1]: Starting linger-users.service...2079machine # [ 62.274953] systemd[1]: linger-users.service: Deactivated successfully.2080machine # [ 62.277811] systemd[1]: Finished linger-users.service.2081machine # [ 62.534701] nixos[3927]: finished switching to system configuration /nix/store/n7hajgqqlgl59g7hx0dr26pa2gpkara3-nixos-system-machine-test2082machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 2.11 seconds)2083machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2084machine # [ 62.582599] sudo[4075]: 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.service2085machine # [ 62.600779] sudo[4075]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2086machine # [ 62.616350] sudo[4075]: pam_unix(sudo:session): session closed for user usertest2087machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)2088machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2089machine # [ 62.652551] sudo[4081]: 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-owner2090machine # [ 62.663605] sudo[4081]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2091machine # [ 62.671544] sudo[4081]: pam_unix(sudo:session): session closed for user usertest2092machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2093machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&12094machine # [ 62.863077] nixos[4090]: switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test2095machine # [ 62.865739] systemd[1]: Stopped target Local File Systems.2096machine # [ 62.868242] systemd[1]: Stopped target Remote File Systems.2097machine # [ 63.166631] systemd[1]: Reload requested from client PID 4090 ('.switch-to-conf') (unit backdoor.service)...2098machine # [ 63.168845] systemd[1]: Reloading...2099machine # [ 63.498715] systemd[1]: Reloading finished in 328 ms.2100machine # [ 63.591601] systemd[563]: Stopped migrated.service.2101machine # [ 63.595661] systemd[563]: Reexecution requested from client PID 4159 ('switch-to-confi')...2102machine # [ 63.597491] systemd[563]: Reexecuting.2103machine # [ 63.655343] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2104machine # [ 63.777202] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2105machine # [ 63.783939] systemd[563]: Reload requested from client PID 4159 ('switch-to-confi')...2106machine # [ 63.786920] systemd[563]: Reloading...2107machine # [ 63.900619] systemd[563]: Reloading finished in 112 ms.2108machine # [ 63.916192] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2109machine # [ 63.925985] systemd[563]: Stopped Run user-specific NixOS activation.2110machine # [ 63.928642] systemd[563]: Stopping Run user-specific NixOS activation...2111machine # [ 63.930904] systemd[563]: Starting Run user-specific NixOS activation...2112machine # [ 63.964368] systemd[563]: Finished Run user-specific NixOS activation.2113machine # [ 63.968943] systemd[1]: Stopped target Reactivate sysinit units.2114machine # [ 63.971761] systemd[1]: Stopping Reactivate sysinit units...2115machine # [ 63.973916] systemd[1]: Reached target Reactivate sysinit units.2116machine # [ 63.974800] systemd[1]: Reached target Remote File Systems.2117machine # [ 63.980163] systemd[1]: Reached target Local File Systems.2118machine # [ 63.981069] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2119machine # [ 63.986429] systemd[1]: Starting Create SUID/SGID Wrappers...2120machine # [ 63.987335] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2121machine # [ 63.997510] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672122machine # [ 63.999896] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2123machine # [ 64.008884] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2124machine # [ 64.010226] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2125machine # [ 64.011492] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2126machine # [ 64.012979] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2127machine # [ 64.178469] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2128machine # [ 64.183887] systemd[1]: Finished Create SUID/SGID Wrappers.2129machine # [ 64.186325] systemd[1]: Starting linger-users.service...2130machine # [ 64.230965] systemd[1]: linger-users.service: Deactivated successfully.2131machine # [ 64.233155] systemd[1]: Finished linger-users.service.2132machine # [ 64.489728] nixos[4090]: finished switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test2133machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.82 seconds)2134machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2135machine # [ 64.535279] sudo[4227]: 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.service2136machine # [ 64.551525] sudo[4227]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2137machine # [ 64.566143] sudo[4227]: pam_unix(sudo:session): session closed for user usertest2138machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2139machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user2140machine # [ 64.597830] sudo[4233]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user2141machine # [ 64.609032] sudo[4233]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2142machine # [ 64.617906] sudo[4233]: pam_unix(sudo:session): session closed for user usertest2143machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.05 seconds)2144machine: must succeed: sudo -u usertest cp /nix/store/gj8i3dxm5jb7nzvvpbkliw0syyzbr40h-migrated.service ~usertest/.local/share/systemd/user/migrated.service2145machine # [ 64.646897] sudo[4239]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/gj8i3dxm5jb7nzvvpbkliw0syyzbr40h-migrated.service /home/usertest/.local/share/systemd/user/migrated.service2146machine # [ 64.657712] sudo[4239]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2147machine # [ 64.666366] sudo[4239]: pam_unix(sudo:session): session closed for user usertest2148machine: (finished: must succeed: sudo -u usertest cp /nix/store/gj8i3dxm5jb7nzvvpbkliw0syyzbr40h-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.05 seconds)2149machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2150machine # [ 64.693986] sudo[4245]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2151machine # [ 64.704875] sudo[4245]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2152machine # [ 64.713109] systemd[563]: Reload requested from client PID 4250 ('systemctl')...2153machine # [ 64.716287] systemd[563]: Reloading...2154machine # [ 64.833015] systemd[563]: Reloading finished in 117 ms.2155machine # [ 64.848197] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2156machine # [ 64.861341] sudo[4245]: pam_unix(sudo:session): session closed for user usertest2157machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.20 seconds)2158machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2159machine # [ 64.890876] sudo[4255]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2160machine # [ 64.901744] sudo[4255]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2161machine # [ 64.917400] systemd[563]: Starting migrated.service...2162machine # [ 64.943153] systemd[563]: Finished migrated.service.2163machine # [ 64.947788] sudo[4255]: pam_unix(sudo:session): session closed for user usertest2164machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)2165machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2166machine # [ 64.977177] sudo[4263]: 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.service2167machine # [ 64.987613] sudo[4263]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2168machine # [ 64.998945] sudo[4263]: pam_unix(sudo:session): session closed for user usertest2169machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)2170machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2171machine # [ 65.027527] sudo[4269]: 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-owner2172machine # [ 65.037858] sudo[4269]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2173machine # [ 65.045701] sudo[4269]: pam_unix(sudo:session): session closed for user usertest2174machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2175machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2176machine # [ 65.073586] sudo[4275]: 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.service2177machine # [ 65.084002] sudo[4275]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2178machine # [ 65.106670] sudo[4275]: pam_unix(sudo:session): session closed for user usertest2179machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)2180machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12181machine # [ 65.297141] nixos[4284]: switching to system configuration /nix/store/k8466374gshrcrcbr40bnp3rr5p26zl4-nixos-system-machine-test2182machine # [ 65.300508] systemd[1]: Stopped target Local File Systems.2183machine # [ 65.302706] systemd[1]: Stopped target Remote File Systems.2184machine # [ 65.602538] systemd[1]: Reload requested from client PID 4284 ('.switch-to-conf') (unit backdoor.service)...2185machine # [ 65.604626] systemd[1]: Reloading...2186machine # [ 65.927705] systemd[1]: Reloading finished in 321 ms.2187machine # [ 66.024444] systemd[563]: Reexecution requested from client PID 4353 ('switch-to-confi')...2188machine # [ 66.027629] systemd[563]: Reexecuting.2189machine # [ 66.084436] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2190machine # [ 66.209050] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2191machine # [ 66.216792] systemd[563]: Reload requested from client PID 4353 ('switch-to-confi')...2192machine # [ 66.218168] systemd[563]: Reloading...2193machine # [ 66.332633] systemd[563]: Reloading finished in 113 ms.2194machine # [ 66.348187] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2195machine # [ 66.358462] systemd[563]: Stopped Run user-specific NixOS activation.2196machine # [ 66.360962] systemd[563]: Stopping Run user-specific NixOS activation...2197machine # [ 66.363104] systemd[563]: Starting Run user-specific NixOS activation...2198machine # [ 66.396914] systemd[563]: Finished Run user-specific NixOS activation.2199machine # [ 66.399566] systemd[563]: Reload requested from client PID 4353 ('switch-to-confi')...2200machine # [ 66.403328] systemd[563]: Reloading...2201machine # [ 66.517767] systemd[563]: Reloading finished in 115 ms.2202machine # [ 66.536189] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2203machine # [ 66.546627] systemd[563]: Stopped migrated.service.2204machine # [ 66.547610] systemd[563]: Stopping migrated.service...2205machine # [ 66.550113] systemd[563]: Starting migrated.service...2206machine # [ 66.575180] systemd[563]: Finished migrated.service.2207machine # [ 66.582074] systemd[1]: Stopped target Reactivate sysinit units.2208machine # [ 66.588827] systemd[1]: Stopping Reactivate sysinit units...2209machine # [ 66.589763] systemd[1]: Reached target Reactivate sysinit units.2210machine # [ 66.590660] systemd[1]: Reached target Local File Systems.2211machine # [ 66.591545] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2212machine # [ 66.595706] systemd[1]: Starting Create SUID/SGID Wrappers...2213machine # [ 66.600712] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2214machine # [ 66.602058] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672215machine # [ 66.616475] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2216machine # [ 66.618192] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2217machine # [ 66.619632] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2218machine # [ 66.621902] systemd[1]: Reached target Remote File Systems.2219machine # [ 66.622791] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2220machine # [ 66.625495] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2221machine # [ 66.782241] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2222machine # [ 66.784678] systemd[1]: Finished Create SUID/SGID Wrappers.2223machine # [ 66.789718] systemd[1]: Starting linger-users.service...2224machine # [ 66.835187] systemd[1]: linger-users.service: Deactivated successfully.2225machine # [ 66.839338] systemd[1]: Finished linger-users.service.2226machine # [ 67.095013] nixos[4284]: finished switching to system configuration /nix/store/k8466374gshrcrcbr40bnp3rr5p26zl4-nixos-system-machine-test2227machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.99 seconds)2228machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2229machine # [ 67.140363] sudo[4427]: 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.service2230machine # [ 67.157417] sudo[4427]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2231machine # [ 67.173607] sudo[4427]: pam_unix(sudo:session): session closed for user usertest2232machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2233machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2234machine # [ 67.210826] sudo[4433]: 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.service2235machine # [ 67.223566] sudo[4433]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2236machine # [ 67.243133] sudo[4433]: pam_unix(sudo:session): session closed for user usertest2237machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)2238machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2239machine # [ 67.272727] sudo[4439]: 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-owner2240machine # [ 67.283291] sudo[4439]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2241machine # [ 67.291804] sudo[4439]: pam_unix(sudo:session): session closed for user usertest2242machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2243machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12244machine # [ 67.477945] nixos[4448]: switching to system configuration /nix/store/k8466374gshrcrcbr40bnp3rr5p26zl4-nixos-system-machine-test2245machine # [ 67.480574] systemd[1]: Stopped target Local File Systems.2246machine # [ 67.483434] systemd[1]: Stopped target Remote File Systems.2247machine # [ 67.765213] systemd[1]: Reload requested from client PID 4448 ('.switch-to-conf') (unit backdoor.service)...2248machine # [ 67.766859] systemd[1]: Reloading...2249machine # [ 68.093323] systemd[1]: Reloading finished in 325 ms.2250machine # [ 68.185844] systemd[563]: Reexecution requested from client PID 4517 ('switch-to-confi')...2251machine # [ 68.190116] systemd[563]: Reexecuting.2252machine # [ 68.239091] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2253machine # [ 68.358924] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2254machine # [ 68.365986] systemd[563]: Reload requested from client PID 4517 ('switch-to-confi')...2255machine # [ 68.369683] systemd[563]: Reloading...2256machine # [ 68.482291] systemd[563]: Reloading finished in 113 ms.2257machine # [ 68.500184] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2258machine # [ 68.510622] systemd[563]: Stopped Run user-specific NixOS activation.2259machine # [ 68.513252] systemd[563]: Stopping Run user-specific NixOS activation...2260machine # [ 68.515383] systemd[563]: Starting Run user-specific NixOS activation...2261machine # [ 68.548117] systemd[563]: Finished Run user-specific NixOS activation.2262machine # [ 68.552485] systemd[1]: Stopped target Reactivate sysinit units.2263machine # [ 68.555157] systemd[1]: Stopping Reactivate sysinit units...2264machine # [ 68.556812] systemd[1]: Reached target Reactivate sysinit units.2265machine # [ 68.559151] systemd[1]: Reached target Remote File Systems.2266machine # [ 68.561061] systemd[1]: Reached target Local File Systems.2267machine # [ 68.566532] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2268machine # [ 68.567999] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2269machine # [ 68.571815] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2270machine # [ 68.579998] systemd[1]: Starting Create SUID/SGID Wrappers...2271machine # [ 68.581460] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2272machine # [ 68.582706] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672273machine # [ 68.591460] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2274machine # [ 68.593201] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2275machine # [ 68.594525] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2276machine # [ 68.751891] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2277machine # [ 68.753871] systemd[1]: Finished Create SUID/SGID Wrappers.2278machine # [ 68.759152] systemd[1]: Starting linger-users.service...2279machine # [ 68.803620] systemd[1]: linger-users.service: Deactivated successfully.2280machine # [ 68.806028] systemd[1]: Finished linger-users.service.2281machine # [ 69.062801] nixos[4448]: finished switching to system configuration /nix/store/k8466374gshrcrcbr40bnp3rr5p26zl4-nixos-system-machine-test2282machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.78 seconds)2283machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd2284machine # [ 69.107850] sudo[4585]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd2285machine # [ 69.123910] sudo[4585]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2286machine # [ 69.135647] sudo[4585]: pam_unix(sudo:session): session closed for user usertest2287machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.07 seconds)2288machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2289machine # [ 69.171273] sudo[4591]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2290machine # [ 69.183966] sudo[4591]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2291machine # [ 69.194069] systemd[563]: Reload requested from client PID 4596 ('systemctl')...2292machine # [ 69.198419] systemd[563]: Reloading...2293machine # [ 69.313999] systemd[563]: Reloading finished in 116 ms.2294machine # [ 69.328179] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2295machine # [ 69.342936] sudo[4591]: pam_unix(sudo:session): session closed for user usertest2296machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.21 seconds)2297machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&12298machine # [ 69.529837] nixos[4604]: switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test2299machine # [ 69.534499] systemd[1]: Stopped target Local File Systems.2300machine # [ 69.535679] systemd[1]: Stopped target Remote File Systems.2301machine # [ 69.814406] systemd[1]: Reload requested from client PID 4604 ('.switch-to-conf') (unit backdoor.service)...2302machine # [ 69.816547] systemd[1]: Reloading...2303machine # [ 70.140367] systemd[1]: Reloading finished in 322 ms.2304machine # [ 70.229812] systemd[563]: Stopped migrated.service.2305machine # [ 70.233831] systemd[563]: Reexecution requested from client PID 4673 ('switch-to-confi')...2306machine # [ 70.235189] systemd[563]: Reexecuting.2307machine # [ 70.283183] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2308machine # [ 70.397833] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2309machine # [ 70.405482] systemd[563]: Reload requested from client PID 4673 ('switch-to-confi')...2310machine # [ 70.409172] systemd[563]: Reloading...2311machine # [ 70.519235] systemd[563]: Reloading finished in 111 ms.2312machine # [ 70.536182] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2313machine # [ 70.548127] systemd[563]: Stopped Run user-specific NixOS activation.2314machine # [ 70.549169] systemd[563]: Stopping Run user-specific NixOS activation...2315machine # [ 70.552256] systemd[563]: Starting Run user-specific NixOS activation...2316machine # [ 70.585659] systemd[563]: Finished Run user-specific NixOS activation.2317machine # [ 70.589660] systemd[1]: Stopped target Reactivate sysinit units.2318machine # [ 70.591802] systemd[1]: Stopping Reactivate sysinit units...2319machine # [ 70.596801] systemd[1]: Reached target Reactivate sysinit units.2320machine # [ 70.597730] systemd[1]: Reached target Remote File Systems.2321machine # [ 70.600101] systemd[1]: Starting linger-users.service...2322machine # [ 70.603509] systemd[1]: Reached target Local File Systems.2323machine # [ 70.609347] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2324machine # [ 70.610707] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2325machine # [ 70.621986] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2326machine # [ 70.630019] systemd[1]: Starting Create SUID/SGID Wrappers...2327machine # [ 70.636543] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2328machine # [ 70.637910] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672329machine # [ 70.645789] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2330machine # [ 70.647529] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2331machine # [ 70.652494] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2332machine # [ 70.696877] systemd[1]: linger-users.service: Deactivated successfully.2333machine # [ 70.701803] systemd[1]: Finished linger-users.service.2334machine # [ 70.836641] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2335machine # [ 70.840385] systemd[1]: Finished Create SUID/SGID Wrappers.2336machine # [ 71.094473] nixos[4604]: finished switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test2337machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.76 seconds)2338machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2339machine # [ 71.139607] sudo[4741]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2340machine # [ 71.156637] sudo[4741]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2341machine # [ 71.166047] sudo[4741]: pam_unix(sudo:session): session closed for user usertest2342machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.07 seconds)2343machine: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service2344machine # [ 71.200589] sudo[4747]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service /home/usertest/.config/systemd/user/migrated.service2345machine # [ 71.213389] sudo[4747]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2346machine # [ 71.222292] sudo[4747]: pam_unix(sudo:session): session closed for user usertest2347machine: (finished: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.06 seconds)2348machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2349machine # [ 71.251292] sudo[4753]: 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.service2350machine # [ 71.261904] sudo[4753]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2351machine # [ 71.269956] sudo[4753]: pam_unix(sudo:session): session closed for user usertest2352machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)2353machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2354machine # [ 71.297976] sudo[4759]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2355machine # [ 71.308911] sudo[4759]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2356machine # [ 71.317187] systemd[563]: Reload requested from client PID 4764 ('systemctl')...2357machine # [ 71.320440] systemd[563]: Reloading...2358machine # [ 71.437013] systemd[563]: Reloading finished in 117 ms.2359machine # [ 71.452195] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2360machine # [ 71.462292] sudo[4759]: pam_unix(sudo:session): session closed for user usertest2361machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)2362machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2363machine # [ 71.490932] sudo[4769]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2364machine # [ 71.501311] sudo[4769]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2365machine # [ 71.515588] systemd[563]: Starting migrated.service...2366machine # [ 71.543026] systemd[563]: Finished migrated.service.2367machine # [ 71.547801] sudo[4769]: pam_unix(sudo:session): session closed for user usertest2368machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)2369machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2370machine # [ 71.575603] sudo[4777]: 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.service2371machine # [ 71.586547] sudo[4777]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2372machine # [ 71.596503] sudo[4777]: pam_unix(sudo:session): session closed for user usertest2373machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)2374machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2375machine # [ 71.624699] sudo[4783]: 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-owner2376machine # [ 71.634637] sudo[4783]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2377machine # [ 71.643082] sudo[4783]: pam_unix(sudo:session): session closed for user usertest2378machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2379machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2380machine # [ 71.670358] sudo[4789]: 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.service2381machine # [ 71.681015] sudo[4789]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2382machine # [ 71.697849] sudo[4789]: pam_unix(sudo:session): session closed for user usertest2383machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)2384machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12385machine # [ 71.892716] nixos[4798]: switching to system configuration /nix/store/k8466374gshrcrcbr40bnp3rr5p26zl4-nixos-system-machine-test2386machine # [ 71.897054] systemd[1]: Stopped target Remote File Systems.2387machine # [ 71.897986] systemd[1]: Stopped target Local File Systems.2388machine # [ 72.179505] systemd[1]: Reload requested from client PID 4798 ('.switch-to-conf') (unit backdoor.service)...2389machine # [ 72.181575] systemd[1]: Reloading...2390machine # [ 72.507356] systemd[1]: Reloading finished in 324 ms.2391machine # [ 72.596342] systemd[563]: Reexecution requested from client PID 4867 ('switch-to-confi')...2392machine # [ 72.601179] systemd[563]: Reexecuting.2393machine # [ 72.656155] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2394machine # [ 72.781384] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2395machine # [ 72.789245] systemd[563]: Reload requested from client PID 4867 ('switch-to-confi')...2396machine # [ 72.792124] systemd[563]: Reloading...2397machine # [ 72.907503] systemd[563]: Reloading finished in 115 ms.2398machine # [ 72.924172] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2399machine # [ 72.934249] systemd[563]: Stopped Run user-specific NixOS activation.2400machine # [ 72.936779] systemd[563]: Stopping Run user-specific NixOS activation...2401machine # [ 72.938977] systemd[563]: Starting Run user-specific NixOS activation...2402machine # [ 72.973442] systemd[563]: Finished Run user-specific NixOS activation.2403machine # [ 72.976841] systemd[563]: Reload requested from client PID 4867 ('switch-to-confi')...2404machine # [ 72.978135] systemd[563]: Reloading...2405machine # [ 73.096178] systemd[563]: Reloading finished in 116 ms.2406machine # [ 73.113850] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2407machine # [ 73.126750] systemd[1]: Stopped target Reactivate sysinit units.2408machine # [ 73.129875] systemd[1]: Stopping Reactivate sysinit units...2409machine # [ 73.130787] systemd[1]: Reached target Reactivate sysinit units.2410machine # [ 73.134516] systemd[1]: Reached target Remote File Systems.2411machine # [ 73.137964] systemd[1]: Starting linger-users.service...2412machine # [ 73.146759] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2413machine # [ 73.154539] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2414machine # [ 73.155880] systemd[1]: Reached target Local File Systems.2415machine # [ 73.159897] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2416machine # [ 73.168419] systemd[1]: Starting Create SUID/SGID Wrappers...2417machine # [ 73.169353] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2418machine # [ 73.174144] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672419machine # [ 73.178843] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2420machine # [ 73.182893] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2421machine # [ 73.188182] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2422machine # [ 73.228713] systemd[1]: linger-users.service: Deactivated successfully.2423machine # [ 73.231669] systemd[1]: Finished linger-users.service.2424machine # [ 73.366025] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2425machine # [ 73.369416] systemd[1]: Finished Create SUID/SGID Wrappers.2426machine # [ 73.623080] nixos[4798]: finished switching to system configuration /nix/store/k8466374gshrcrcbr40bnp3rr5p26zl4-nixos-system-machine-test2427machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.93 seconds)2428machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2429machine # [ 73.666105] sudo[4939]: 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.service2430machine # [ 73.681722] sudo[4939]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2431machine # [ 73.704747] sudo[4939]: pam_unix(sudo:session): session closed for user usertest2432machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds)2433machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2434machine # [ 73.735860] sudo[4945]: 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-owner2435machine # [ 73.747054] sudo[4945]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2436machine # [ 73.755410] sudo[4945]: pam_unix(sudo:session): session closed for user usertest2437machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2438machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd2439machine # [ 73.782529] sudo[4951]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd2440machine # [ 73.791990] sudo[4951]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2441machine # [ 73.799899] sudo[4951]: pam_unix(sudo:session): session closed for user usertest2442machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.04 seconds)2443machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2444machine # [ 73.825992] sudo[4957]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2445machine # [ 73.835699] sudo[4957]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2446machine # [ 73.844343] systemd[563]: Reload requested from client PID 4962 ('systemctl')...2447machine # [ 73.846499] systemd[563]: Reloading...2448machine # [ 73.960303] systemd[563]: Reloading finished in 112 ms.2449machine # [ 73.976167] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2450machine # [ 73.990078] sudo[4957]: pam_unix(sudo:session): session closed for user usertest2451machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)2452machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service2453machine # [ 74.017389] sudo[4967]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user stop migrated.service2454machine # [ 74.027164] sudo[4967]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2455machine # [ 74.038960] systemd[563]: Stopped migrated.service.2456machine # [ 74.043054] sudo[4967]: pam_unix(sudo:session): session closed for user usertest2457machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.05 seconds)2458machine: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&12459machine # [ 74.220166] nixos[4976]: switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test2460machine # [ 74.223047] systemd[1]: Stopped target Remote File Systems.2461machine # [ 74.225228] systemd[1]: Stopped target Local File Systems.2462machine # [ 74.483249] systemd[1]: Reload requested from client PID 4976 ('.switch-to-conf') (unit backdoor.service)...2463machine # [ 74.485342] systemd[1]: Reloading...2464machine # [ 74.786783] systemd[1]: Reloading finished in 300 ms.2465machine # [ 74.877754] systemd[563]: Reexecution requested from client PID 5045 ('switch-to-confi')...2466machine # [ 74.881733] systemd[563]: Reexecuting.2467machine # [ 74.939269] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2468machine # [ 75.055208] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2469machine # [ 75.061658] systemd[563]: Reload requested from client PID 5045 ('switch-to-confi')...2470machine # [ 75.065338] systemd[563]: Reloading...2471machine # [ 75.168470] systemd[563]: Reloading finished in 104 ms.2472machine # [ 75.184160] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2473machine # [ 75.193981] systemd[563]: Stopped Run user-specific NixOS activation.2474machine # [ 75.196352] systemd[563]: Stopping Run user-specific NixOS activation...2475machine # [ 75.198359] systemd[563]: Starting Run user-specific NixOS activation...2476machine # [ 75.231354] systemd[563]: Finished Run user-specific NixOS activation.2477machine # [ 75.235706] systemd[1]: Stopped target Reactivate sysinit units.2478machine # [ 75.239226] systemd[1]: Stopping Reactivate sysinit units...2479machine # [ 75.242662] systemd[1]: Reached target Reactivate sysinit units.2480machine # [ 75.243569] systemd[1]: Reached target Remote File Systems.2481machine # [ 75.249306] systemd[1]: Starting linger-users.service...2482machine # [ 75.258728] systemd[1]: Reached target Local File Systems.2483machine # [ 75.259674] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2484machine # [ 75.265100] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2485machine # [ 75.266446] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2486machine # [ 75.276066] systemd[1]: Starting Create SUID/SGID Wrappers...2487machine # [ 75.276973] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2488machine # [ 75.278225] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672489machine # [ 75.289075] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2490machine # [ 75.292887] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2491machine # [ 75.294234] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2492machine # [ 75.333685] systemd[1]: linger-users.service: Deactivated successfully.2493machine # [ 75.338149] systemd[1]: Finished linger-users.service.2494machine # [ 75.470439] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2495machine # [ 75.472379] systemd[1]: Finished Create SUID/SGID Wrappers.2496machine # [ 75.728122] nixos[4976]: finished switching to system configuration /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test2497machine: (finished: must succeed: /nix/store/siy6kc0nwwg8nsqvq8r85zml77ahv8z6-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.69 seconds)2498(finished: subtest: user services, in 52.14 seconds)2499(finished: run the VM test script, in 76.43 seconds)2500test script finished in 76.47s2501cleanup2502kill QemuMachine (pid 45)2503machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2504machine # [2026-10-02T03:45:41Z INFO virtiofsd] Client disconnected, shutting down2505machine # [2026-10-02T03:45:41Z INFO virtiofsd] Client disconnected, shutting down2506machine # [2026-10-02T03:45:41Z INFO virtiofsd] Client disconnected, shutting down2507(finished: cleanup, in 0.17 seconds)2508additionally exposed symbols:2509 machine, other,2510 vlan1,2511 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