vm-test-run-switch-test-user
checks.aarch64-linux.switchTest-user
· build #42
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7machine: waiting for unit multi-user.target8machine: waiting for the VM to finish booting9machine: starting vm10machine: QEMU running (pid 45)11machine # Disk image does not exist, creating the virtualisation disk image...12machine # Formatting '/build/vm-state-machine/tmp.PZm8mCX9Zb', 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: ce7a81f5-595c-41cb-88d7-20c36737751217machine # Superblock backups stored on blocks:18machine # 32768, 98304, 163840, 22937619machine # 20machine # Allocating group tables: 0/8 done21machine # Writing inode tables: 0/8 done22machine # Creating journal (8192 blocks): done23machine # Writing superblocks and filesystem accounting information: 0/8 done24machine # 25machine # Virtualisation disk image created.26machine # Starting virtiofs daemons...27machine # [2026-09-23T02:59:49Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28machine # [2026-09-23T02:59:49Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29machine # [2026-09-23T02:59:49Z INFO virtiofsd] Waiting for vhost-user socket connection...30machine # [2026-09-23T02:59:49Z INFO virtiofsd] Client connected, servicing requests31machine # [2026-09-23T02:59:49Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)32machine # [2026-09-23T02:59:49Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether33machine # [2026-09-23T02:59:49Z INFO virtiofsd] Waiting for vhost-user socket connection...34machine # [2026-09-23T02:59:49Z INFO virtiofsd] Client connected, servicing requests35machine # [2026-09-23T02:59:49Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)36machine # [2026-09-23T02:59:49Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether37machine # [2026-09-23T02:59:49Z INFO virtiofsd] Waiting for vhost-user socket connection...38machine # [2026-09-23T02:59:49Z INFO virtiofsd] Client connected, servicing requests39machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]40machine # [ 0.000000] Linux version 6.18.53 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 21 13:06:06 UTC 202641machine # [ 0.000000] KASLR enabled42machine # [ 0.000000] random: crng init done43machine # [ 0.000000] Machine model: linux,dummy-virt44machine # [ 0.000000] efi: UEFI not found.45machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT46machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]47machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f]48machine # [ 0.000000] Zone ranges:49machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]50machine # [ 0.000000] DMA32 empty51machine # [ 0.000000] Normal empty52machine # [ 0.000000] Device empty53machine # [ 0.000000] Movable zone start for each node54machine # [ 0.000000] Early memory node ranges55machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]56machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]57machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000058machine # [ 0.000000] psci: probing for conduit method from DT.59machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.60machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs61machine # [ 0.000000] psci: Trusted OS migration not required62machine # [ 0.000000] psci: SMC Calling Convention v1.163machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)64machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u31129665machine # [ 0.000000] Detected PIPT I-cache on CPU066machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)67machine # [ 0.000000] CPU features: detected: GICv3 CPU interface68machine # [ 0.000000] CPU features: detected: Spectre-v469machine # [ 0.000000] CPU features: detected: Spectre-BHB70machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_3871machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_2372machine # [ 0.000000] alternatives: applying boot alternatives73machine # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/init regInfo=/nix/.ro-store/bdwdxh5sqvxfax67bhxax2p6cqlinanm-closure-info/registration console=ttyAMA0,115200n8 console=tty074machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/bdwdxh5sqvxfax67bhxax2p6cqlinanm-closure-info/registration", will be passed to user space.75machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes76machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)77machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)78machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB79machine # [ 0.000000] software IO TLB: area num 1.80machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)81machine # [ 0.000000] Fallback order for Node 0: 082machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214483machine # [ 0.000000] Policy zone: DMA84machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off85machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=186machine # [ 0.000000] allocated 2097152 bytes of page_ext87machine # [ 0.000000] ftrace: allocating 74963 entries in 294 pages88machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups89machine # [ 0.000000] rcu: Hierarchical RCU implementation.90machine # [ 0.000000] rcu: RCU event tracing is enabled.91machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.92machine # [ 0.000000] Trampoline variant of Tasks RCU enabled.93machine # [ 0.000000] Rude variant of Tasks RCU enabled.94machine # [ 0.000000] Tracing variant of Tasks RCU enabled.95machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.96machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=197machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.98machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.99machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.100machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0101machine # [ 0.000000] GICv3: 256 SPIs implemented102machine # [ 0.000000] GICv3: 0 Extended SPIs implemented103machine # [ 0.000000] Root IRQ handler: gic_handle_irq104machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI105machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0106machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000107machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]108machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)109machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (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.000032] arm-pv: using stolen time PV118machine # [ 0.000420] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)119machine # [ 0.000605] Console: colour dummy device 80x25120machine # [ 0.000614] printk: legacy console [tty0] enabled121machine # [ 0.000801] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)122machine # [ 0.000808] pid_max: default: 32768 minimum: 301123machine # [ 0.000884] LSM: initializing lsm=capability,landlock,yama,bpf,ima124machine # [ 0.001025] landlock: Up and running.125machine # [ 0.001028] Yama: becoming mindful.126machine # [ 0.001482] LSM support for eBPF active127machine # [ 0.001629] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)128machine # [ 0.001650] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)129machine # [ 0.003440] rcu: Hierarchical SRCU implementation.130machine # [ 0.003445] rcu: Max phase no-delay instances is 1000.131machine # [ 0.004730] fsl-mc MSI: its@8080000 domain created132machine # [ 0.004855] EFI services will not be available.133machine # [ 0.004951] smp: Bringing up secondary CPUs ...134machine # [ 0.004960] smp: Brought up 1 node, 1 CPU135machine # [ 0.004964] SMP: Total of 1 processors activated.136machine # [ 0.004966] CPU: All CPU(s) started at EL1137machine # [ 0.004980] CPU features: detected: Branch Target Identification138machine # [ 0.004984] CPU features: detected: ARMv8.4 Translation Table Level139machine # [ 0.004987] CPU features: detected: Instruction cache invalidation not required for I/D coherence140machine # [ 0.004991] CPU features: detected: Data cache clean to the PoU not required for I/D coherence141machine # [ 0.004994] CPU features: detected: Common not Private translations142machine # [ 0.004997] CPU features: detected: CRC32 instructions143machine # [ 0.005000] CPU features: detected: Data cache clean to Point of Deep Persistence144machine # [ 0.005004] CPU features: detected: Data cache clean to Point of Persistence145machine # [ 0.005007] CPU features: detected: Data independent timing control (DIT)146machine # [ 0.005010] CPU features: detected: E0PD147machine # [ 0.005013] CPU features: detected: Enhanced Counter Virtualization148machine # [ 0.005016] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)149machine # [ 0.005020] CPU features: detected: Enhanced Virtualization Traps150machine # [ 0.005023] CPU features: detected: Fine Grained Traps151machine # [ 0.005026] CPU features: detected: Generic authentication (architected QARMA5 algorithm)152machine # [ 0.005031] CPU features: detected: RCpc load-acquire (LDAPR)153machine # [ 0.005034] CPU features: detected: LSE atomic instructions154machine # [ 0.005037] CPU features: detected: Privileged Access Never155machine # [ 0.005040] CPU features: detected: PMUv3156machine # [ 0.005043] CPU features: detected: RAS Extension Support157machine # [ 0.005046] CPU features: detected: RASv1p1 Extension Support158machine # [ 0.005048] CPU features: detected: Random Number Generator159machine # [ 0.005051] CPU features: detected: Speculation barrier (SB)160machine # [ 0.005054] CPU features: detected: Stage-2 Force Write-Back161machine # [ 0.005057] CPU features: detected: TLB range maintenance instructions162machine # [ 0.005062] CPU features: detected: Speculative Store Bypassing Safe (SSBS)163machine # [ 0.005099] alternatives: applying system-wide alternatives164machine # [ 0.008225] CPU features: detected: BBM Level 2 without TLB conflict abort165machine # [ 0.008423] Memory: 893780K/1048576K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 113492K reserved, 32768K cma-reserved)166machine # [ 0.008780] devtmpfs: initialized167machine # [ 0.010477] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)168machine # [ 0.010499] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).169machine # [ 0.010687] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL170machine # [ 0.010692] 0 pages in range for non-PLT usage171machine # [ 0.010693] 508224 pages in range for PLT usage172machine # [ 0.010785] pinctrl core: initialized pinctrl subsystem173machine # [ 0.011559] DMI not present or invalid.174machine # [ 0.014711] NET: Registered PF_NETLINK/PF_ROUTE protocol family175machine # [ 0.017074] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations176machine # [ 0.017226] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations177machine # [ 0.017393] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations178machine # [ 0.017416] audit: initializing netlink subsys (disabled)179machine # [ 0.018029] thermal_sys: Registered thermal governor 'fair_share'180machine # [ 0.018031] thermal_sys: Registered thermal governor 'bang_bang'181machine # [ 0.018034] thermal_sys: Registered thermal governor 'step_wise'182machine # [ 0.018037] thermal_sys: Registered thermal governor 'user_space'183machine # [ 0.018040] thermal_sys: Registered thermal governor 'power_allocator'184machine # [ 0.018075] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1185machine # [ 0.018083] cpuidle: using governor ladder186machine # [ 0.018089] cpuidle: using governor menu187machine # [ 0.018308] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.188machine # [ 0.018324] ASID allocator initialised with 65536 entries189machine # [ 0.019554] Serial: AMBA PL011 UART driver190machine # [ 0.025221] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1191machine # [ 0.025380] printk: console [ttyAMA0] enabled192machine # [ 0.151558] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages193machine # [ 0.151570] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page194machine # [ 0.151573] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages195machine # [ 0.151576] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page196machine # [ 0.151579] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages197machine # [ 0.151582] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page198machine # [ 0.151584] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages199machine # [ 0.151587] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page200machine # [ 0.152827] fbcon: Taking over console201machine # [ 0.152837] ACPI: Interpreter disabled.202machine # [ 0.153812] iommu: Default domain type: Translated203machine # [ 0.153816] iommu: DMA domain TLB invalidation policy: strict mode204machine # [ 0.164668] SCSI subsystem initialized205machine # [ 0.164874] usbcore: registered new interface driver usbfs206machine # [ 0.164902] usbcore: registered new interface driver hub207machine # [ 0.164917] usbcore: registered new device driver usb208machine # [ 0.165232] pps_core: LinuxPPS API ver. 1 registered209machine # [ 0.165236] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>210machine # [ 0.165244] PTP clock support registered211machine # [ 0.165295] EDAC MC: Ver: 3.0.0212machine # [ 0.165451] scmi_core: SCMI protocol bus registered213machine # [ 0.174267] FPGA manager framework214machine # [ 0.174787] vgaarb: loaded215machine # [ 0.175078] clocksource: Switched to clocksource arch_sys_counter216machine # [ 0.180430] VFS: Disk quotas dquot_6.6.0217machine # [ 0.180454] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)218machine # [ 0.182763] netfs: FS-Cache loaded219machine # [ 0.182885] pnp: PnP ACPI: disabled220machine # [ 0.188188] NET: Registered PF_INET protocol family221machine # [ 0.188341] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)222machine # [ 0.218819] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)223machine # [ 0.218859] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)224machine # [ 0.218884] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)225machine # [ 0.218925] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)226machine # [ 0.218998] TCP: Hash tables configured (established 8192 bind 8192)227machine # [ 0.224068] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)228machine # [ 0.224129] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)229machine # [ 0.224177] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)230machine # [ 0.224271] NET: Registered PF_UNIX/PF_LOCAL protocol family231machine # [ 0.224326] NET: Registered PF_XDP protocol family232machine # [ 0.224345] PCI: CLS 0 bytes, default 64233machine # [ 0.224573] Trying to unpack rootfs image as initramfs...234machine # [ 0.239113] kvm [1]: HYP mode not available235machine # [ 0.351643] Initialise system trusted keyrings236machine # [ 0.351779] workingset: timestamp_bits=42 max_order=18 bucket_order=0237machine # [ 0.352201] squashfs: version 4.0 (2009/01/31) Phillip Lougher238machine # [ 0.352248] 9p: Installing v9fs 9p2000 file system support239machine # [ 0.380317] Key type asymmetric registered240machine # [ 0.380335] Asymmetric key parser 'x509' registered241machine # [ 0.380409] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)242machine # [ 0.380562] io scheduler mq-deadline registered243machine # [ 0.380567] io scheduler kyber registered244machine # [ 0.393285] pl061_gpio 9030000.pl061: PL061 GPIO chip registered245machine # [ 0.393871] ledtrig-cpu: registered to indicate activity on CPUs246machine # [ 0.394229] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:247machine # [ 0.394242] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000248machine # [ 0.394252] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000249machine # [ 0.394257] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000250machine # [ 0.394279] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits251machine # [ 0.394297] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]252machine # [ 0.394371] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00253machine # [ 0.394378] pci_bus 0000:00: root bus resource [bus 00-ff]254machine # [ 0.394383] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]255machine # [ 0.394386] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]256machine # [ 0.394389] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]257machine # [ 0.394457] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint258machine # [ 0.394923] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint259machine # [ 0.415197] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]260machine # [ 0.415220] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]261machine # [ 0.415248] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]262machine # [ 0.415260] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]263machine # [ 0.415792] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint264machine # [ 0.415980] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]265machine # [ 0.415992] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]266machine # [ 0.416017] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]267machine # [ 0.416506] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint268machine # [ 0.416688] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]269machine # [ 0.416700] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]270machine # [ 0.416724] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]271machine # [ 0.417181] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint272machine # [ 0.417364] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]273machine # [ 0.417375] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]274machine # [ 0.417399] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275machine # [ 0.417410] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]276machine # [ 0.417856] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint277machine # [ 0.418037] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]278machine # [ 0.418062] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279machine # [ 0.418519] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint280machine # [ 0.418705] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.418730] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.437761] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint283machine # [ 0.437938] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]284machine # [ 0.438178] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint285machine # [ 0.438359] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]286machine # [ 0.438383] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]287machine # [ 0.438821] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint288machine # [ 0.439005] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]289machine # [ 0.439030] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]290machine # [ 0.451595] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint291machine # [ 0.451801] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]292machine # [ 0.451827] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]293machine # [ 0.452323] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint294machine # [ 0.452617] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]295machine # [ 0.452628] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]296machine # [ 0.452653] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]297machine # [ 0.453113] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint298machine # [ 0.453295] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]299machine # [ 0.453306] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]300machine # [ 0.453331] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]301machine # [ 0.453931] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned302machine # [ 0.453937] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned303machine # [ 0.453941] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned304machine # [ 0.453985] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned305machine # [ 0.454028] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned306machine # [ 0.454072] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned307machine # [ 0.454115] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned308machine # [ 0.454158] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned309machine # [ 0.454202] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned310machine # [ 0.454244] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned311machine # [ 0.454293] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned312machine # [ 0.454334] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned313machine # [ 0.454394] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned314machine # [ 0.454485] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned315machine # [ 0.454502] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned316machine # [ 0.454519] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned317machine # [ 0.454535] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned318machine # [ 0.454552] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned319machine # [ 0.454569] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned320machine # [ 0.454587] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned321machine # [ 0.454605] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned322machine # [ 0.454626] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned323machine # [ 0.454643] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned324machine # [ 0.454664] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned325machine # [ 0.454682] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned326machine # [ 0.454699] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned327machine # [ 0.454716] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned328machine # [ 0.454732] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned329machine # [ 0.454749] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned330machine # [ 0.454766] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned331machine # [ 0.454784] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned332machine # [ 0.454806] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]333machine # [ 0.454811] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]334machine # [ 0.454814] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]335machine # [ 0.463913] pci 0000:00:07.0: enabling device (0000 -> 0002)336machine # [ 0.522083] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)337machine # [ 0.525573] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)338machine # [ 0.536382] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)339machine # [ 0.538300] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)340machine # [ 0.542017] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)341machine # [ 0.552794] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)342machine # [ 0.554681] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)343machine # [ 0.558078] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)344machine # [ 0.568322] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)345machine # [ 0.570346] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)346machine # [ 0.574390] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)347machine # [ 0.587516] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled348machine # [ 0.589257] msm_serial: driver initialized349machine # [ 0.589413] SuperH (H)SCI(F) driver initialized350machine # [ 0.589470] STM32 USART driver initialized351machine # [ 0.625924] loop: module loaded352machine # [ 0.626124] virtio_blk virtio2: 1/0/0 default/read/poll queues353machine # [ 0.626987] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)354machine # [ 0.639223] megasas: 07.734.00.00-rc1355machine # [ 0.640230] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]356machine # [ 0.642585] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000357machine # [ 0.642636] Intel/Sharp Extended Query Table at 0x0031358machine # [ 0.644348] Using buffer write method359machine # [ 0.644383] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]360machine # [ 0.646298] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000361machine # [ 0.646312] Intel/Sharp Extended Query Table at 0x0031362machine # [ 0.648389] Using buffer write method363machine # [ 0.648404] Concatenating MTD devices:364machine # [ 0.648407] (0): "0.flash"365machine # [ 0.648409] (1): "0.flash"366machine # [ 0.648411] into device "0.flash"367machine # [ 0.939006] Freeing initrd memory: 26380K368machine # [ 0.945333] tun: Universal TUN/TAP device driver, 1.6369machine # [ 0.949424] thunder_xcv, ver 1.0370machine # [ 0.949454] thunder_bgx, ver 1.0371machine # [ 0.949472] nicpf, ver 1.0372machine # [ 0.950048] e1000: Intel(R) PRO/1000 Network Driver373machine # [ 0.950052] e1000: Copyright (c) 1999-2006 Intel Corporation.374machine # [ 0.950079] e1000e: Intel(R) PRO/1000 Network Driver375machine # [ 0.950087] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.376machine # [ 0.950110] igb: Intel(R) Gigabit Ethernet Network Driver377machine # [ 0.950114] igb: Copyright (c) 2007-2014 Intel Corporation.378machine # [ 0.950133] igbvf: Intel(R) Gigabit Virtual Function Network Driver379machine # [ 0.950136] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.380machine # [ 0.950274] sky2: driver version 1.30381machine # [ 0.959968] ehci-pci 0000:00:07.0: EHCI Host Controller382machine # [ 0.959990] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1383machine # [ 0.960197] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000384machine # [ 0.960662] usbcore: registered new interface driver usb-storage385machine # [ 0.960710] usbcore: registered new interface driver usbserial_generic386machine # [ 0.960721] usbserial: USB Serial support registered for generic387machine # [ 0.961378] hv_vmbus: registering driver hyperv_keyboard388machine # [ 0.962291] rtc-pl031 9010000.pl031: registered as rtc0389machine # [ 0.962317] rtc-pl031 9010000.pl031: setting system clock to 2026-09-23T02:59:50 UTC (1790132390)390machine # [ 0.962627] i2c_dev: i2c /dev entries driver391machine # [ 0.971178] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00392machine # [ 0.971455] hub 1-0:1.0: USB hub found393machine # [ 0.971470] hub 1-0:1.0: 6 ports detected394machine # [ 0.974363] sdhci: Secure Digital Host Controller Interface driver395machine # [ 0.974368] sdhci: Copyright(c) Pierre Ossman396machine # [ 0.974659] Synopsys Designware Multimedia Card Interface Driver397machine # [ 0.975044] sdhci-pltfm: SDHCI platform and OF driver helper398machine # [ 0.981304] hid: raw HID events driver (C) Jiri Kosina399machine # [ 0.981534] usbcore: registered new interface driver usbhid400machine # [ 0.981543] usbhid: USB HID core driver401machine # [ 0.982742] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available402machine # [ 0.984381] drop_monitor: Initializing network drop monitor service403machine # [ 0.984573] NET: Registered PF_INET6 protocol family404machine # [ 0.989561] Segment Routing with IPv6405machine # [ 0.989574] In-situ OAM (IOAM) with IPv6406machine # [ 0.989616] NET: Registered PF_PACKET protocol family407machine # [ 0.989670] 9pnet: Installing 9P2000 support408machine # [ 0.989710] Key type dns_resolver registered409machine # [ 0.998507] registered taskstats version 1410machine # [ 0.998676] Loading compiled-in X.509 certificates411machine # [ 1.007620] Demotion targets for Node 0: null412machine # [ 1.007726] Key type .fscrypt registered413machine # [ 1.007733] Key type fscrypt-provisioning registered414machine # [ 1.007837] ima: No TPM chip found, activating TPM-bypass!415machine # [ 1.007854] ima: Allocated hash algorithm: sha1416machine # [ 1.007876] ima: No architecture policies found417machine # [ 1.008621] input: gpio-keys as /devices/platform/gpio-keys/input/input0418machine # [ 1.032388] clk: Disabling unused clocks419machine # [ 1.032407] PM: genpd: Disabling unused power domains420machine # [ 1.036768] Freeing unused kernel memory: 4736K421machine # [ 1.036972] Run /init as init process422machine # [ 1.054868] systemd[1]: Successfully made /usr/ read-only.423machine # [ 1.219180] usb 1-1: new high-speed USB device number 2 using ehci-pci424machine # [ 1.369325] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1425machine # [ 1.390370] systemd[1]: systemd 263-ef3e0ff running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)426machine # [ 1.390421] systemd[1]: Detected virtualization qemu.427machine # [ 1.390549] systemd[1]: Detected architecture arm64.428machine # [ 1.390566] systemd[1]: Running in initrd.429machine # [ 1.408560] systemd[1]: Initializing machine ID from random generator.430machine # [ 1.408866] systemd[1]: Hostname set to <machine>.431machine # [ 1.463000] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0432machine # [ 1.522325] systemd[1]: bpf-restrict-fs: LSM BPF program attached433machine # [ 1.579410] systemd[1]: Queued start job for default target Initrd Default Target.434machine # [ 1.583173] usb 1-2: new high-speed USB device number 3 using ehci-pci435machine # [ 1.587473] systemd[1]: Created slice Slice /system/modprobe.436machine # [ 1.587667] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.437machine # [ 1.587694] systemd[1]: Expecting device /dev/disk/by-label/nixos...438machine # [ 1.587717] systemd[1]: Reached target Path Units.439machine # [ 1.587729] systemd[1]: Reached target Slice Units.440machine # [ 1.587743] systemd[1]: Reached target Swaps.441machine # [ 1.587756] systemd[1]: Reached target Timer Units.442machine # [ 1.587897] systemd[1]: Listening on D-Bus System Message Bus Socket.443machine # [ 1.588051] systemd[1]: Listening on Journal Socket (/dev/log).444machine # [ 1.588206] systemd[1]: Listening on Journal Sockets.445machine # [ 1.588283] systemd[1]: Listening on udev Kernel Socket.446machine # [ 1.588297] systemd[1]: Reached target Socket Units.447machine # [ 1.589722] systemd[1]: Starting Create List of Static Device Nodes...448machine # [ 1.589795] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs449machine # [ 1.606204] systemd[1]: Mounting Kernel Configuration File System...450machine # [ 1.615409] systemd[1]: Starting Journal Service...451machine # [ 1.651480] systemd[1]: Starting Load Kernel Modules...452machine # [ 1.651630] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os453machine # [ 1.678089] systemd-journald[71]: Collecting audit messages is disabled.454machine # [ 1.679348] systemd[1]: Starting Coldplug All udev Devices...455machine # [ 1.682759] systemd[1]: Finished Create List of Static Device Nodes.456machine # [ 1.691997] systemd[1]: Mounted Kernel Configuration File System.457machine # [ 1.708416] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.458machine # [ 1.709127] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...459machine # [ 1.725937] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev460machine # [ 1.751751] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0461machine # [ 1.751998] [drm] features: -virgl +edid -resource_blob -host_visible462machine # [ 1.752001] [drm] features: -context_init463machine # [ 1.752745] [drm] number of scanouts: 1464machine # [ 1.752757] [drm] number of cap sets: 0465machine # [ 1.753178] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2466machine # [ 1.753362] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0467machine # [ 1.761923] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic468machine # [ 1.761937] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0469machine # [ 1.783916] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.470machine # [ 1.791535] Console: switching to colour frame buffer device 160x50471machine # [ 1.798207] systemd[1]: Starting Create Static Device Nodes in /dev...472machine # [ 1.816354] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device473machine # [ 1.832685] systemd[1]: Finished Load Kernel Modules.474machine # [ 1.834469] systemd[1]: Starting Apply Kernel Variables...475machine # [ 1.851691] systemd-modules-load[72]: Inserted module 'dm_mod'476machine # [ 1.863343] systemd[1]: Finished Create Static Device Nodes in /dev.477machine # [ 1.863953] systemd[1]: Started Journal Service.478machine # [ 1.854344] systemd-modules-load[72]: Module 'virtio_balloon' is built in479machine # [ 1.868410] systemd-modules-load[72]: Module 'virtio_console' is built in480machine # [ 1.872587] systemd-modules-load[72]: Inserted module 'virtio_gpu'481machine # [ 1.873798] systemd-modules-load[72]: Module 'virtio_rng' is built in482machine # [ 1.876335] systemd[1]: Reached target Preparation for Local File Systems.483machine # [ 1.886827] systemd[1]: Reached target Local File Systems.484machine # [ 1.892455] systemd[1]: Starting Create System Files and Directories...485machine # [ 1.900578] systemd[1]: Starting Rule-based Manager for Device Events and Files...486machine # [ 1.908672] systemd[1]: Finished Apply Kernel Variables.487machine # [ 1.911365] systemd[1]: Finished Create System Files and Directories.488machine # [ 1.973499] systemd-udevd[81]: Using default interface naming scheme 'v261'.489machine # [ 2.002031] systemd[1]: Started Rule-based Manager for Device Events and Files.490machine # [ 2.084690] systemd[1]: Starting Virtual Console Setup...491machine # [ 2.165892] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.492machine # [ 2.171166] systemd[1]: Finished Virtual Console Setup.493machine # [ 2.755319] systemd[1]: Finished Coldplug All udev Devices.494machine # [ 2.757071] systemd[1]: Reached target System Initialization.495machine # [ 2.757983] systemd[1]: Reached target Basic System.496machine # [ 2.926282] (udev-worker)[97]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.497machine # [ 2.932339] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.498machine # [ 2.985576] systemd[1]: Found device /dev/disk/by-label/nixos.499machine # [ 2.987997] systemd[1]: Reached target Initrd Root Device.500machine # [ 2.992329] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...501machine # [ 3.048492] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks502machine # [ 3.056076] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.503machine # [ 3.064366] systemd[1]: Mounting /sysroot...504machine # [ 3.114800] EXT4-fs (vda): mounted filesystem ce7a81f5-595c-41cb-88d7-20c367377512 r/w with ordered data mode. Quota mode: none.505machine # [ 3.109378] systemd[1]: Mounted /sysroot.506machine # [ 3.111117] systemd[1]: Reached target Initrd Root File System.507machine # [ 3.115081] systemd[1]: Starting Mountpoints Configured in the Real Root...508machine # [ 3.144251] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.509machine # [ 3.151675] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...510machine # [ 3.156275] systemd[1]: Reloading...511machine # [ 3.355172] systemd[1]: Reloading finished in 200 ms.512machine # [ 3.386173] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...513machine # [ 3.392542] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...514machine # [ 3.397301] systemd[1]: initrd-parse-etc.service: Deactivated successfully.515machine # [ 3.400923] systemd[1]: Finished Mountpoints Configured in the Real Root.516machine # [ 3.401964] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.517machine # [ 3.669152] systemd[1]: Mounting /sysroot/nix/.ro-store...518machine # [ 3.688128] systemd[1]: Mounting /sysroot/nix/.rw-store...519machine # [ 3.695623] systemd[1]: Mounting /sysroot/run...520machine # [ 3.724121] systemd[1]: Mounting /sysroot/tmp/shared...521machine # [ 3.768141] systemd[1]: Mounting /sysroot/tmp/xchg...522machine # [ 3.782334] fuse: init (API version 7.45)523machine # [ 3.790057] virtiofs virtio6: discovered new tag: nix-store524machine # [ 3.790879] virtiofs virtio6: virtio_fs_setup_dax: No cache capability525machine # [ 3.806567] virtiofs virtio7: discovered new tag: shared526machine # [ 3.814325] virtiofs virtio7: virtio_fs_setup_dax: No cache capability527machine # [ 3.819939] virtiofs virtio8: discovered new tag: xchg528machine # [ 3.820747] virtiofs virtio8: virtio_fs_setup_dax: No cache capability529machine # [ 3.836167] systemd[1]: Mounted /sysroot/nix/.ro-store.530machine # [ 3.842719] systemd[1]: Mounted /sysroot/nix/.rw-store.531machine # [ 3.844972] systemd[1]: Mounted /sysroot/run.532machine # [ 3.850413] systemd[1]: Mounted /sysroot/tmp/shared.533machine # [ 3.871269] systemd[1]: Starting rw-sysroot-nix-store.service...534machine # [ 3.874390] systemd[1]: Mounted /sysroot/tmp/xchg.535machine # [ 3.898837] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.536machine # [ 3.900366] systemd[1]: Finished rw-sysroot-nix-store.service.537machine # [ 4.320876] (udev-worker)[91]: mtd0ro: Failed to find and pin callout binary "/nix/store/2lckkskw40in5vmpcihl3b4rygx8df89-systemd-263-ef3e0ff/lib/udev/mtd_probe": No such file or directory538machine # [ 4.330663] (udev-worker)[91]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory539machine # [ 4.361818] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.540machine # [ 4.364408] systemd[1]: Stopped Virtual Console Setup.541machine # [ 4.366807] systemd[1]: Stopping Virtual Console Setup...542machine # [ 4.372370] systemd[1]: Starting Virtual Console Setup...543machine # [ 4.373666] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.544machine # [ 4.375094] systemd[1]: Stopped Virtual Console Setup.545machine # [ 4.385244] systemd[1]: Starting Virtual Console Setup...546machine # [ 4.414680] systemd-vconsole-setup[152]: Configuration of first virtual console was skipped, ignoring remaining ones.547machine # [ 4.418341] systemd[1]: Finished Virtual Console Setup.548machine # [ 4.673122] systemd[1]: Mounting /sysroot/nix/store...549machine # [ 4.742258] systemd[1]: Mounted /sysroot/nix/store.550machine # [ 4.747633] systemd[1]: Reached target Initrd File Systems.551machine # [ 4.752553] systemd[1]: Starting Find NixOS closure...552machine # [ 4.761124] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...553machine # [ 4.821172] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.554machine # [ 4.824563] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.555machine # [ 4.832874] systemd[1]: Finished Find NixOS closure.556machine # [ 4.836219] systemd[1]: Reached target Initrd Default Target.557machine # [ 4.838061] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...558machine # [ 4.867975] systemd[1]: Stopped target Initrd Default Target.559machine # [ 4.872727] systemd[1]: Stopped target Basic System.560machine # [ 4.873742] systemd[1]: Stopped target Initrd Root Device.561machine # [ 4.874722] systemd[1]: Stopped target Path Units.562machine # [ 4.876757] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.563machine # [ 4.880213] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.564machine # [ 4.882348] systemd[1]: Stopped target Slice Units.565machine # [ 4.888158] systemd[1]: Stopped target Socket Units.566machine # [ 4.889246] systemd[1]: Stopped target System Initialization.567machine # [ 4.890283] systemd[1]: Stopped target Swaps.568machine # [ 4.891069] systemd[1]: Stopped target Timer Units.569machine # [ 4.891899] systemd[1]: dbus.socket: Deactivated successfully.570machine # [ 4.895267] systemd[1]: Closed D-Bus System Message Bus Socket.571machine # [ 4.905372] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.572machine # [ 4.906611] systemd[1]: Stopped Find NixOS closure.573machine # [ 4.907499] systemd[1]: Starting rw-sysroot-nix-store.service...574machine # [ 4.916229] systemd[1]: systemd-sysctl.service: Deactivated successfully.575machine # [ 4.917257] systemd[1]: Stopped Apply Kernel Variables.576machine # [ 4.917988] systemd[1]: systemd-modules-load.service: Deactivated successfully.577machine # [ 4.918956] systemd[1]: Stopped Load Kernel Modules.578machine # [ 4.919646] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.579machine # [ 4.928158] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.580machine # [ 4.929368] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.581machine # [ 4.930398] systemd[1]: Stopped Create System Files and Directories.582machine # [ 4.931290] systemd[1]: Stopped target Local File Systems.583machine # [ 4.937556] systemd[1]: Stopped target Preparation for Local File Systems.584machine # [ 4.940267] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.585machine # [ 4.944605] systemd[1]: Stopped Coldplug All udev Devices.586machine # [ 4.945496] systemd[1]: Stopping Rule-based Manager for Device Events and Files...587machine # [ 4.946526] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.588machine # [ 4.947628] systemd[1]: Stopped Virtual Console Setup.589machine # [ 4.948596] systemd[1]: initrd-cleanup.service: Deactivated successfully.590machine # [ 4.949545] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.591machine # [ 4.950465] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.592machine # [ 4.951468] systemd[1]: Finished rw-sysroot-nix-store.service.593machine # [ 4.952401] systemd[1]: systemd-udevd.service: Deactivated successfully.594machine # [ 4.953353] systemd[1]: Stopped Rule-based Manager for Device Events and Files.595machine # [ 4.954356] systemd[1]: systemd-udevd.service: Consumed 1.475s CPU time over 3.046s wall clock time, 22.3M memory peak.596machine # [ 4.955776] systemd[1]: Starting Cleanup udev Database...597machine # [ 4.960281] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.598machine # [ 4.961443] systemd[1]: Stopped Create Static Device Nodes in /dev.599machine # [ 4.964470] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.600machine # [ 4.968271] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.601machine # [ 4.969399] systemd[1]: kmod-static-nodes.service: Deactivated successfully.602machine # [ 4.972160] systemd[1]: Stopped Create List of Static Device Nodes.603machine # [ 5.002432] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.604machine # [ 5.004529] systemd[1]: Finished Cleanup udev Database.605machine # [ 5.006927] systemd[1]: Reached target Switch Root.606machine # [ 5.008813] systemd[1]: Starting NixOS Activation...607machine # [ 5.094413] initrd-nixos-activation-start[175]: booting system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test608machine # [ 5.127516] initrd-nixos-activation-start[175]: running activation script...609machine # [ 5.356642] initrd-nixos-activation-start[198]: setting up /etc...610machine # [ 5.482978] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.611machine # [ 5.486254] systemd[1]: Finished NixOS Activation.612machine # [ 5.487618] systemd[1]: Starting Switch Root...613machine # [ 5.513818] systemd[1]: Switching root.614machine # [ 5.697306] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).615machine # [ 6.222208] systemd[1]: systemd 263-ef3e0ff running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)616machine # [ 6.222318] systemd[1]: Detected virtualization qemu.617machine # [ 6.222426] systemd[1]: Detected architecture arm64.618machine # [ 6.222667] systemd[1]: Detected first boot.619machine # [ 6.243723] systemd[1]: Initializing machine ID from random generator.620machine # [ 6.417006] systemd[1]: bpf-restrict-fs: LSM BPF program attached621machine # [ 6.582139] systemd[1]: Applying preset policy.622machine # [ 6.814986] systemd[1]: Populated /etc with preset unit settings.623machine # [ 7.038642] systemd[1]: initrd-switch-root.service: Deactivated successfully.624machine # [ 7.041812] systemd[1]: Stopped initrd-switch-root.service.625machine # [ 7.045411] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.626machine # [ 7.049231] systemd[1]: Created slice Slice /system/getty.627machine # [ 7.050670] systemd[1]: Created slice User and Session Slice.628machine # [ 7.053741] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.629machine # [ 7.054348] systemd[1]: Started Forward Password Requests to Wall Directory Watch.630machine # [ 7.058036] systemd[1]: Expecting device /dev/hvc0...631machine # [ 7.058492] systemd[1]: Expecting device /dev/ttyAMA0...632machine # [ 7.061606] systemd[1]: Reached target Local Encrypted Volumes.633machine # [ 7.062066] systemd[1]: Stopped target initrd-fs.target.634machine # [ 7.062930] systemd[1]: Stopped target initrd-root-fs.target.635machine # [ 7.066722] systemd[1]: Stopped target initrd-switch-root.target.636machine # [ 7.068897] systemd[1]: Reached target Virtual Machines and Containers.637machine # [ 7.069352] systemd[1]: Reached target Path Units.638machine # [ 7.070200] systemd[1]: Reached target Remote File Systems.639machine # [ 7.071078] systemd[1]: Reached target Slice Units.640machine # [ 7.075446] systemd[1]: Reached target Swaps.641machine # [ 7.077340] systemd[1]: Listening on Query the User Interactively for a Password.642machine # [ 7.081121] systemd[1]: Listening on Process Core Dump Socket.643machine # [ 7.082642] systemd[1]: Listening on Credential Encryption/Decryption.644machine # [ 7.086518] systemd[1]: Listening on Factory Reset Management.645machine # [ 7.088604] systemd[1]: Listening on Hostname Service Socket.646machine # [ 7.092681] systemd[1]: Starting Journal Log Access Socket...647machine # [ 7.094221] systemd[1]: Listening on Journal Audit Socket.648machine # [ 7.098411] systemd[1]: Listening on Console Output Muting Service Socket.649machine # [ 7.101798] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.650machine # [ 7.102217] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os651machine # [ 7.102493] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki652machine # [ 7.112088] systemd[1]: Listening on Disk Repartitioning Service Socket.653machine # [ 7.112770] systemd[1]: Listening on udev Varlink Socket.654machine # [ 7.117679] systemd[1]: Mounting Huge Pages File System...655machine # [ 7.122723] systemd[1]: Mounting POSIX Message Queue File System...656machine # [ 7.136203] systemd[1]: Mounting Kernel Debug File System...657machine # [ 7.143915] systemd[1]: Mounting Kernel Trace File System...658machine # [ 7.167282] systemd[1]: Starting Create List of Static Device Nodes...659machine # [ 7.172161] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs660machine # [ 7.187805] systemd[1]: Mounting Kernel Configuration File System...661machine # [ 7.188894] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm662machine # [ 7.198773] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore663machine # [ 7.201867] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse664machine # [ 7.219951] systemd[1]: Mounting FUSE Control File System...665machine # [ 7.221497] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67666machine # [ 7.255235] systemd[1]: Starting Journal Service...667machine # [ 7.277386] systemd[1]: Starting Load Kernel Modules...668machine # [ 7.299392] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...669machine # [ 7.319904] systemd[1]: Starting Remount Root and Kernel File Systems...670machine # [ 7.320337] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os671machine # [ 7.330509] systemd[1]: Starting Coldplug All udev Devices...672machine # [ 7.340103] systemd-journald[269]: Collecting audit messages is enabled.673machine # [ 7.333708] systemd[1]: Queued start job for default target Multi-User System.674machine # [ 7.350464] systemd[1]: Started Journal Service.675machine # [ 7.339203] systemd[1]: systemd-journald.service: Deactivated successfully.676machine # [ 7.342012] systemd-modules-load[270]: Module 'atkbd' is built in677machine # [ 7.349598] systemd[1]: Listening on Journal Log Access Socket.678machine # [ 7.350630] systemd[1]: Mounted Huge Pages File System.679machine # [ 7.351395] systemd[1]: Mounted POSIX Message Queue File System.680machine # [ 7.359770] systemd[1]: Mounted Kernel Debug File System.681machine # [ 7.365205] systemd[1]: Mounted Kernel Trace File System.682machine # [ 7.366195] systemd-modules-load[270]: Module 'loop' is built in683machine # [ 7.367059] systemd[1]: Finished Create List of Static Device Nodes.684machine # [ 7.374288] systemd[1]: Finished Load Kernel Modules.685machine # [ 7.408312] systemd[1]: Starting Firewall...686machine # [ 7.416235] systemd[1]: Starting Apply Kernel Variables...687machine # [ 7.424097] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...688machine # [ 7.434983] systemd[1]: Mounted Kernel Configuration File System.689machine # [ 7.445421] systemd-oomd[272]: No swap; memory pressure usage will be degraded690machine # [ 7.465100] EXT4-fs (vda): re-mounted ce7a81f5-595c-41cb-88d7-20c367377512.691machine # [ 7.461631] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.692machine # [ 7.477941] systemd[1]: Finished Remount Root and Kernel File Systems.693machine # [ 7.492670] systemd[1]: Listening on Disk Image Download Service Socket.694machine # [ 7.506290] systemd[1]: Starting Flush Journal to Persistent Storage...695machine # [ 7.517372] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore696machine # [ 7.524661] systemd[1]: Starting Load/Save OS Random Seed...697machine # [ 7.525690] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os698machine # [ 7.526981] systemd[1]: Mounted FUSE Control File System.699machine # [ 7.623241] systemd-journald[269]: Received client request to flush runtime journal.700machine # [ 7.668463] systemd[1]: Finished Apply Kernel Variables.701machine # [ 7.669460] systemd[1]: Finished Load/Save OS Random Seed.702machine # [ 7.670245] systemd[1]: Reached target First Boot Complete.703machine # [ 7.678359] systemd[1]: Finished Flush Journal to Persistent Storage.704machine # [ 7.729006] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.705machine # [ 7.737365] systemd[1]: Starting Create Static Device Nodes in /dev...706machine # [ 8.032919] systemd[1]: Finished Create Static Device Nodes in /dev.707machine # [ 8.035256] systemd[1]: Reached target Preparation for Local File Systems.708machine # [ 8.042971] systemd[1]: Mounting /run/wrappers...709machine # [ 8.051525] systemd[1]: Starting Rule-based Manager for Device Events and Files...710machine # [ 8.141291] systemd[1]: Mounted /run/wrappers.711machine # [ 8.142149] systemd[1]: Reached target Local File Systems.712machine # [ 8.150281] systemd[1]: Listening on Boot Loader Control Service Socket.713machine # [ 8.158323] systemd[1]: Starting Create SUID/SGID Wrappers...714machine # [ 8.159313] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.715machine # [ 8.168328] systemd[1]: Starting Save Transient machine-id to Disk...716machine # [ 8.182657] systemd[1]: Starting Create System Files and Directories...717machine # [ 8.253720] systemd-udevd[309]: Using default interface naming scheme 'v261'.718machine # [ 8.385278] systemd[1]: Finished Create System Files and Directories.719machine # [ 8.405503] systemd[1]: Starting Rebuild Journal Catalog...720machine # [ 8.412321] systemd[1]: Starting Record System Boot/Shutdown in UTMP...721machine # [ 8.432186] systemd[1]: Started Rule-based Manager for Device Events and Files.722machine # [ 8.516364] systemd[1]: Finished Save Transient machine-id to Disk.723machine # [ 8.597301] systemd[1]: Finished Record System Boot/Shutdown in UTMP.724machine # [ 8.633861] systemd[1]: Finished Rebuild Journal Catalog.725machine # [ 8.646615] systemd[1]: Starting Update is Completed...726machine # [ 8.763078] systemd[1]: Finished Update is Completed.727machine # [ 8.829280] systemd[1]: Finished Coldplug All udev Devices.728machine # [ 8.969419] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs729machine # [ 9.005423] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse730machine # [ 9.024796] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.731machine # [ 9.103386] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.732machine # [ 9.107763] systemd[1]: Finished Create SUID/SGID Wrappers.733machine # [ 9.111995] systemd[1]: Reached target System Initialization.734machine # [ 9.115342] systemd[1]: Started Discard unused filesystem blocks once a week.735machine # [ 9.120543] systemd[1]: Started Daily Cleanup of Temporary Directories.736machine # [ 9.121579] systemd[1]: Reached target Timer Units.737machine # [ 9.122312] systemd[1]: Listening on D-Bus System Message Bus Socket.738machine # [ 9.123206] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.739machine # [ 9.134985] systemd[1]: Reached target Socket Units.740machine # [ 9.135768] systemd[1]: Reached target Basic System.741machine # [ 9.141392] systemd[1]: Starting Import lastlog data into lastlog2 database...742machine # [ 9.142504] systemd[1]: Starting Name Service Cache Daemon (nsncd)...743machine # [ 9.143414] systemd[1]: Starting Post-Boot Actions...744machine # [ 9.162392] systemd[1]: Started Reset console on configuration changes.745machine # [ 9.183782] systemd[1]: Starting resolvconf update...746machine # [ 9.239445] systemd[1]: Starting D-Bus System Message Bus...747machine # [ 9.347495] systemd[1]: Started Name Service Cache Daemon (nsncd).748machine # [ 9.354597] nsncd[423]: Sep 23 02:59:58.899 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"749machine # [ 9.369212] systemd[1]: Finished Post-Boot Actions.750machine # [ 9.383935] systemd[1]: Reached target Host and Network Name Lookups.751machine # [ 9.385454] systemd[1]: Reached target User and Group Name Lookups.752machine # [ 9.406559] systemd[1]: Starting User Login Management...753machine # [ 9.417879] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.754machine # [ 9.448443] systemd[1]: Finished Import lastlog data into lastlog2 database.755machine # [ 9.510986] dbus-broker-launch[428]: Looking up NSS user entry for 'systemd-timesync'...756machine # [ 9.522909] dbus-broker-launch[428]: NSS returned no entry for 'systemd-timesync'757machine # [ 9.530486] dbus-broker-launch[428]: Invalid user-name in /nix/store/w0wya9dwm0y8jgbs16l05by7m2pj9b4y-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"758machine # [ 9.548363] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.759machine # [ 9.560984] systemd[1]: Started backdoor.service.760machine # [ 9.607022] systemd[1]: Started D-Bus System Message Bus.761machine # [ 9.684792] dbus-broker-launch[428]: Ready762machine # [ 9.706673] systemd-logind[451]: New seat seat0.763machine # [ 9.719010] systemd[1]: Started User Login Management.764machine # [ 9.732363] systemd[1]: Starting linger-users.service...765machine # connecting to host...766machine # [ 9.797184] systemd[1]: Stopped target Host and Network Name Lookups.767machine # [ 9.805061] systemd[1]: Stopping Host and Network Name Lookups...768machine # [ 9.806176] systemd[1]: Stopped target User and Group Name Lookups.769machine # [ 9.807074] systemd[1]: Stopping User and Group Name Lookups...770machine # [ 9.807921] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...771machine # [ 9.827051] systemd[1]: nscd.service: Deactivated successfully.772machine # [ 9.830684] systemd[1]: Stopped Name Service Cache Daemon (nsncd).773machine: Guest shell says: b'Spawning backdoor root shell...\n'774machine: connected to guest root shell775machine: (connecting took 10.18 seconds)776machine: (finished: waiting for the VM to finish booting, in 10.72 seconds)777machine # [ 9.893997] systemd[1]: Starting Name Service Cache Daemon (nsncd)...778machine # [ 9.985539] systemd[1]: Created slice Slice /user/1001.779machine # [ 9.998220] (udev-worker)[382]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.780machine # [ 10.017384] systemd[1]: Starting User Runtime Directory /run/user/1001...781machine # [ 10.042929] nsncd[517]: Sep 23 02:59:59.592 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"782machine # [ 10.059719] (udev-worker)[382]: Network interface NamePolicy= disabled on kernel command line.783machine # [ 10.070644] systemd[1]: Started Name Service Cache Daemon (nsncd).784machine # [ 10.071817] systemd[1]: linger-users.service: Deactivated successfully.785machine # [ 10.079134] systemd[1]: Finished linger-users.service.786machine # [ 10.203344] systemd[1]: Reached target Host and Network Name Lookups.787machine # [ 10.209744] systemd[1]: Reached target User and Group Name Lookups.788machine # [ 10.224398] systemd[1]: Finished resolvconf update.789machine # [ 10.245725] systemd[1]: Starting DHCP Client...790machine # [ 10.355586] systemd[1]: Finished User Runtime Directory /run/user/1001.791machine # [ 10.411762] systemd[1]: Starting User Manager for UID 1001...792machine # [ 10.496680] systemd[1]: Condition check resulted in Virtio network device being skipped.793machine # [ 10.588663] systemd-logind[451]: Watching system buttons on /dev/input/event0 (gpio-keys)794machine # [ 10.636710] (systemd)[581]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)795machine # [ 10.669021] systemd-logind[451]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.796machine # [ 10.754973] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy797machine # [ 10.776169] mousedev: PS/2 mouse device common for all mice798machine # [ 11.049266] systemd-logind[451]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)799machine # [ 11.116575] systemd[581]: Queued start job for default target Main User Target.800machine # [ 11.117925] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy801machine # [ 11.119440] systemd[581]: Created slice User Application Slice.802machine # [ 11.224557] systemd[581]: Started Daily Cleanup of User's Temporary Directories.803machine # [ 11.225860] systemd[581]: Reached target Paths.804machine # [ 11.264703] systemd[581]: Reached target Timers.805machine # [ 11.277078] systemd[581]: Starting D-Bus User Message Bus Socket...806machine # [ 11.284607] systemd[581]: Starting Create User Files and Directories...807machine # [ 11.285749] systemd[1]: Finished Firewall.808machine # [ 11.286410] systemd[1]: Reached target Preparation for Network.809machine # [ 11.287292] systemd[1]: Starting Address configuration of eth1...810machine # [ 11.323980] systemd[1]: Starting Extra networking commands....811machine # [ 11.335691] systemd[581]: Finished Create User Files and Directories.812machine # [ 11.347811] systemd[581]: Listening on D-Bus User Message Bus Socket.813machine # [ 11.355736] systemd[581]: Reached target Sockets.814machine # [ 11.357202] systemd[581]: Reached target Basic System.815machine # [ 11.357982] systemd[1]: Started User Manager for UID 1001.816machine # [ 11.358780] systemd[581]: Starting Run user-specific NixOS activation...817machine # [ 11.359734] systemd[581]: Finished Run user-specific NixOS activation.818machine # [ 11.360746] systemd[581]: Reached target Main User Target.819machine # [ 11.361543] systemd[581]: Startup finished in 601ms.820machine # [ 11.438025] dhcpcd[587]: dhcpcd-10.3.2 starting821machine # [ 11.450173] dhcpcd[616]: dev: loaded udev822machine # [ 11.505876] 8021q: 802.1Q VLAN Support v1.8823machine # [ 11.571908] 8021q: adding VLAN 0 to HW filter on device eth1824machine # [ 11.618543] cfg80211: Loading compiled-in X.509 certificates for regulatory database825machine # [ 11.645496] network-addresses-eth1-start[604]: adding address 192.168.1.1/24... done826machine # [ 11.662095] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'827machine # [ 11.662683] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'828machine # [ 11.667317] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2829machine # [ 11.667649] cfg80211: failed to load regulatory.db830machine # [ 11.744273] network-addresses-eth1-start[604]: adding address 2001:db8:1::1/64... done831machine # [ 11.858229] systemd[1]: Finished Address configuration of eth1.832machine # [ 12.031526] dhcpcd[666]: /nix/store/1ki7bgdr0niilkg9jbkpcdaggbv0kb8a-openresolv-3.17.4/sbin/.resolvconf-wrapped: line 1250: kill: (618) - Operation not permitted833machine # [ 12.059432] dhcpcd[666]: .resolvconf-wrapped: clearing stale lock pid 618834machine # [ 12.285671] 8021q: adding VLAN 0 to HW filter on device eth0835machine # [ 12.276653] dhcpcd[616]: eth0: waiting for carrier836machine # [ 12.277542] dhcpcd[616]: eth0: carrier acquired837machine # [ 12.349337] dhcpcd[616]: DUID 00:01:00:01:32:45:fd:31:52:54:00:12:34:56838machine # [ 12.350448] dhcpcd[616]: eth0: IAID 00:12:34:56839machine # [ 12.351091] dhcpcd[616]: eth0: adding address fe80::5054:ff:fe12:3456840machine # [ 12.403556] systemd[1]: Finished Extra networking commands..841machine # [ 12.412367] systemd[1]: Reached target Network.842machine # [ 12.413321] systemd[1]: Starting Permit User Sessions...843machine # [ 12.506164] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3844machine # [ 12.616533] dhcpcd[616]: eth0: soliciting a DHCP lease845machine # [ 12.637382] dhcpcd[616]: eth0: offered 10.0.2.15 from 10.0.2.2846machine # [ 12.638437] dhcpcd[616]: eth0: probing address 10.0.2.15/24847machine # [ 12.680163] systemd[1]: Finished Permit User Sessions.848machine # [ 12.696776] systemd[1]: Started Getty on tty1.849machine # [ 12.697623] systemd[1]: Reached target Login Prompts.850machine # [ 12.948473] systemd[1]: Starting Virtual Console Setup...851machine # [ 12.978084] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.852machine # [ 13.026756] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.853machine # [ 13.040637] systemd[1]: Stopped Virtual Console Setup.854machine # [ 13.041509] systemd[1]: Starting Virtual Console Setup...855machine # [ 13.042598] systemd-logind[451]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)856machine # [ 13.188864] systemd-vconsole-setup[717]: Configuration of first virtual console was skipped, ignoring remaining ones.857machine # [ 13.196111] systemd[1]: Finished Virtual Console Setup.858machine # [ 14.759843] dhcpcd[616]: eth0: soliciting an IPv6 router859machine # [ 14.763821] dhcpcd[616]: eth0: Router Advertisement from fe80::2860machine # [ 14.766942] dhcpcd[616]: eth0: adding address fec0::5054:ff:fe12:3456/64861machine # [ 14.770036] dhcpcd[616]: eth0: adding route to fec0::/64862machine # [ 14.772497] dhcpcd[616]: eth0: adding default route via fe80::2863machine # [ 18.241007] dhcpcd[616]: eth0: leased 10.0.2.15 for 86400 seconds864machine # [ 18.246035] dhcpcd[616]: eth0: adding route to 10.0.2.0/24865machine # [ 18.247857] dhcpcd[616]: eth0: adding default route via 10.0.2.2866machine # [ 18.399111] systemd[1]: Started DHCP Client.867machine # [ 18.402018] systemd[1]: Reached target Multi-User System.868machine # [ 18.402981] systemd[1]: Startup finished in 1.026s (kernel) + 4.752s (initrd) + 12.622s (userspace) = 18.401s.869machine: (finished: waiting for unit multi-user.target, in 19.50 seconds)870machine: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test871machine # Checking switch inhibitors... done872machine # [ 18.963425] nixos[830]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test873machine # [ 18.966785] systemd[1]: Stopped target Local File Systems.874machine # activating the configuration...875machine # [ 18.969953] systemd[1]: Stopped target Remote File Systems.876machine # setting up /etc...877machine # [ 19.309002] systemd[1]: Reload requested from client PID 830 ('.switch-to-conf') (unit backdoor.service)...878machine # [ 19.311001] systemd[1]: Reloading...879machine # [ 19.676195] systemd[1]: Reloading finished in 363 ms.880machine # reloading user units for usertest...881machine # [ 19.730062] systemd[581]: Created slice User Core Session Slice.882machine # [ 19.733696] systemd[581]: Starting D-Bus User Message Bus...883machine # [ 19.771083] dbus-broker-launch[900]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/w0wya9dwm0y8jgbs16l05by7m2pj9b4y-system-path/share/dbus-1/services/org.freedesktop.import1.service'884machine # [ 19.774820] dbus-broker-launch[900]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/w0wya9dwm0y8jgbs16l05by7m2pj9b4y-system-path/share/dbus-1/services/org.freedesktop.machine1.service'885machine # [ 19.778096] dbus-broker-launch[900]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/w0wya9dwm0y8jgbs16l05by7m2pj9b4y-system-path/share/dbus-1/services/org.freedesktop.portable1.service'886machine # [ 19.781124] dbus-broker-launch[900]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/w0wya9dwm0y8jgbs16l05by7m2pj9b4y-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'887machine # [ 19.786466] dbus-broker-launch[900]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored888machine # [ 19.789994] dbus-broker-launch[900]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored889machine # [ 19.791725] systemd[581]: Started D-Bus User Message Bus.890machine # [ 19.796423] dbus-broker-launch[900]: Ready891machine # [ 19.846827] systemd[581]: Reexecution requested from client PID 899 ('switch-to-confi')...892machine # [ 19.850760] systemd[581]: Reexecuting.893machine # [ 19.903451] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy894machine # [ 20.029297] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy895machine # [ 20.036660] systemd[581]: Reload requested from client PID 899 ('switch-to-confi')...896machine # [ 20.040132] systemd[581]: Reloading...897machine # [ 20.161468] systemd[581]: Reloading finished in 121 ms.898machine # [ 20.176246] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy899machine # restarting the following user units: nixos-activation.service900machine # [ 20.189250] systemd[581]: Stopped Run user-specific NixOS activation.901machine # [ 20.191879] systemd[581]: Stopping Run user-specific NixOS activation...902machine # [ 20.194404] systemd[581]: Starting Run user-specific NixOS activation...903machine # [ 20.230868] systemd[581]: Finished Run user-specific NixOS activation.904machine # restarting sysinit-reactivation.target905machine # [ 20.243824] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....906machine # [ 20.335320] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..907machine # [ 20.337914] systemd[1]: Reached target Reactivate sysinit units.908machine # [ 20.341770] systemd[1]: Reached target Local File Systems.909machine # [ 20.345792] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore910machine # [ 20.350300] systemd[1]: Starting Create SUID/SGID Wrappers...911machine # [ 20.351256] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.912machine # [ 20.360659] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67913machine # [ 20.363069] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore914machine # [ 20.372967] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os915machine # [ 20.374386] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os916machine # [ 20.375657] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os917machine # [ 20.377144] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki918machine # [ 20.378449] systemd[1]: Reached target Remote File Systems.919machine # [ 20.550911] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.920machine # [ 20.552813] systemd[1]: Finished Create SUID/SGID Wrappers.921machine # [ 20.558712] systemd[1]: Starting linger-users.service...922machine # [ 20.607412] systemd[1]: linger-users.service: Deactivated successfully.923machine # [ 20.609393] systemd[1]: Finished linger-users.service.924machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service925machine # [ 20.875519] nixos[830]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test926machine: (finished: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test, in 2.24 seconds)927machine: must succeed: rm /etc/NIXOS928machine: (finished: must succeed: rm /etc/NIXOS, in 0.05 seconds)929machine: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/s9d0p34vf3pnv7gnbgyc62zk4szw7r36-nixos-system-other-test/bin/switch-to-configuration test930machine # this should succeed (config: /nix/store/s9d0p34vf3pnv7gnbgyc62zk4szw7r36-nixos-system-other-test, action: test)931machine # Checking switch inhibitors... done932machine # [ 21.227374] nixos[977]: switching to system configuration /nix/store/s9d0p34vf3pnv7gnbgyc62zk4szw7r36-nixos-system-other-test933machine # activating the configuration...934machine # [ 21.233546] systemd[1]: Stopped target Remote File Systems.935machine # [ 21.234485] systemd[1]: Stopped target Local File Systems.936machine # removing user ‘usertest’937machine # setting up /etc...938machine # [ 21.587449] systemd[1]: Reload requested from client PID 977 ('.switch-to-conf') (unit backdoor.service)...939machine # [ 21.589943] systemd[1]: Reloading...940machine # [ 21.975159] systemd[1]: Reloading finished in 383 ms.941machine # reloading user units for usertest...942machine # [ 22.076557] systemd[581]: Reexecution requested from client PID 1049 ('switch-to-confi')...943machine # [ 22.079999] systemd[581]: Reexecuting.944machine # [ 22.140086] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy945machine # [ 22.272640] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy946machine # [ 22.280519] systemd[581]: Reload requested from client PID 1049 ('switch-to-confi')...947machine # [ 22.281939] systemd[581]: Reloading...948machine # [ 22.412468] systemd[581]: Reloading finished in 129 ms.949machine # [ 22.428295] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy950machine # restarting the following user units: nixos-activation.service951machine # [ 22.441898] systemd[581]: Stopped Run user-specific NixOS activation.952machine # [ 22.442926] systemd[581]: Stopping Run user-specific NixOS activation...953machine # [ 22.445079] systemd[581]: Starting Run user-specific NixOS activation...954machine # [ 22.484709] systemd[581]: Finished Run user-specific NixOS activation.955machine # restarting sysinit-reactivation.target956machine # [ 22.490655] systemd[1]: Stopped target Reactivate sysinit units.957machine # reloading the following units: dbus-broker.service958machine # [ 22.495250] systemd[1]: Stopping Reactivate sysinit units...959machine # [ 22.498322] systemd[1]: Reached target Reactivate sysinit units.960machine # [ 22.499573] systemd[1]: Reloading D-Bus System Message Bus...961machine # [ 22.501161] dbus-broker-launch[428]: Caught SIGHUP, trigger reload.962machine # [ 22.508177] dbus-broker-launch[428]: Looking up NSS user entry for 'systemd-timesync'...963machine # [ 22.510748] dbus-broker-launch[428]: NSS returned no entry for 'systemd-timesync'964machine # [ 22.511945] dbus-broker-launch[428]: Invalid user-name in /nix/store/q6wjsw0nyp2xvbk5q7qp4h4kymalq12s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"965machine # [ 22.539873] systemd[1]: Reloaded D-Bus System Message Bus.966machine # restarting the following units: network-addresses-eth1.service967machine # [ 22.547958] systemd[1]: Stopping Address configuration of eth1...968machine # [ 22.585051] network-addresses-eth1-pre-stop[1061]: deleting address 192.168.1.1/24... done969machine # [ 22.593509] network-addresses-eth1-pre-stop[1061]: deleting address 2001:db8:1::1/64... done970machine # [ 22.602034] systemd[1]: network-addresses-eth1.service: Deactivated successfully.971machine # [ 22.604137] systemd[1]: Stopped Address configuration of eth1.972machine # [ 22.609448] systemd[1]: Starting Address configuration of eth1...973machine # [ 22.658302] network-addresses-eth1-start[1066]: adding address 192.168.1.2/24... done974machine # [ 22.666727] network-addresses-eth1-start[1066]: adding address 2001:db8:1::2/64... done975machine # [ 22.677103] systemd[1]: Finished Address configuration of eth1.976machine # [ 22.684621] systemd[1]: Reached target Remote File Systems.977machine # [ 22.687557] systemd[1]: Reached target Local File Systems.978machine # [ 22.691773] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore979machine # [ 22.696207] systemd[1]: Starting Create SUID/SGID Wrappers...980machine # [ 22.697285] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.981machine # [ 22.698552] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67982machine # [ 22.708882] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore983machine # [ 22.710606] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os984machine # [ 22.711881] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os985machine # [ 22.720176] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os986machine # [ 22.721559] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki987machine # [ 22.983769] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.988machine # [ 22.988615] systemd[1]: Finished Create SUID/SGID Wrappers.989machine # [ 22.991853] systemd[1]: Starting linger-users.service...990machine # [ 23.027720] linger-users-start[1138]: id: ‘usertest’: no such user991machine # [ 23.030061] linger-users-start[1136]: Removing linger for missing user usertest992machine # [ 23.038270] systemd[1]: linger-users.service: Deactivated successfully.993machine # [ 23.041253] systemd[1]: Finished linger-users.service.994machine # [ 23.298844] nixos[977]: finished switching to system configuration /nix/store/s9d0p34vf3pnv7gnbgyc62zk4szw7r36-nixos-system-other-test995machine: (finished: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/s9d0p34vf3pnv7gnbgyc62zk4szw7r36-nixos-system-other-test/bin/switch-to-configuration test, in 2.37 seconds)996machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1997machine # [ 23.578620] nixos[1144]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test998machine # [ 23.582039] systemd[1]: Stopped target Remote File Systems.999machine # [ 23.584876] systemd[1]: Stopped target Local File Systems.1000machine # [ 23.885121] systemd[1]: Reload requested from client PID 1144 ('.switch-to-conf') (unit backdoor.service)...1001machine # [ 23.887135] systemd[1]: Reloading...1002machine # [ 24.237832] systemd[1]: Reloading finished in 349 ms.1003machine # [ 24.332739] systemd[581]: Reexecution requested from client PID 1213 ('switch-to-confi')...1004machine # [ 24.337027] systemd[581]: Reexecuting.1005machine # [ 24.391634] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1006machine # [ 24.518688] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1007machine # [ 24.525939] systemd[581]: Reload requested from client PID 1213 ('switch-to-confi')...1008machine # [ 24.528691] systemd[581]: Reloading...1009machine # [ 24.648621] systemd[581]: Reloading finished in 118 ms.1010machine # [ 24.664194] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1011machine # [ 24.674263] systemd[581]: Stopped Run user-specific NixOS activation.1012machine # [ 24.676992] systemd[581]: Stopping Run user-specific NixOS activation...1013machine # [ 24.679298] systemd[581]: Starting Run user-specific NixOS activation...1014machine # [ 24.714534] systemd[581]: Finished Run user-specific NixOS activation.1015machine # [ 24.721318] systemd[1]: Stopped target Reactivate sysinit units.1016machine # [ 24.722647] systemd[1]: Stopping Reactivate sysinit units...1017machine # [ 24.723489] systemd[1]: Reached target Reactivate sysinit units.1018machine # [ 24.728774] systemd[1]: Reloading D-Bus System Message Bus...1019machine # [ 24.730501] dbus-broker-launch[428]: Caught SIGHUP, trigger reload.1020machine # [ 24.734457] dbus-broker-launch[428]: Looking up NSS user entry for 'systemd-timesync'...1021machine # [ 24.736754] dbus-broker-launch[428]: NSS returned no entry for 'systemd-timesync'1022machine # [ 24.737852] dbus-broker-launch[428]: Invalid user-name in /nix/store/w0wya9dwm0y8jgbs16l05by7m2pj9b4y-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1023machine # [ 24.765229] systemd[1]: Reloaded D-Bus System Message Bus.1024machine # [ 24.772002] systemd[1]: Stopping Address configuration of eth1...1025machine # [ 24.813388] network-addresses-eth1-pre-stop[1225]: deleting address 192.168.1.2/24... done1026machine # [ 24.825435] network-addresses-eth1-pre-stop[1225]: deleting address 2001:db8:1::2/64... done1027machine # [ 24.837514] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1028machine # [ 24.840842] systemd[1]: Stopped Address configuration of eth1.1029machine # [ 24.846092] systemd[1]: Starting Address configuration of eth1...1030machine # [ 24.912715] network-addresses-eth1-start[1230]: adding address 192.168.1.1/24... done1031machine # [ 24.920383] network-addresses-eth1-start[1230]: adding address 2001:db8:1::1/64... done1032machine # [ 24.930718] systemd[1]: Finished Address configuration of eth1.1033machine # [ 24.937942] systemd[1]: Reached target Local File Systems.1034machine # [ 24.943516] systemd[1]: Reached target Remote File Systems.1035machine # [ 24.948107] systemd[1]: Starting linger-users.service...1036machine # [ 24.960136] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1037machine # [ 24.972227] systemd[1]: Starting Create SUID/SGID Wrappers...1038machine # [ 24.973472] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1039machine # [ 24.974801] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671040machine # [ 24.983733] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1041machine # [ 24.988169] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1042machine # [ 24.989630] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1043machine # [ 24.990825] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1044machine # [ 24.998945] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1045machine # [ 25.027207] systemd[1]: linger-users.service: Deactivated successfully.1046machine # [ 25.029349] systemd[1]: Finished linger-users.service.1047machine # [ 25.194505] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1048machine # [ 25.196592] systemd[1]: Finished Create SUID/SGID Wrappers.1049machine # [ 25.460400] nixos[1144]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test1050machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.16 seconds)1051subtest: user services1052machine: waiting for unit user@1001.service1053machine: (finished: waiting for unit user@1001.service, in 0.10 seconds)1054machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11055machine # [ 25.796103] nixos[1304]: switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test1056machine # [ 25.798915] systemd[1]: Stopped target Local File Systems.1057machine # [ 25.801429] systemd[1]: Stopped target Remote File Systems.1058machine # [ 26.115544] systemd[1]: Reload requested from client PID 1304 ('.switch-to-conf') (unit backdoor.service)...1059machine # [ 26.117820] systemd[1]: Reloading...1060machine # [ 26.469569] systemd[1]: Reloading finished in 349 ms.1061machine # [ 26.561504] systemd[581]: Reexecution requested from client PID 1373 ('switch-to-confi')...1062machine # [ 26.565851] systemd[581]: Reexecuting.1063machine # [ 26.619189] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1064machine # [ 26.742234] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1065machine # [ 26.748868] systemd[581]: Reload requested from client PID 1373 ('switch-to-confi')...1066machine # [ 26.751432] systemd[581]: Reloading...1067machine # [ 26.873135] systemd[581]: Reloading finished in 120 ms.1068machine # [ 26.888193] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1069machine # [ 26.899364] systemd[581]: Starting usertest.service...1070machine # [ 26.927401] systemd[581]: Finished usertest.service.1071machine # [ 26.930450] systemd[581]: Stopped Run user-specific NixOS activation.1072machine # [ 26.935128] systemd[581]: Stopping Run user-specific NixOS activation...1073machine # [ 26.938196] systemd[581]: Starting Run user-specific NixOS activation...1074machine # [ 26.975702] systemd[581]: Finished Run user-specific NixOS activation.1075machine # [ 26.980496] systemd[1]: Stopped target Reactivate sysinit units.1076machine # [ 26.983488] systemd[1]: Stopping Reactivate sysinit units...1077machine # [ 26.985575] systemd[1]: Reached target Reactivate sysinit units.1078machine # [ 26.987709] systemd[1]: Reached target Remote File Systems.1079machine # [ 26.993425] systemd[1]: Starting linger-users.service...1080machine # [ 27.003804] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1081machine # [ 27.006016] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1082machine # [ 27.010504] systemd[1]: Reached target Local File Systems.1083machine # [ 27.013988] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1084machine # [ 27.021265] systemd[1]: Starting Create SUID/SGID Wrappers...1085machine # [ 27.022282] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1086machine # [ 27.023553] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671087machine # [ 27.036345] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1088machine # [ 27.039363] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1089machine # [ 27.043243] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1090machine # [ 27.088830] systemd[1]: linger-users.service: Deactivated successfully.1091machine # [ 27.093967] systemd[1]: Finished linger-users.service.1092machine # [ 27.267731] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1093machine # [ 27.270019] systemd[1]: Finished Create SUID/SGID Wrappers.1094machine # [ 27.531935] nixos[1304]: finished switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test1095machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.97 seconds)1096machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1097machine # [ 27.623788] sudo[1443]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1098machine # [ 27.645877] sudo[1443]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1099machine # [ 27.661723] sudo[1443]: pam_unix(sudo:session): session closed for user usertest1100machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.12 seconds)1101machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11102machine # [ 27.896823] nixos[1452]: switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test1103machine # [ 27.899408] systemd[1]: Stopped target Local File Systems.1104machine # [ 27.902002] systemd[1]: Stopped target Remote File Systems.1105machine # [ 28.242704] systemd[1]: Reload requested from client PID 1452 ('.switch-to-conf') (unit backdoor.service)...1106machine # [ 28.244832] systemd[1]: Reloading...1107machine # [ 28.612676] systemd[1]: Reloading finished in 366 ms.1108machine # [ 28.712410] systemd[581]: Reexecution requested from client PID 1522 ('switch-to-confi')...1109machine # [ 28.713933] systemd[581]: Reexecuting.1110machine # [ 28.775711] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1111machine # [ 28.906038] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1112machine # [ 28.913156] systemd[581]: Reload requested from client PID 1522 ('switch-to-confi')...1113machine # [ 28.917249] systemd[581]: Reloading...1114machine # [ 29.035700] systemd[581]: Reloading finished in 119 ms.1115machine # [ 29.052336] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1116machine # [ 29.062155] systemd[581]: Stopped Run user-specific NixOS activation.1117machine # [ 29.064848] systemd[581]: Stopping Run user-specific NixOS activation...1118machine # [ 29.067265] systemd[581]: Starting Run user-specific NixOS activation...1119machine # [ 29.103118] systemd[581]: Finished Run user-specific NixOS activation.1120machine # [ 29.107764] systemd[1]: Stopped target Reactivate sysinit units.1121machine # [ 29.110902] systemd[1]: Stopping Reactivate sysinit units...1122machine # [ 29.111802] systemd[1]: Reached target Reactivate sysinit units.1123machine # [ 29.115755] systemd[1]: Reached target Local File Systems.1124machine # [ 29.121293] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1125machine # [ 29.128572] systemd[1]: Starting Create SUID/SGID Wrappers...1126machine # [ 29.129598] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1127machine # [ 29.130943] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671128machine # [ 29.144835] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1129machine # [ 29.146590] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1130machine # [ 29.147878] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1131machine # [ 29.149760] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1132machine # [ 29.151183] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1133machine # [ 29.152649] systemd[1]: Reached target Remote File Systems.1134machine # [ 29.342430] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1135machine # [ 29.347618] systemd[1]: Finished Create SUID/SGID Wrappers.1136machine # [ 29.350059] systemd[1]: Starting linger-users.service...1137machine # [ 29.396723] systemd[1]: linger-users.service: Deactivated successfully.1138machine # [ 29.399898] systemd[1]: Finished linger-users.service.1139machine # [ 29.660705] nixos[1452]: finished switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test1140machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.01 seconds)1141machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&11142machine # [ 29.949075] nixos[1593]: switching to system configuration /nix/store/4i8x3ynh84c7jm18b0in82hwpxpfxqkb-nixos-system-machine-test1143machine # [ 29.951783] systemd[1]: Stopped target Remote File Systems.1144machine # [ 29.954662] systemd[1]: Stopped target Local File Systems.1145machine # [ 30.263823] systemd[1]: Reload requested from client PID 1593 ('.switch-to-conf') (unit backdoor.service)...1146machine # [ 30.266133] systemd[1]: Reloading...1147machine # [ 30.601372] systemd[1]: Reloading finished in 333 ms.1148machine # [ 30.695155] systemd[581]: Stopped usertest.service.1149machine # [ 30.698838] systemd[581]: Reexecution requested from client PID 1661 ('switch-to-confi')...1150machine # [ 30.701080] systemd[581]: Reexecuting.1151machine # [ 30.759298] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1152machine # [ 30.875569] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1153machine # [ 30.882666] systemd[581]: Reload requested from client PID 1661 ('switch-to-confi')...1154machine # [ 30.886558] systemd[581]: Reloading...1155machine # [ 30.999063] systemd[581]: Reloading finished in 113 ms.1156machine # [ 31.012186] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1157machine # [ 31.021898] systemd[581]: Starting usertest.service...1158machine # [ 31.050136] systemd[581]: Finished usertest.service.1159machine # [ 31.053075] systemd[581]: Stopped Run user-specific NixOS activation.1160machine # [ 31.055279] systemd[581]: Stopping Run user-specific NixOS activation...1161machine # [ 31.057519] systemd[581]: Starting Run user-specific NixOS activation...1162machine # [ 31.095600] systemd[581]: Finished Run user-specific NixOS activation.1163machine # [ 31.099639] systemd[1]: Stopped target Reactivate sysinit units.1164machine # [ 31.101512] systemd[1]: Stopping Reactivate sysinit units...1165machine # [ 31.102351] systemd[1]: Reached target Reactivate sysinit units.1166machine # [ 31.106358] systemd[1]: Reached target Local File Systems.1167machine # [ 31.113922] systemd[1]: Reached target Remote File Systems.1168machine # [ 31.117648] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1169machine # [ 31.119045] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1170machine # [ 31.120812] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1171machine # [ 31.123261] systemd[1]: Starting Create SUID/SGID Wrappers...1172machine # [ 31.128444] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1173machine # [ 31.129793] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671174machine # [ 31.131928] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1175machine # [ 31.139966] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1176machine # [ 31.142154] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1177machine # [ 31.308484] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1178machine # [ 31.312650] systemd[1]: Finished Create SUID/SGID Wrappers.1179machine # [ 31.315684] systemd[1]: Starting linger-users.service...1180machine # [ 31.359776] systemd[1]: linger-users.service: Deactivated successfully.1181machine # [ 31.362142] systemd[1]: Finished linger-users.service.1182machine # [ 31.622208] nixos[1593]: finished switching to system configuration /nix/store/4i8x3ynh84c7jm18b0in82hwpxpfxqkb-nixos-system-machine-test1183machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 1.96 seconds)1184machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1185machine # [ 31.698354] sudo[1733]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1186machine # [ 31.717677] sudo[1733]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1187machine # [ 31.733423] sudo[1733]: pam_unix(sudo:session): session closed for user usertest1188machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds)1189machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&11190machine # [ 31.959790] nixos[1742]: switching to system configuration /nix/store/a8iaf9wvnin33cj060m3q3zzx4qmnz9b-nixos-system-machine-test1191machine # [ 31.963265] systemd[1]: Stopped target Remote File Systems.1192machine # [ 31.965876] systemd[1]: Stopped target Local File Systems.1193machine # [ 32.268178] systemd[1]: Reload requested from client PID 1742 ('.switch-to-conf') (unit backdoor.service)...1194machine # [ 32.270191] systemd[1]: Reloading...1195machine # [ 32.718277] systemd[1]: Reloading finished in 446 ms.1196machine # [ 32.808442] systemd[581]: Reexecution requested from client PID 1810 ('switch-to-confi')...1197machine # [ 32.811856] systemd[581]: Reexecuting.1198machine # [ 32.859596] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1199machine # [ 32.978273] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1200machine # [ 32.985056] systemd[581]: Reload requested from client PID 1810 ('switch-to-confi')...1201machine # [ 32.986415] systemd[581]: Reloading...1202machine # [ 33.101279] systemd[581]: Reloading finished in 112 ms.1203machine # [ 33.116207] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1204machine # [ 33.123385] systemd[581]: Stopped usertest.service.1205machine # [ 33.125715] systemd[581]: Stopping usertest.service...1206machine # [ 33.127850] systemd[581]: Starting usertest.service...1207machine # [ 33.154693] systemd[581]: Finished usertest.service.1208machine # [ 33.159683] systemd[581]: Stopped Run user-specific NixOS activation.1209machine # [ 33.162224] systemd[581]: Stopping Run user-specific NixOS activation...1210machine # [ 33.164760] systemd[581]: Starting Run user-specific NixOS activation...1211machine # [ 33.199520] systemd[581]: Finished Run user-specific NixOS activation.1212machine # [ 33.204489] systemd[1]: Stopped target Reactivate sysinit units.1213machine # [ 33.205471] systemd[1]: Stopping Reactivate sysinit units...1214machine # [ 33.206255] systemd[1]: Reached target Reactivate sysinit units.1215machine # [ 33.210531] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1216machine # [ 33.211926] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1217machine # [ 33.220488] systemd[1]: Reached target Local File Systems.1218machine # [ 33.221396] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1219machine # [ 33.225705] systemd[1]: Starting Create SUID/SGID Wrappers...1220machine # [ 33.226656] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1221machine # [ 33.227885] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671222machine # [ 33.240256] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1223machine # [ 33.241958] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1224machine # [ 33.243187] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1225machine # [ 33.245315] systemd[1]: Reached target Remote File Systems.1226machine # [ 33.414292] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1227machine # [ 33.419239] systemd[1]: Finished Create SUID/SGID Wrappers.1228machine # [ 33.422409] systemd[1]: Starting linger-users.service...1229machine # [ 33.462623] systemd[1]: linger-users.service: Deactivated successfully.1230machine # [ 33.465753] systemd[1]: Finished linger-users.service.1231machine # [ 33.722945] nixos[1742]: finished switching to system configuration /nix/store/a8iaf9wvnin33cj060m3q3zzx4qmnz9b-nixos-system-machine-test1232machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 2.00 seconds)1233machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1234machine # [ 33.801614] sudo[1881]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1235machine # [ 33.819010] sudo[1881]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1236machine # [ 33.834222] sudo[1881]: pam_unix(sudo:session): session closed for user usertest1237machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds)1238machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&11239machine # [ 34.056146] nixos[1890]: switching to system configuration /nix/store/50qcsx3w4pq02qdi47da0skjd88vp1p8-nixos-system-machine-test1240machine # [ 34.059689] systemd[1]: Stopped target Remote File Systems.1241machine # [ 34.060893] systemd[1]: Stopped target Local File Systems.1242machine # [ 34.367114] systemd[1]: Reload requested from client PID 1890 ('.switch-to-conf') (unit backdoor.service)...1243machine # [ 34.369747] systemd[1]: Reloading...1244machine # [ 34.869618] systemd[1]: Reloading finished in 497 ms.1245machine # [ 34.998259] systemd[581]: Reexecution requested from client PID 1959 ('switch-to-confi')...1246machine # [ 35.002616] systemd[581]: Reexecuting.1247machine # [ 35.055568] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1248machine # [ 35.179128] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1249machine # [ 35.187327] systemd[581]: Reload requested from client PID 1959 ('switch-to-confi')...1250machine # [ 35.190074] systemd[581]: Reloading...1251machine # [ 35.312175] systemd[581]: Reloading finished in 121 ms.1252machine # [ 35.328251] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1253machine # [ 35.338041] systemd[581]: Reloading usertest.service...1254machine # [ 35.367724] systemd[581]: Reloaded usertest.service.1255machine # [ 35.373256] systemd[581]: Stopped Run user-specific NixOS activation.1256machine # [ 35.375706] systemd[581]: Stopping Run user-specific NixOS activation...1257machine # [ 35.378352] systemd[581]: Starting Run user-specific NixOS activation...1258machine # [ 35.414554] systemd[581]: Finished Run user-specific NixOS activation.1259machine # [ 35.419287] systemd[1]: Stopped target Reactivate sysinit units.1260machine # [ 35.422355] systemd[1]: Stopping Reactivate sysinit units...1261machine # [ 35.423256] systemd[1]: Reached target Reactivate sysinit units.1262machine # [ 35.427311] systemd[1]: Reached target Local File Systems.1263machine # [ 35.430503] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1264machine # [ 35.439232] systemd[1]: Starting Create SUID/SGID Wrappers...1265machine # [ 35.444745] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1266machine # [ 35.446089] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671267machine # [ 35.453897] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1268machine # [ 35.455666] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1269machine # [ 35.458725] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1270machine # [ 35.460084] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1271machine # [ 35.461473] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1272machine # [ 35.462799] systemd[1]: Reached target Remote File Systems.1273machine # [ 35.631199] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1274machine # [ 35.636262] systemd[1]: Finished Create SUID/SGID Wrappers.1275machine # [ 35.638498] systemd[1]: Starting linger-users.service...1276machine # [ 35.684189] systemd[1]: linger-users.service: Deactivated successfully.1277machine # [ 35.687393] systemd[1]: Finished linger-users.service.1278machine # [ 35.942441] nixos[1890]: finished switching to system configuration /nix/store/50qcsx3w4pq02qdi47da0skjd88vp1p8-nixos-system-machine-test1279machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 2.11 seconds)1280machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1281machine # [ 36.013975] sudo[2030]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1282machine # [ 36.032906] sudo[2030]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1283machine # [ 36.047004] sudo[2030]: pam_unix(sudo:session): session closed for user usertest1284machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds)1285machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11286machine # [ 36.255847] nixos[2039]: switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test1287machine # [ 36.258604] systemd[1]: Stopped target Local File Systems.1288machine # [ 36.261502] systemd[1]: Stopped target Remote File Systems.1289machine # [ 36.526969] systemd[1]: Reload requested from client PID 2039 ('.switch-to-conf') (unit backdoor.service)...1290machine # [ 36.529094] systemd[1]: Reloading...1291machine # [ 36.864976] systemd[1]: Reloading finished in 334 ms.1292machine # [ 36.955366] systemd[581]: Stopped usertest.service.1293machine # [ 36.957180] systemd[581]: Reexecution requested from client PID 2108 ('switch-to-confi')...1294machine # [ 36.960300] systemd[581]: Reexecuting.1295machine # [ 37.020834] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1296machine # [ 37.142246] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1297machine # [ 37.149224] systemd[581]: Reload requested from client PID 2108 ('switch-to-confi')...1298machine # [ 37.151879] systemd[581]: Reloading...1299machine # [ 37.269473] systemd[581]: Reloading finished in 116 ms.1300machine # [ 37.284211] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1301machine # [ 37.295887] systemd[581]: Starting usertest.service...1302machine # [ 37.327175] systemd[581]: Finished usertest.service.1303machine # [ 37.330367] systemd[581]: Stopped Run user-specific NixOS activation.1304machine # [ 37.332881] systemd[581]: Stopping Run user-specific NixOS activation...1305machine # [ 37.335222] systemd[581]: Starting Run user-specific NixOS activation...1306machine # [ 37.371138] systemd[581]: Finished Run user-specific NixOS activation.1307machine # [ 37.375821] systemd[1]: Stopped target Reactivate sysinit units.1308machine # [ 37.378516] systemd[1]: Stopping Reactivate sysinit units...1309machine # [ 37.379424] systemd[1]: Reached target Reactivate sysinit units.1310machine # [ 37.385098] systemd[1]: Reached target Local File Systems.1311machine # [ 37.386010] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1312machine # [ 37.396431] systemd[1]: Starting Create SUID/SGID Wrappers...1313machine # [ 37.397482] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1314machine # [ 37.398787] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671315machine # [ 37.407331] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1316machine # [ 37.416609] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1317machine # [ 37.418081] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1318machine # [ 37.420602] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1319machine # [ 37.422506] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1320machine # [ 37.424328] systemd[1]: Reached target Remote File Systems.1321machine # [ 37.587178] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1322machine # [ 37.591766] systemd[1]: Finished Create SUID/SGID Wrappers.1323machine # [ 37.594668] systemd[1]: Starting linger-users.service...1324machine # [ 37.639721] systemd[1]: linger-users.service: Deactivated successfully.1325machine # [ 37.642684] systemd[1]: Finished linger-users.service.1326machine # [ 37.901069] nixos[2039]: finished switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test1327machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.86 seconds)1328machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1329machine # [ 37.975703] sudo[2178]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1330machine # [ 37.994239] sudo[2178]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1331machine # [ 38.009528] sudo[2178]: pam_unix(sudo:session): session closed for user usertest1332machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds)1333machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&11334machine # [ 38.279134] nixos[2187]: switching to system configuration /nix/store/vx73wjicaqq07j8d3f1lwd8m2dv2rq5c-nixos-system-machine-test1335machine # [ 38.284895] systemd[1]: Stopped target Remote File Systems.1336machine # [ 38.286690] systemd[1]: Stopped target Local File Systems.1337machine # [ 38.584455] systemd[1]: Reload requested from client PID 2187 ('.switch-to-conf') (unit backdoor.service)...1338machine # [ 38.586112] systemd[1]: Reloading...1339machine # [ 38.963948] systemd[1]: Reloading finished in 376 ms.1340machine # [ 39.063012] systemd[581]: Reexecution requested from client PID 2257 ('switch-to-confi')...1341machine # [ 39.066564] systemd[581]: Reexecuting.1342machine # [ 39.119209] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1343machine # [ 39.281726] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1344machine # [ 39.289910] systemd[581]: Reload requested from client PID 2257 ('switch-to-confi')...1345machine # [ 39.292850] systemd[581]: Reloading...1346machine # [ 39.409300] systemd[581]: Reloading finished in 114 ms.1347machine # [ 39.424194] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1348machine # [ 39.433046] systemd[581]: Reloading usertest.service...1349machine # [ 39.458265] systemd[581]: Reloaded usertest.service.1350machine # [ 39.463012] systemd[581]: Stopped Run user-specific NixOS activation.1351machine # [ 39.465402] systemd[581]: Stopping Run user-specific NixOS activation...1352machine # [ 39.467430] systemd[581]: Starting Run user-specific NixOS activation...1353machine # [ 39.499943] systemd[581]: Finished Run user-specific NixOS activation.1354machine # [ 39.503966] systemd[1]: Stopped target Reactivate sysinit units.1355machine # [ 39.505454] systemd[1]: Stopping Reactivate sysinit units...1356machine # [ 39.506282] systemd[1]: Reached target Reactivate sysinit units.1357machine # [ 39.511697] systemd[1]: Reached target Local File Systems.1358machine # [ 39.516208] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1359machine # [ 39.517786] systemd[1]: Starting Create SUID/SGID Wrappers...1360machine # [ 39.530064] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1361machine # [ 39.531469] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671362machine # [ 39.536913] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1363machine # [ 39.538554] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1364machine # [ 39.539792] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1365machine # [ 39.541110] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1366machine # [ 39.542389] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1367machine # [ 39.543636] systemd[1]: Reached target Remote File Systems.1368machine # [ 39.705996] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1369machine # [ 39.711330] systemd[1]: Finished Create SUID/SGID Wrappers.1370machine # [ 39.714200] systemd[1]: Starting linger-users.service...1371machine # [ 39.762708] systemd[1]: linger-users.service: Deactivated successfully.1372machine # [ 39.764633] systemd[1]: Finished linger-users.service.1373machine # [ 40.023758] nixos[2187]: finished switching to system configuration /nix/store/vx73wjicaqq07j8d3f1lwd8m2dv2rq5c-nixos-system-machine-test1374machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 2.02 seconds)1375machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1376machine # [ 40.099597] sudo[2327]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1377machine # [ 40.121515] sudo[2327]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1378machine # [ 40.140359] sudo[2327]: pam_unix(sudo:session): session closed for user usertest1379machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds)1380machine: must fail: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&11381machine # [ 40.365017] nixos[2336]: switching to system configuration /nix/store/qc65grcdj9gag3m6psyy82x3f1ydly45-nixos-system-machine-test1382machine # [ 40.367827] systemd[1]: Stopped target Local File Systems.1383machine # [ 40.370317] systemd[1]: Stopped target Remote File Systems.1384machine # [ 40.673648] systemd[1]: Reload requested from client PID 2336 ('.switch-to-conf') (unit backdoor.service)...1385machine # [ 40.675670] systemd[1]: Reloading...1386machine # [ 41.014184] systemd[1]: Reloading finished in 336 ms.1387machine # [ 41.110563] systemd[581]: Stopped usertest.service.1388machine # [ 41.113843] systemd[581]: Reexecution requested from client PID 2405 ('switch-to-confi')...1389machine # [ 41.115184] systemd[581]: Reexecuting.1390machine # [ 41.172287] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1391machine # [ 41.295766] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1392machine # [ 41.302567] systemd[581]: Reload requested from client PID 2405 ('switch-to-confi')...1393machine # [ 41.306870] systemd[581]: Reloading...1394machine # [ 41.420606] systemd[581]: Reloading finished in 114 ms.1395machine # [ 41.436200] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1396machine # [ 41.451273] systemd[581]: Starting usertest.service...1397machine # [ 41.497574] systemd[581]: usertest.service: Main process exited, code=exited, status=1/FAILURE1398machine # [ 41.499062] systemd[581]: usertest.service: Failed with result 'exit-code'.1399machine # [ 41.504422] systemd[581]: Failed to start usertest.service.1400machine # [ 41.507926] systemd[581]: Stopped Run user-specific NixOS activation.1401machine # [ 41.510081] systemd[581]: Stopping Run user-specific NixOS activation...1402machine # [ 41.519784] systemd[581]: Starting Run user-specific NixOS activation...1403machine # [ 41.559523] systemd[581]: Finished Run user-specific NixOS activation.1404machine # [ 41.564849] systemd[1]: Stopped target Reactivate sysinit units.1405machine # [ 41.565825] systemd[1]: Stopping Reactivate sysinit units...1406machine # [ 41.566644] systemd[1]: Reached target Reactivate sysinit units.1407machine # [ 41.570457] systemd[1]: Reached target Remote File Systems.1408machine # [ 41.573180] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1409machine # [ 41.579308] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1410machine # [ 41.580848] systemd[1]: Reached target Local File Systems.1411machine # [ 41.581651] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1412machine # [ 41.585470] systemd[1]: Starting Create SUID/SGID Wrappers...1413machine # [ 41.586403] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1414machine # [ 41.587630] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671415machine # [ 41.596946] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1416machine # [ 41.598540] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1417machine # [ 41.599785] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1418machine # [ 41.771003] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1419machine # [ 41.773245] systemd[1]: Finished Create SUID/SGID Wrappers.1420machine # [ 41.778194] systemd[1]: Starting linger-users.service...1421machine # [ 41.818824] systemd[1]: linger-users.service: Deactivated successfully.1422machine # [ 41.821563] systemd[1]: Finished linger-users.service.1423machine # [ 42.079984] nixos[2336]: switching to system configuration /nix/store/qc65grcdj9gag3m6psyy82x3f1ydly45-nixos-system-machine-test failed (status 4)1424machine: (finished: must fail: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 1.95 seconds)1425machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11426machine # [ 42.353917] nixos[2479]: switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test1427machine # [ 42.356365] systemd[1]: Stopped target Local File Systems.1428machine # [ 42.358663] systemd[1]: Stopped target Remote File Systems.1429machine # [ 42.628598] systemd[1]: Reload requested from client PID 2479 ('.switch-to-conf') (unit backdoor.service)...1430machine # [ 42.630173] systemd[1]: Reloading...1431machine # [ 43.107624] systemd[1]: Reloading finished in 476 ms.1432machine # [ 43.196422] systemd[581]: Reexecution requested from client PID 2548 ('switch-to-confi')...1433machine # [ 43.200489] systemd[581]: Reexecuting.1434machine # [ 43.255508] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1435machine # [ 43.374295] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1436machine # [ 43.381743] systemd[581]: Reload requested from client PID 2548 ('switch-to-confi')...1437machine # [ 43.383065] systemd[581]: Reloading...1438machine # [ 43.495165] systemd[581]: Reloading finished in 110 ms.1439machine # [ 43.512181] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1440machine # [ 43.523221] systemd[581]: Starting usertest.service...1441machine # [ 43.550103] systemd[581]: Finished usertest.service.1442machine # [ 43.552451] systemd[581]: Stopped Run user-specific NixOS activation.1443machine # [ 43.554691] systemd[581]: Stopping Run user-specific NixOS activation...1444machine # [ 43.556819] systemd[581]: Starting Run user-specific NixOS activation...1445machine # [ 43.588748] systemd[581]: Finished Run user-specific NixOS activation.1446machine # [ 43.592257] systemd[1]: Stopped target Reactivate sysinit units.1447machine # [ 43.594996] systemd[1]: Stopping Reactivate sysinit units...1448machine # [ 43.595906] systemd[1]: Reached target Reactivate sysinit units.1449machine # [ 43.600466] systemd[1]: Reached target Remote File Systems.1450machine # [ 43.602786] systemd[1]: Starting linger-users.service...1451machine # [ 43.614285] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1452machine # [ 43.615747] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1453machine # [ 43.629278] systemd[1]: Reached target Local File Systems.1454machine # [ 43.630155] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1455machine # [ 43.638772] systemd[1]: Starting Create SUID/SGID Wrappers...1456machine # [ 43.639730] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1457machine # [ 43.641812] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671458machine # [ 43.646494] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1459machine # [ 43.651397] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1460machine # [ 43.656916] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1461machine # [ 43.694123] systemd[1]: linger-users.service: Deactivated successfully.1462machine # [ 43.697374] systemd[1]: Finished linger-users.service.1463machine # [ 43.856548] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1464machine # [ 43.859574] systemd[1]: Finished Create SUID/SGID Wrappers.1465machine # [ 44.118380] nixos[2479]: finished switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test1466machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.04 seconds)1467machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1468machine # [ 44.193334] sudo[2618]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1469machine # [ 44.214522] sudo[2618]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1470machine # [ 44.231016] sudo[2618]: pam_unix(sudo:session): session closed for user usertest1471machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds)1472machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&11473machine # [ 44.446955] nixos[2627]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test1474machine # [ 44.450991] systemd[1]: Stopped target Local File Systems.1475machine # [ 44.451877] systemd[1]: Stopped target Remote File Systems.1476machine # [ 44.719219] systemd[1]: Reload requested from client PID 2627 ('.switch-to-conf') (unit backdoor.service)...1477machine # [ 44.721455] systemd[1]: Reloading...1478machine # [ 45.059035] systemd[1]: Reloading finished in 336 ms.1479machine # [ 45.182907] systemd[581]: Stopped usertest.service.1480machine # [ 45.186820] systemd[581]: Reexecution requested from client PID 2696 ('switch-to-confi')...1481machine # [ 45.188933] systemd[581]: Reexecuting.1482machine # [ 45.222923] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1483machine # [ 45.335108] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1484machine # [ 45.341788] systemd[581]: Reload requested from client PID 2696 ('switch-to-confi')...1485machine # [ 45.344293] systemd[581]: Reloading...1486machine # [ 45.454139] systemd[581]: Reloading finished in 108 ms.1487machine # [ 45.468180] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1488machine # [ 45.477774] systemd[581]: Stopped Run user-specific NixOS activation.1489machine # [ 45.480328] systemd[581]: Stopping Run user-specific NixOS activation...1490machine # [ 45.482495] systemd[581]: Starting Run user-specific NixOS activation...1491machine # [ 45.516669] systemd[581]: Finished Run user-specific NixOS activation.1492machine # [ 45.520153] systemd[1]: Stopped target Reactivate sysinit units.1493machine # [ 45.522674] systemd[1]: Stopping Reactivate sysinit units...1494machine # [ 45.523579] systemd[1]: Reached target Reactivate sysinit units.1495machine # [ 45.526424] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1496machine # [ 45.527755] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1497machine # [ 45.532292] systemd[1]: Reached target Remote File Systems.1498machine # [ 45.535629] systemd[1]: Starting linger-users.service...1499machine # [ 45.538808] systemd[1]: Reached target Local File Systems.1500machine # [ 45.550735] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1501machine # [ 45.556346] systemd[1]: Starting Create SUID/SGID Wrappers...1502machine # [ 45.557270] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1503machine # [ 45.558528] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671504machine # [ 45.570799] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1505machine # [ 45.575648] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1506machine # [ 45.582182] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1507machine # [ 45.623057] systemd[1]: linger-users.service: Deactivated successfully.1508machine # [ 45.626142] systemd[1]: Finished linger-users.service.1509machine # [ 45.765765] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1510machine # [ 45.768549] systemd[1]: Finished Create SUID/SGID Wrappers.1511machine # [ 46.027173] nixos[2627]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test1512machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.80 seconds)1513machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1514machine # [ 46.107696] sudo[2765]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1515machine # [ 46.129278] sudo[2765]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1516machine # [ 46.147689] sudo[2765]: pam_unix(sudo:session): session closed for user usertest1517machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds)1518machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1519machine # [ 46.185900] sudo[2771]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1520machine # [ 46.198165] sudo[2771]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1521machine # [ 46.207790] sudo[2771]: pam_unix(sudo:session): session closed for user usertest1522machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)1523machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service1524machine # [ 46.238812] sudo[2777]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service1525machine # [ 46.249851] sudo[2777]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1526machine # [ 46.259191] sudo[2777]: pam_unix(sudo:session): session closed for user usertest1527machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1528machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1529machine # [ 46.287590] sudo[2783]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service1530machine # [ 46.298103] sudo[2783]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1531machine # [ 46.307270] sudo[2783]: pam_unix(sudo:session): session closed for user usertest1532machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)1533machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1534machine # [ 46.335785] sudo[2789]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1535machine # [ 46.345837] sudo[2789]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1536machine # [ 46.354264] systemd[581]: Reload requested from client PID 2794 ('systemctl')...1537machine # [ 46.358217] systemd[581]: Reloading...1538machine # [ 46.471673] systemd[581]: Reloading finished in 114 ms.1539machine # [ 46.488181] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1540machine # [ 46.499542] sudo[2789]: pam_unix(sudo:session): session closed for user usertest1541machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)1542machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1543machine # [ 46.531132] sudo[2799]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1544machine # [ 46.541731] sudo[2799]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1545machine # [ 46.557276] systemd[581]: Starting migrated.service...1546machine # [ 46.585143] systemd[581]: Finished migrated.service.1547machine # [ 46.590676] sudo[2799]: pam_unix(sudo:session): session closed for user usertest1548machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)1549machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1550machine # [ 46.622864] sudo[2807]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1551machine # [ 46.633474] sudo[2807]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1552machine # [ 46.645237] sudo[2807]: pam_unix(sudo:session): session closed for user usertest1553machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1554machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1555machine # [ 46.676595] sudo[2813]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1556machine # [ 46.686808] sudo[2813]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1557machine # [ 46.695827] sudo[2813]: pam_unix(sudo:session): session closed for user usertest1558machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1559machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1560machine # [ 46.727334] sudo[2819]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1561machine # [ 46.738018] sudo[2819]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1562machine # [ 46.756264] sudo[2819]: pam_unix(sudo:session): session closed for user usertest1563machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)1564machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&11565machine # [ 46.959716] nixos[2828]: switching to system configuration /nix/store/myjnxfyq1mwc9jy082z37f6ldmvx2v36-nixos-system-machine-test1566machine # [ 46.962691] systemd[1]: Stopped target Local File Systems.1567machine # [ 46.965109] systemd[1]: Stopped target Remote File Systems.1568machine # [ 47.254917] systemd[1]: Reload requested from client PID 2828 ('.switch-to-conf') (unit backdoor.service)...1569machine # [ 47.256973] systemd[1]: Reloading...1570machine # [ 47.577554] systemd[1]: Reloading finished in 318 ms.1571machine # [ 47.671368] systemd[581]: Reexecution requested from client PID 2897 ('switch-to-confi')...1572machine # [ 47.674268] systemd[581]: Reexecuting.1573machine # [ 47.732511] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1574machine # [ 47.853320] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1575machine # [ 47.860114] systemd[581]: Reload requested from client PID 2897 ('switch-to-confi')...1576machine # [ 47.861529] systemd[581]: Reloading...1577machine # [ 47.974669] systemd[581]: Reloading finished in 111 ms.1578machine # [ 47.992197] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1579machine # [ 48.001372] systemd[581]: Stopped Run user-specific NixOS activation.1580machine # [ 48.003824] systemd[581]: Stopping Run user-specific NixOS activation...1581machine # [ 48.006110] systemd[581]: Starting Run user-specific NixOS activation...1582machine # [ 48.070394] systemd[581]: Reload requested from client PID 2909 ('systemctl') (unit nixos-activation.service)...1583machine # [ 48.075230] systemd[581]: Reloading...1584machine # [ 48.193960] systemd[581]: Reloading finished in 120 ms.1585machine # [ 48.212223] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1586machine # [ 48.232773] systemd[581]: Stopped migrated.service.1587machine # [ 48.244166] systemd[581]: Finished Run user-specific NixOS activation.1588machine # [ 48.246981] systemd[581]: Reload requested from client PID 2897 ('switch-to-confi')...1589machine # [ 48.251288] systemd[581]: Reloading...1590machine # [ 48.366094] systemd[581]: Reloading finished in 114 ms.1591machine # [ 48.380177] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1592machine # [ 48.391992] systemd[581]: Starting migrated.service...1593machine # [ 48.419704] systemd[581]: Finished migrated.service.1594machine # [ 48.424487] systemd[1]: Stopped target Reactivate sysinit units.1595machine # [ 48.428361] systemd[1]: Stopping Reactivate sysinit units...1596machine # [ 48.429338] systemd[1]: Reached target Reactivate sysinit units.1597machine # [ 48.430329] systemd[1]: Reached target Local File Systems.1598machine # [ 48.433852] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1599machine # [ 48.444253] systemd[1]: Starting Create SUID/SGID Wrappers...1600machine # [ 48.445236] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1601machine # [ 48.446451] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671602machine # [ 48.455668] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1603machine # [ 48.459120] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1604machine # [ 48.460894] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1605machine # [ 48.462215] systemd[1]: Reached target Remote File Systems.1606machine # [ 48.463370] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1607machine # [ 48.465418] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1608machine # [ 48.630006] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1609machine # [ 48.634953] systemd[1]: Finished Create SUID/SGID Wrappers.1610machine # [ 48.637716] systemd[1]: Starting linger-users.service...1611machine # [ 48.682404] systemd[1]: linger-users.service: Deactivated successfully.1612machine # [ 48.685434] systemd[1]: Finished linger-users.service.1613machine # [ 48.946302] nixos[2828]: finished switching to system configuration /nix/store/myjnxfyq1mwc9jy082z37f6ldmvx2v36-nixos-system-machine-test1614machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 2.20 seconds)1615machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1616machine # [ 49.021890] sudo[2979]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1617machine # [ 49.043731] sudo[2979]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1618machine # [ 49.061286] sudo[2979]: pam_unix(sudo:session): session closed for user usertest1619machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds)1620machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1621machine # [ 49.100553] sudo[2985]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1622machine # [ 49.114721] sudo[2985]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1623machine # [ 49.135090] sudo[2985]: pam_unix(sudo:session): session closed for user usertest1624machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)1625machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1626machine # [ 49.165523] sudo[2991]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1627machine # [ 49.175590] sudo[2991]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1628machine # [ 49.184288] sudo[2991]: pam_unix(sudo:session): session closed for user usertest1629machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1630machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&11631machine # [ 49.385253] nixos[3000]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test1632machine # [ 49.388800] systemd[1]: Stopped target Remote File Systems.1633machine # [ 49.389742] systemd[1]: Stopped target Local File Systems.1634machine # [ 49.663625] systemd[1]: Reload requested from client PID 3000 ('.switch-to-conf') (unit backdoor.service)...1635machine # [ 49.665687] systemd[1]: Reloading...1636machine # [ 49.987121] systemd[1]: Reloading finished in 320 ms.1637machine # [ 50.080961] systemd[581]: Stopped migrated.service.1638machine # [ 50.084806] systemd[581]: Reexecution requested from client PID 3069 ('switch-to-confi')...1639machine # [ 50.086177] systemd[581]: Reexecuting.1640machine # [ 50.143721] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1641machine # [ 50.261547] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1642machine # [ 50.268345] systemd[581]: Reload requested from client PID 3069 ('switch-to-confi')...1643machine # [ 50.272212] systemd[581]: Reloading...1644machine # [ 50.381911] systemd[581]: Reloading finished in 110 ms.1645machine # [ 50.396190] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1646machine # [ 50.405463] systemd[581]: Stopped Run user-specific NixOS activation.1647machine # [ 50.407822] systemd[581]: Stopping Run user-specific NixOS activation...1648machine # [ 50.410029] systemd[581]: Starting Run user-specific NixOS activation...1649machine # [ 50.439771] systemd[581]: Finished Run user-specific NixOS activation.1650machine # [ 50.443756] systemd[1]: Stopped target Reactivate sysinit units.1651machine # [ 50.449166] systemd[1]: Stopping Reactivate sysinit units...1652machine # [ 50.450066] systemd[1]: Reached target Reactivate sysinit units.1653machine # [ 50.450888] systemd[1]: Reached target Local File Systems.1654machine # [ 50.451670] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1655machine # [ 50.456408] systemd[1]: Starting Create SUID/SGID Wrappers...1656machine # [ 50.457363] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1657machine # [ 50.458554] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671658machine # [ 50.471673] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1659machine # [ 50.477810] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1660machine # [ 50.479145] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1661machine # [ 50.481069] systemd[1]: Reached target Remote File Systems.1662machine # [ 50.481930] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1663machine # [ 50.483586] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1664machine # [ 50.641211] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1665machine # [ 50.642911] systemd[1]: Finished Create SUID/SGID Wrappers.1666machine # [ 50.647813] systemd[1]: Starting linger-users.service...1667machine # [ 50.693419] systemd[1]: linger-users.service: Deactivated successfully.1668machine # [ 50.696816] systemd[1]: Finished linger-users.service.1669machine # [ 50.954433] nixos[3000]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test1670machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.78 seconds)1671machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1672machine # [ 51.032612] sudo[3137]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1673machine # [ 51.053178] sudo[3137]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1674machine # [ 51.071187] sudo[3137]: pam_unix(sudo:session): session closed for user usertest1675machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds)1676machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1677machine # [ 51.107990] sudo[3143]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1678machine # [ 51.120827] sudo[3143]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1679machine # [ 51.131044] sudo[3143]: pam_unix(sudo:session): session closed for user usertest1680machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)1681machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service1682machine # [ 51.165076] sudo[3149]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service1683machine # [ 51.175934] sudo[3149]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1684machine # [ 51.184998] sudo[3149]: pam_unix(sudo:session): session closed for user usertest1685machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1686machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1687machine # [ 51.214695] sudo[3155]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service1688machine # [ 51.225002] sudo[3155]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1689machine # [ 51.233489] sudo[3155]: pam_unix(sudo:session): session closed for user usertest1690machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)1691machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1692machine # [ 51.263480] sudo[3161]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1693machine # [ 51.274027] sudo[3161]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1694machine # [ 51.283217] systemd[581]: Reload requested from client PID 3166 ('systemctl')...1695machine # [ 51.287707] systemd[581]: Reloading...1696machine # [ 51.401750] systemd[581]: Reloading finished in 115 ms.1697machine # [ 51.416249] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1698machine # [ 51.428115] sudo[3161]: pam_unix(sudo:session): session closed for user usertest1699machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)1700machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1701machine # [ 51.457429] sudo[3171]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1702machine # [ 51.467251] sudo[3171]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1703machine # [ 51.480968] systemd[581]: Starting migrated.service...1704machine # [ 51.507946] systemd[581]: Finished migrated.service.1705machine # [ 51.512196] sudo[3171]: pam_unix(sudo:session): session closed for user usertest1706machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)1707machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1708machine # [ 51.541465] sudo[3179]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1709machine # [ 51.551212] sudo[3179]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1710machine # [ 51.562073] sudo[3179]: pam_unix(sudo:session): session closed for user usertest1711machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1712machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1713machine # [ 51.591116] sudo[3185]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1714machine # [ 51.601371] sudo[3185]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1715machine # [ 51.609652] sudo[3185]: pam_unix(sudo:session): session closed for user usertest1716machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1717machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1718machine # [ 51.637983] sudo[3191]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1719machine # [ 51.647857] sudo[3191]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1720machine # [ 51.664612] sudo[3191]: pam_unix(sudo:session): session closed for user usertest1721machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)1722machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&11723machine # [ 51.862728] nixos[3200]: switching to system configuration /nix/store/03z9zcv1kgd2f5s3mi96w4zwxjz7skhk-nixos-system-machine-test1724machine # [ 51.865624] systemd[1]: Stopped target Local File Systems.1725machine # [ 51.867857] systemd[1]: Stopped target Remote File Systems.1726machine # [ 52.154289] systemd[1]: Reload requested from client PID 3200 ('.switch-to-conf') (unit backdoor.service)...1727machine # [ 52.156309] systemd[1]: Reloading...1728machine # [ 52.485222] systemd[1]: Reloading finished in 327 ms.1729machine # [ 52.584264] systemd[581]: Reexecution requested from client PID 3269 ('switch-to-confi')...1730machine # [ 52.588634] systemd[581]: Reexecuting.1731machine # [ 52.643784] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1732machine # [ 52.769900] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1733machine # [ 52.777050] systemd[581]: Reload requested from client PID 3269 ('switch-to-confi')...1734machine # [ 52.779489] systemd[581]: Reloading...1735machine # [ 52.903139] systemd[581]: Reloading finished in 122 ms.1736machine # [ 52.920211] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1737machine # [ 52.930909] systemd[581]: Stopped Run user-specific NixOS activation.1738machine # [ 52.931991] systemd[581]: Stopping Run user-specific NixOS activation...1739machine # [ 52.934510] systemd[581]: Starting Run user-specific NixOS activation...1740machine # [ 52.974869] systemd[581]: Reload requested from client PID 3281 ('systemctl') (unit nixos-activation.service)...1741machine # [ 52.979731] systemd[581]: Reloading...1742machine # [ 53.098688] systemd[581]: Reloading finished in 120 ms.1743machine # [ 53.116199] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1744machine # [ 53.133199] systemd[581]: Finished Run user-specific NixOS activation.1745machine # [ 53.135890] systemd[581]: Reload requested from client PID 3269 ('switch-to-confi')...1746machine # [ 53.139959] systemd[581]: Reloading...1747machine # [ 53.253927] systemd[581]: Reloading finished in 113 ms.1748machine # [ 53.268173] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1749machine # [ 53.279729] systemd[581]: Stopped migrated.service.1750machine # [ 53.280781] systemd[581]: Stopping migrated.service...1751machine # [ 53.282607] systemd[581]: Starting migrated.service...1752machine # [ 53.310937] systemd[581]: Finished migrated.service.1753machine # [ 53.317602] systemd[1]: Stopped target Reactivate sysinit units.1754machine # [ 53.319993] systemd[1]: Stopping Reactivate sysinit units...1755machine # [ 53.321704] systemd[1]: Reached target Reactivate sysinit units.1756machine # [ 53.323362] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1757machine # [ 53.328582] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1758machine # [ 53.329901] systemd[1]: Reached target Remote File Systems.1759machine # [ 53.333302] systemd[1]: Starting linger-users.service...1760machine # [ 53.345694] systemd[1]: Reached target Local File Systems.1761machine # [ 53.346602] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1762machine # [ 53.358563] systemd[1]: Starting Create SUID/SGID Wrappers...1763machine # [ 53.359456] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1764machine # [ 53.360898] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671765machine # [ 53.363098] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1766machine # [ 53.367018] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1767machine # [ 53.371139] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1768machine # [ 53.418524] systemd[1]: linger-users.service: Deactivated successfully.1769machine # [ 53.422228] systemd[1]: Finished linger-users.service.1770machine # [ 53.556359] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1771machine # [ 53.559604] systemd[1]: Finished Create SUID/SGID Wrappers.1772machine # [ 53.818347] nixos[3200]: finished switching to system configuration /nix/store/03z9zcv1kgd2f5s3mi96w4zwxjz7skhk-nixos-system-machine-test1773machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 2.17 seconds)1774machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1775machine # [ 53.897297] sudo[3350]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1776machine # [ 53.917278] sudo[3350]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1777machine # [ 53.932296] sudo[3350]: pam_unix(sudo:session): session closed for user usertest1778machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)1779machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1780machine # [ 53.969495] sudo[3356]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1781machine # [ 53.982758] sudo[3356]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1782machine # [ 54.005449] sudo[3356]: pam_unix(sudo:session): session closed for user usertest1783machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)1784machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1785machine # [ 54.039008] sudo[3362]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1786machine # [ 54.051129] sudo[3362]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1787machine # [ 54.059774] sudo[3362]: pam_unix(sudo:session): session closed for user usertest1788machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1789machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&11790machine # [ 54.272338] nixos[3371]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test1791machine # [ 54.274819] systemd[1]: Stopped target Local File Systems.1792machine # [ 54.277215] systemd[1]: Stopped target Remote File Systems.1793machine # [ 54.547521] systemd[1]: Reload requested from client PID 3371 ('.switch-to-conf') (unit backdoor.service)...1794machine # [ 54.549663] systemd[1]: Reloading...1795machine # [ 54.875089] systemd[1]: Reloading finished in 324 ms.1796machine # [ 54.966341] systemd[581]: Stopped migrated.service.1797machine # [ 54.970785] systemd[581]: Reexecution requested from client PID 3440 ('switch-to-confi')...1798machine # [ 54.972881] systemd[581]: Reexecuting.1799machine # [ 55.031970] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1800machine # [ 55.152828] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1801machine # [ 55.159399] systemd[581]: Reload requested from client PID 3440 ('switch-to-confi')...1802machine # [ 55.162871] systemd[581]: Reloading...1803machine # [ 55.272191] systemd[581]: Reloading finished in 110 ms.1804machine # [ 55.288179] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1805machine # [ 55.299976] systemd[581]: Stopped Run user-specific NixOS activation.1806machine # [ 55.301091] systemd[581]: Stopping Run user-specific NixOS activation...1807machine # [ 55.303675] systemd[581]: Starting Run user-specific NixOS activation...1808machine # [ 55.336609] systemd[581]: Finished Run user-specific NixOS activation.1809machine # [ 55.340641] systemd[1]: Stopped target Reactivate sysinit units.1810machine # [ 55.343449] systemd[1]: Stopping Reactivate sysinit units...1811machine # [ 55.344978] systemd[1]: Reached target Reactivate sysinit units.1812machine # [ 55.345860] systemd[1]: Reached target Remote File Systems.1813machine # [ 55.350334] systemd[1]: Starting linger-users.service...1814machine # [ 55.359947] systemd[1]: Reached target Local File Systems.1815machine # [ 55.366684] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1816machine # [ 55.375119] systemd[1]: Starting Create SUID/SGID Wrappers...1817machine # [ 55.384432] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1818machine # [ 55.386487] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671819machine # [ 55.392952] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1820machine # [ 55.395373] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1821machine # [ 55.399336] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1822machine # [ 55.405763] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1823machine # [ 55.407165] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1824machine # [ 55.448757] systemd[1]: linger-users.service: Deactivated successfully.1825machine # [ 55.452607] systemd[1]: Finished linger-users.service.1826machine # [ 55.593762] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1827machine # [ 55.597268] systemd[1]: Finished Create SUID/SGID Wrappers.1828machine # [ 55.854427] nixos[3371]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test1829machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.81 seconds)1830machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1831machine # [ 55.929792] sudo[3508]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1832machine # [ 55.945942] sudo[3508]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1833machine # [ 55.961750] sudo[3508]: pam_unix(sudo:session): session closed for user usertest1834machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)1835machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1836machine # [ 55.997514] sudo[3514]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1837machine # [ 56.009110] sudo[3514]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1838machine # [ 56.018535] sudo[3514]: pam_unix(sudo:session): session closed for user usertest1839machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)1840machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service1841machine # [ 56.049891] sudo[3520]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service1842machine # [ 56.060614] sudo[3520]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1843machine # [ 56.069083] sudo[3520]: pam_unix(sudo:session): session closed for user usertest1844machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1845machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1846machine # [ 56.097645] sudo[3526]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service1847machine # [ 56.109083] sudo[3526]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1848machine # [ 56.117526] sudo[3526]: pam_unix(sudo:session): session closed for user usertest1849machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)1850machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1851machine # [ 56.145746] sudo[3532]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1852machine # [ 56.155337] sudo[3532]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1853machine # [ 56.164172] systemd[581]: Reload requested from client PID 3537 ('systemctl')...1854machine # [ 56.168191] systemd[581]: Reloading...1855machine # [ 56.281329] systemd[581]: Reloading finished in 114 ms.1856machine # [ 56.297649] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1857machine # [ 56.308554] sudo[3532]: pam_unix(sudo:session): session closed for user usertest1858machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)1859machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1860machine # [ 56.337273] sudo[3542]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1861machine # [ 56.346925] sudo[3542]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1862machine # [ 56.360405] systemd[581]: Starting migrated.service...1863machine # [ 56.387517] systemd[581]: Finished migrated.service.1864machine # [ 56.391872] sudo[3542]: pam_unix(sudo:session): session closed for user usertest1865machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)1866machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1867machine # [ 56.420980] sudo[3550]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1868machine # [ 56.430210] sudo[3550]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1869machine # [ 56.440754] sudo[3550]: pam_unix(sudo:session): session closed for user usertest1870machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1871machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1872machine # [ 56.468939] sudo[3556]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1873machine # [ 56.478545] sudo[3556]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1874machine # [ 56.486858] sudo[3556]: pam_unix(sudo:session): session closed for user usertest1875machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1876machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1877machine # [ 56.515140] sudo[3562]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1878machine # [ 56.525140] sudo[3562]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1879machine # [ 56.541826] sudo[3562]: pam_unix(sudo:session): session closed for user usertest1880machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)1881machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&11882machine # [ 56.743196] nixos[3571]: switching to system configuration /nix/store/kb8dr6qa9mngxf2d7faxshwsspkc5r1r-nixos-system-machine-test1883machine # [ 56.746273] systemd[1]: Stopped target Local File Systems.1884machine # [ 56.747973] systemd[1]: Stopped target Remote File Systems.1885machine # [ 57.037998] systemd[1]: Reload requested from client PID 3571 ('.switch-to-conf') (unit backdoor.service)...1886machine # [ 57.039898] systemd[1]: Reloading...1887machine # [ 57.365950] systemd[1]: Reloading finished in 324 ms.1888machine # [ 57.458996] systemd[581]: Reexecution requested from client PID 3640 ('switch-to-confi')...1889machine # [ 57.461921] systemd[581]: Reexecuting.1890machine # [ 57.519964] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1891machine # [ 57.647182] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1892machine # [ 57.654263] systemd[581]: Reload requested from client PID 3640 ('switch-to-confi')...1893machine # [ 57.658299] systemd[581]: Reloading...1894machine # [ 57.774173] systemd[581]: Reloading finished in 116 ms.1895machine # [ 57.788413] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1896machine # [ 57.798089] systemd[581]: Stopped Run user-specific NixOS activation.1897machine # [ 57.800836] systemd[581]: Stopping Run user-specific NixOS activation...1898machine # [ 57.803204] systemd[581]: Starting Run user-specific NixOS activation...1899machine # [ 57.844196] systemd[581]: Reload requested from client PID 3652 ('systemctl') (unit nixos-activation.service)...1900machine # [ 57.847179] systemd[581]: Reloading...1901machine # [ 57.969265] systemd[581]: Reloading finished in 120 ms.1902machine # [ 57.984182] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1903machine # [ 58.003713] systemd[581]: Finished Run user-specific NixOS activation.1904machine # [ 58.006730] systemd[581]: Reload requested from client PID 3640 ('switch-to-confi')...1905machine # [ 58.010801] systemd[581]: Reloading...1906machine # [ 58.126207] systemd[581]: Reloading finished in 116 ms.1907machine # [ 58.140180] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1908machine # [ 58.150637] systemd[581]: Reloading migrated.service...1909machine # [ 58.177837] systemd[581]: Reloaded migrated.service.1910machine # [ 58.184579] systemd[1]: Stopped target Reactivate sysinit units.1911machine # [ 58.189033] systemd[1]: Stopping Reactivate sysinit units...1912machine # [ 58.189930] systemd[1]: Reached target Reactivate sysinit units.1913machine # [ 58.190771] systemd[1]: Reached target Local File Systems.1914machine # [ 58.192911] systemd[1]: Reached target Remote File Systems.1915machine # [ 58.197494] systemd[1]: Starting linger-users.service...1916machine # [ 58.207899] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1917machine # [ 58.210248] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1918machine # [ 58.216085] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1919machine # [ 58.222975] systemd[1]: Starting Create SUID/SGID Wrappers...1920machine # [ 58.223912] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1921machine # [ 58.232563] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671922machine # [ 58.234817] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1923machine # [ 58.243446] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1924machine # [ 58.245379] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1925machine # [ 58.280892] systemd[1]: linger-users.service: Deactivated successfully.1926machine # [ 58.284310] systemd[1]: Finished linger-users.service.1927machine # [ 58.431741] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1928machine # [ 58.434448] systemd[1]: Finished Create SUID/SGID Wrappers.1929machine # [ 58.696991] nixos[3571]: finished switching to system configuration /nix/store/kb8dr6qa9mngxf2d7faxshwsspkc5r1r-nixos-system-machine-test1930machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 2.17 seconds)1931machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1932machine # [ 58.774171] sudo[3721]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1933machine # [ 58.793184] sudo[3721]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1934machine # [ 58.808662] sudo[3721]: pam_unix(sudo:session): session closed for user usertest1935machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)1936machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1937machine # [ 58.844605] sudo[3727]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1938machine # [ 58.857293] sudo[3727]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1939machine # [ 58.866972] sudo[3727]: pam_unix(sudo:session): session closed for user usertest1940machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)1941machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&11942machine # [ 59.069964] nixos[3736]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test1943machine # [ 59.073555] systemd[1]: Stopped target Remote File Systems.1944machine # [ 59.074429] systemd[1]: Stopped target Local File Systems.1945machine # [ 59.338583] systemd[1]: Reload requested from client PID 3736 ('.switch-to-conf') (unit backdoor.service)...1946machine # [ 59.340541] systemd[1]: Reloading...1947machine # [ 59.654656] systemd[1]: Reloading finished in 313 ms.1948machine # [ 59.744505] systemd[581]: Stopped migrated.service.1949machine # [ 59.748205] systemd[581]: Reexecution requested from client PID 3804 ('switch-to-confi')...1950machine # [ 59.749596] systemd[581]: Reexecuting.1951machine # [ 59.803322] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1952machine # [ 59.920196] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1953machine # [ 59.926791] systemd[581]: Reload requested from client PID 3804 ('switch-to-confi')...1954machine # [ 59.930773] systemd[581]: Reloading...1955machine # [ 60.039455] systemd[581]: Reloading finished in 110 ms.1956machine # [ 60.056212] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1957machine # [ 60.066913] systemd[581]: Stopped Run user-specific NixOS activation.1958machine # [ 60.069309] systemd[581]: Stopping Run user-specific NixOS activation...1959machine # [ 60.071480] systemd[581]: Starting Run user-specific NixOS activation...1960machine # [ 60.104292] systemd[581]: Finished Run user-specific NixOS activation.1961machine # [ 60.108178] systemd[1]: Stopped target Reactivate sysinit units.1962machine # [ 60.110079] systemd[1]: Stopping Reactivate sysinit units...1963machine # [ 60.110961] systemd[1]: Reached target Reactivate sysinit units.1964machine # [ 60.113502] systemd[1]: Reached target Remote File Systems.1965machine # [ 60.117755] systemd[1]: Starting linger-users.service...1966machine # [ 60.126579] systemd[1]: Reached target Local File Systems.1967machine # [ 60.131414] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1968machine # [ 60.139517] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1969machine # [ 60.148444] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1970machine # [ 60.150518] systemd[1]: Starting Create SUID/SGID Wrappers...1971machine # [ 60.152438] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1972machine # [ 60.157604] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671973machine # [ 60.159794] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1974machine # [ 60.167631] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1975machine # [ 60.171623] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1976machine # [ 60.204739] systemd[1]: linger-users.service: Deactivated successfully.1977machine # [ 60.209034] systemd[1]: Finished linger-users.service.1978machine # [ 60.347039] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1979machine # [ 60.350494] systemd[1]: Finished Create SUID/SGID Wrappers.1980machine # [ 60.607555] nixos[3736]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test1981machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.75 seconds)1982machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1983machine # [ 60.680391] sudo[3873]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1984machine # [ 60.697964] sudo[3873]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1985machine # [ 60.714939] sudo[3873]: pam_unix(sudo:session): session closed for user usertest1986machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)1987machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1988machine # [ 60.757717] sudo[3879]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1989machine # [ 60.771176] sudo[3879]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1990machine # [ 60.781020] sudo[3879]: pam_unix(sudo:session): session closed for user usertest1991machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)1992machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service1993machine # [ 60.811430] sudo[3885]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service1994machine # [ 60.822580] sudo[3885]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1995machine # [ 60.831094] sudo[3885]: pam_unix(sudo:session): session closed for user usertest1996machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1997machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1998machine # [ 60.858419] sudo[3891]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service1999machine # [ 60.868845] sudo[3891]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2000machine # [ 60.876099] sudo[3891]: pam_unix(sudo:session): session closed for user usertest2001machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.04 seconds)2002machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2003machine # [ 60.903146] sudo[3897]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2004machine # [ 60.913283] sudo[3897]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2005machine # [ 60.921537] systemd[581]: Reload requested from client PID 3902 ('systemctl')...2006machine # [ 60.925501] systemd[581]: Reloading...2007machine # [ 61.039593] systemd[581]: Reloading finished in 115 ms.2008machine # [ 61.056177] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2009machine # [ 61.067647] sudo[3897]: pam_unix(sudo:session): session closed for user usertest2010machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)2011machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2012machine # [ 61.095468] sudo[3907]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2013machine # [ 61.105195] sudo[3907]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2014machine # [ 61.118258] systemd[581]: Starting migrated.service...2015machine # [ 61.147285] systemd[581]: Finished migrated.service.2016machine # [ 61.151425] sudo[3907]: pam_unix(sudo:session): session closed for user usertest2017machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)2018machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2019machine # [ 61.178972] sudo[3915]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2020machine # [ 61.188714] sudo[3915]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2021machine # [ 61.198752] sudo[3915]: pam_unix(sudo:session): session closed for user usertest2022machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)2023machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2024machine # [ 61.225997] sudo[3921]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2025machine # [ 61.235448] sudo[3921]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2026machine # [ 61.243439] sudo[3921]: pam_unix(sudo:session): session closed for user usertest2027machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.04 seconds)2028machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2029machine # [ 61.270435] sudo[3927]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2030machine # [ 61.280785] sudo[3927]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2031machine # [ 61.296264] sudo[3927]: pam_unix(sudo:session): session closed for user usertest2032machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)2033machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&12034machine # [ 61.495267] nixos[3936]: switching to system configuration /nix/store/f20r1s1n98n4srlr7pdpxx9aklinbxp1-nixos-system-machine-test2035machine # [ 61.499268] systemd[1]: Stopped target Remote File Systems.2036machine # [ 61.500367] systemd[1]: Stopped target Local File Systems.2037machine # [ 61.784864] systemd[1]: Reload requested from client PID 3936 ('.switch-to-conf') (unit backdoor.service)...2038machine # [ 61.786810] systemd[1]: Reloading...2039machine # [ 62.125546] systemd[1]: Reloading finished in 336 ms.2040machine # [ 62.218877] systemd[581]: Reexecution requested from client PID 4005 ('switch-to-confi')...2041machine # [ 62.222794] systemd[581]: Reexecuting.2042machine # [ 62.275450] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2043machine # [ 62.401333] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2044machine # [ 62.408116] systemd[581]: Reload requested from client PID 4005 ('switch-to-confi')...2045machine # [ 62.412170] systemd[581]: Reloading...2046machine # [ 62.527787] systemd[581]: Reloading finished in 116 ms.2047machine # [ 62.544211] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2048machine # [ 62.554740] systemd[581]: Stopped Run user-specific NixOS activation.2049machine # [ 62.555815] systemd[581]: Stopping Run user-specific NixOS activation...2050machine # [ 62.558501] systemd[581]: Starting Run user-specific NixOS activation...2051machine # [ 62.597699] systemd[581]: Reload requested from client PID 4017 ('systemctl') (unit nixos-activation.service)...2052machine # [ 62.602174] systemd[581]: Reloading...2053machine # [ 62.722164] systemd[581]: Reloading finished in 121 ms.2054machine # [ 62.736184] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2055machine # [ 62.753795] systemd[581]: Finished Run user-specific NixOS activation.2056machine # [ 62.757202] systemd[581]: Reload requested from client PID 4005 ('switch-to-confi')...2057machine # [ 62.758496] systemd[581]: Reloading...2058machine # [ 62.873420] systemd[581]: Reloading finished in 113 ms.2059machine # [ 62.888183] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2060machine # [ 62.901588] systemd[1]: Stopped target Reactivate sysinit units.2061machine # [ 62.902608] systemd[1]: Stopping Reactivate sysinit units...2062machine # [ 62.903394] systemd[1]: Reached target Reactivate sysinit units.2063machine # [ 62.907588] systemd[1]: Reached target Local File Systems.2064machine # [ 62.912230] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2065machine # [ 62.913590] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2066machine # [ 62.914848] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2067machine # [ 62.929108] systemd[1]: Starting Create SUID/SGID Wrappers...2068machine # [ 62.930014] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2069machine # [ 62.931257] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672070machine # [ 62.937666] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2071machine # [ 62.939971] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2072machine # [ 62.943313] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2073machine # [ 62.945293] systemd[1]: Reached target Remote File Systems.2074machine # [ 63.101116] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2075machine # [ 63.104573] systemd[1]: Finished Create SUID/SGID Wrappers.2076machine # [ 63.108233] systemd[1]: Starting linger-users.service...2077machine # [ 63.150478] systemd[1]: linger-users.service: Deactivated successfully.2078machine # [ 63.153623] systemd[1]: Finished linger-users.service.2079machine # [ 63.414679] nixos[3936]: finished switching to system configuration /nix/store/f20r1s1n98n4srlr7pdpxx9aklinbxp1-nixos-system-machine-test2080machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 2.13 seconds)2081machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2082machine # [ 63.497215] sudo[4084]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2083machine # [ 63.519985] sudo[4084]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2084machine # [ 63.537046] sudo[4084]: pam_unix(sudo:session): session closed for user usertest2085machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds)2086machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2087machine # [ 63.573537] sudo[4090]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2088machine # [ 63.586420] sudo[4090]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2089machine # [ 63.596219] sudo[4090]: pam_unix(sudo:session): session closed for user usertest2090machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)2091machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&12092machine # [ 63.800982] nixos[4099]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test2093machine # [ 63.803840] systemd[1]: Stopped target Local File Systems.2094machine # [ 63.806524] systemd[1]: Stopped target Remote File Systems.2095machine # [ 64.072176] systemd[1]: Reload requested from client PID 4099 ('.switch-to-conf') (unit backdoor.service)...2096machine # [ 64.074089] systemd[1]: Reloading...2097machine # [ 64.391612] systemd[1]: Reloading finished in 316 ms.2098machine # [ 64.481576] systemd[581]: Stopped migrated.service.2099machine # [ 64.484502] systemd[581]: Reexecution requested from client PID 4168 ('switch-to-confi')...2100machine # [ 64.487382] systemd[581]: Reexecuting.2101machine # [ 64.547515] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2102machine # [ 64.665756] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2103machine # [ 64.672093] systemd[581]: Reload requested from client PID 4168 ('switch-to-confi')...2104machine # [ 64.675598] systemd[581]: Reloading...2105machine # [ 64.785896] systemd[581]: Reloading finished in 111 ms.2106machine # [ 64.800176] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2107machine # [ 64.808761] systemd[581]: Stopped Run user-specific NixOS activation.2108machine # [ 64.811045] systemd[581]: Stopping Run user-specific NixOS activation...2109machine # [ 64.813143] systemd[581]: Starting Run user-specific NixOS activation...2110machine # [ 64.843976] systemd[581]: Finished Run user-specific NixOS activation.2111machine # [ 64.847503] systemd[1]: Stopped target Reactivate sysinit units.2112machine # [ 64.849185] systemd[1]: Stopping Reactivate sysinit units...2113machine # [ 64.849995] systemd[1]: Reached target Reactivate sysinit units.2114machine # [ 64.854483] systemd[1]: Reached target Remote File Systems.2115machine # [ 64.858803] systemd[1]: Starting linger-users.service...2116machine # [ 64.868469] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2117machine # [ 64.869832] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2118machine # [ 64.874297] systemd[1]: Reached target Local File Systems.2119machine # [ 64.875966] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2120machine # [ 64.886045] systemd[1]: Starting Create SUID/SGID Wrappers...2121machine # [ 64.886951] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2122machine # [ 64.894094] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672123machine # [ 64.897368] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2124machine # [ 64.903529] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2125machine # [ 64.906418] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2126machine # [ 64.943727] systemd[1]: linger-users.service: Deactivated successfully.2127machine # [ 64.947399] systemd[1]: Finished linger-users.service.2128machine # [ 65.086127] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2129machine # [ 65.089581] systemd[1]: Finished Create SUID/SGID Wrappers.2130machine # [ 65.346438] nixos[4099]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test2131machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.76 seconds)2132machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2133machine # [ 65.423929] sudo[4236]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2134machine # [ 65.445407] sudo[4236]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2135machine # [ 65.461643] sudo[4236]: pam_unix(sudo:session): session closed for user usertest2136machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)2137machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user2138machine # [ 65.496877] sudo[4242]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user2139machine # [ 65.508922] sudo[4242]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2140machine # [ 65.518919] sudo[4242]: pam_unix(sudo:session): session closed for user usertest2141machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.06 seconds)2142machine: must succeed: sudo -u usertest cp /nix/store/kbn5jd7gqb07qksa4hgqhsx409fjnskk-migrated.service ~usertest/.local/share/systemd/user/migrated.service2143machine # [ 65.549429] sudo[4248]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/kbn5jd7gqb07qksa4hgqhsx409fjnskk-migrated.service /home/usertest/.local/share/systemd/user/migrated.service2144machine # [ 65.560766] sudo[4248]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2145machine # [ 65.568618] sudo[4248]: pam_unix(sudo:session): session closed for user usertest2146machine: (finished: must succeed: sudo -u usertest cp /nix/store/kbn5jd7gqb07qksa4hgqhsx409fjnskk-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.05 seconds)2147machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2148machine # [ 65.596251] sudo[4254]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2149machine # [ 65.605554] sudo[4254]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2150machine # [ 65.613838] systemd[581]: Reload requested from client PID 4259 ('systemctl')...2151machine # [ 65.617757] systemd[581]: Reloading...2152machine # [ 65.728944] systemd[581]: Reloading finished in 112 ms.2153machine # [ 65.744176] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2154machine # [ 65.757129] sudo[4254]: pam_unix(sudo:session): session closed for user usertest2155machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)2156machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2157machine # [ 65.784975] sudo[4264]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2158machine # [ 65.794420] sudo[4264]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2159machine # [ 65.808788] systemd[581]: Starting migrated.service...2160machine # [ 65.834959] systemd[581]: Finished migrated.service.2161machine # [ 65.838708] sudo[4264]: pam_unix(sudo:session): session closed for user usertest2162machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)2163machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2164machine # [ 65.866438] sudo[4272]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2165machine # [ 65.876761] sudo[4272]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2166machine # [ 65.886022] sudo[4272]: pam_unix(sudo:session): session closed for user usertest2167machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)2168machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2169machine # [ 65.912646] sudo[4278]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2170machine # [ 65.921839] sudo[4278]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2171machine # [ 65.929308] sudo[4278]: pam_unix(sudo:session): session closed for user usertest2172machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.04 seconds)2173machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2174machine # [ 65.955416] sudo[4284]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2175machine # [ 65.964986] sudo[4284]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2176machine # [ 65.984941] sudo[4284]: pam_unix(sudo:session): session closed for user usertest2177machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)2178machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12179machine # [ 66.184972] nixos[4293]: switching to system configuration /nix/store/dyva96i4sypfa7gqsq2nw0np5sb9w6p4-nixos-system-machine-test2180machine # [ 66.187620] systemd[1]: Stopped target Remote File Systems.2181machine # [ 66.189837] systemd[1]: Stopped target Local File Systems.2182machine # [ 66.462659] systemd[1]: Reload requested from client PID 4293 ('.switch-to-conf') (unit backdoor.service)...2183machine # [ 66.464810] systemd[1]: Reloading...2184machine # [ 66.770682] systemd[1]: Reloading finished in 304 ms.2185machine # [ 66.859281] systemd[581]: Reexecution requested from client PID 4362 ('switch-to-confi')...2186machine # [ 66.863453] systemd[581]: Reexecuting.2187machine # [ 66.923416] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2188machine # [ 67.046509] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2189machine # [ 67.052665] systemd[581]: Reload requested from client PID 4362 ('switch-to-confi')...2190machine # [ 67.056067] systemd[581]: Reloading...2191machine # [ 67.167982] systemd[581]: Reloading finished in 112 ms.2192machine # [ 67.184159] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2193machine # [ 67.194466] systemd[581]: Stopped Run user-specific NixOS activation.2194machine # [ 67.196789] systemd[581]: Stopping Run user-specific NixOS activation...2195machine # [ 67.198869] systemd[581]: Starting Run user-specific NixOS activation...2196machine # [ 67.231800] systemd[581]: Finished Run user-specific NixOS activation.2197machine # [ 67.233933] systemd[581]: Reload requested from client PID 4362 ('switch-to-confi')...2198machine # [ 67.235163] systemd[581]: Reloading...2199machine # [ 67.348988] systemd[581]: Reloading finished in 112 ms.2200machine # [ 67.364163] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2201machine # [ 67.375313] systemd[581]: Stopped migrated.service.2202machine # [ 67.376204] systemd[581]: Stopping migrated.service...2203machine # [ 67.377946] systemd[581]: Starting migrated.service...2204machine # [ 67.402433] systemd[581]: Finished migrated.service.2205machine # [ 67.408349] systemd[1]: Stopped target Reactivate sysinit units.2206machine # [ 67.411150] systemd[1]: Stopping Reactivate sysinit units...2207machine # [ 67.413206] systemd[1]: Reached target Reactivate sysinit units.2208machine # [ 67.416137] systemd[1]: Reached target Remote File Systems.2209machine # [ 67.419441] systemd[1]: Starting linger-users.service...2210machine # [ 67.428685] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2211machine # [ 67.430331] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2212machine # [ 67.435299] systemd[1]: Reached target Local File Systems.2213machine # [ 67.439500] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2214machine # [ 67.444098] systemd[1]: Starting Create SUID/SGID Wrappers...2215machine # [ 67.444962] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2216machine # [ 67.446144] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672217machine # [ 67.457978] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2218machine # [ 67.459594] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2219machine # [ 67.463694] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2220machine # [ 67.501592] systemd[1]: linger-users.service: Deactivated successfully.2221machine # [ 67.504846] systemd[1]: Finished linger-users.service.2222machine # [ 67.638631] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2223machine # [ 67.641430] systemd[1]: Finished Create SUID/SGID Wrappers.2224machine # [ 67.897993] nixos[4293]: finished switching to system configuration /nix/store/dyva96i4sypfa7gqsq2nw0np5sb9w6p4-nixos-system-machine-test2225machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.92 seconds)2226machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2227machine # [ 67.970360] sudo[4436]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2228machine # [ 67.989726] sudo[4436]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2229machine # [ 68.004815] sudo[4436]: pam_unix(sudo:session): session closed for user usertest2230machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)2231machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2232machine # [ 68.039349] sudo[4442]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2233machine # [ 68.053246] sudo[4442]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2234machine # [ 68.073163] sudo[4442]: pam_unix(sudo:session): session closed for user usertest2235machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)2236machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2237machine # [ 68.103354] sudo[4448]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2238machine # [ 68.113230] sudo[4448]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2239machine # [ 68.120782] sudo[4448]: pam_unix(sudo:session): session closed for user usertest2240machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2241machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12242machine # [ 68.312656] nixos[4457]: switching to system configuration /nix/store/dyva96i4sypfa7gqsq2nw0np5sb9w6p4-nixos-system-machine-test2243machine # [ 68.316071] systemd[1]: Stopped target Local File Systems.2244machine # [ 68.316929] systemd[1]: Stopped target Remote File Systems.2245machine # [ 68.566225] systemd[1]: Reload requested from client PID 4457 ('.switch-to-conf') (unit backdoor.service)...2246machine # [ 68.568115] systemd[1]: Reloading...2247machine # [ 68.867645] systemd[1]: Reloading finished in 298 ms.2248machine # [ 68.953150] systemd[581]: Reexecution requested from client PID 4526 ('switch-to-confi')...2249machine # [ 68.956685] systemd[581]: Reexecuting.2250machine # [ 69.015378] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2251machine # [ 69.133052] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2252machine # [ 69.140101] systemd[581]: Reload requested from client PID 4526 ('switch-to-confi')...2253machine # [ 69.141419] systemd[581]: Reloading...2254machine # [ 69.251090] systemd[581]: Reloading finished in 108 ms.2255machine # [ 69.268167] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2256machine # [ 69.277660] systemd[581]: Stopped Run user-specific NixOS activation.2257machine # [ 69.278659] systemd[581]: Stopping Run user-specific NixOS activation...2258machine # [ 69.281182] systemd[581]: Starting Run user-specific NixOS activation...2259machine # [ 69.311430] systemd[581]: Finished Run user-specific NixOS activation.2260machine # [ 69.315147] systemd[1]: Stopped target Reactivate sysinit units.2261machine # [ 69.317873] systemd[1]: Stopping Reactivate sysinit units...2262machine # [ 69.318706] systemd[1]: Reached target Reactivate sysinit units.2263machine # [ 69.323312] systemd[1]: Reached target Local File Systems.2264machine # [ 69.327531] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2265machine # [ 69.332988] systemd[1]: Starting Create SUID/SGID Wrappers...2266machine # [ 69.333820] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2267machine # [ 69.335026] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672268machine # [ 69.341004] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2269machine # [ 69.342656] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2270machine # [ 69.343931] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2271machine # [ 69.353801] systemd[1]: Reached target Remote File Systems.2272machine # [ 69.354712] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2273machine # [ 69.356831] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2274machine # [ 69.522417] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2275machine # [ 69.524439] systemd[1]: Finished Create SUID/SGID Wrappers.2276machine # [ 69.529904] systemd[1]: Starting linger-users.service...2277machine # [ 69.569377] systemd[1]: linger-users.service: Deactivated successfully.2278machine # [ 69.572089] systemd[1]: Finished linger-users.service.2279machine # [ 69.830253] nixos[4457]: finished switching to system configuration /nix/store/dyva96i4sypfa7gqsq2nw0np5sb9w6p4-nixos-system-machine-test2280machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.72 seconds)2281machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd2282machine # [ 69.903842] sudo[4594]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd2283machine # [ 69.921500] sudo[4594]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2284machine # [ 69.933508] sudo[4594]: pam_unix(sudo:session): session closed for user usertest2285machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.09 seconds)2286machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2287machine # [ 69.972576] sudo[4600]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2288machine # [ 69.986579] sudo[4600]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2289machine # [ 69.996632] systemd[581]: Reload requested from client PID 4605 ('systemctl')...2290machine # [ 70.000318] systemd[581]: Reloading...2291machine # [ 70.115753] systemd[581]: Reloading finished in 115 ms.2292machine # [ 70.132192] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2293machine # [ 70.142081] sudo[4600]: pam_unix(sudo:session): session closed for user usertest2294machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.21 seconds)2295machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&12296machine # [ 70.335851] nixos[4613]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test2297machine # [ 70.338399] systemd[1]: Stopped target Remote File Systems.2298machine # [ 70.340753] systemd[1]: Stopped target Local File Systems.2299machine # [ 70.589933] systemd[1]: Reload requested from client PID 4613 ('.switch-to-conf') (unit backdoor.service)...2300machine # [ 70.591767] systemd[1]: Reloading...2301machine # [ 70.893223] systemd[1]: Reloading finished in 300 ms.2302machine # [ 70.982551] systemd[581]: Stopped migrated.service.2303machine # [ 70.986367] systemd[581]: Reexecution requested from client PID 4682 ('switch-to-confi')...2304machine # [ 70.987819] systemd[581]: Reexecuting.2305machine # [ 71.048221] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2306machine # [ 71.165161] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2307machine # [ 71.171674] systemd[581]: Reload requested from client PID 4682 ('switch-to-confi')...2308machine # [ 71.173810] systemd[581]: Reloading...2309machine # [ 71.279917] systemd[581]: Reloading finished in 105 ms.2310machine # [ 71.297464] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2311machine # [ 71.305698] systemd[581]: Stopped Run user-specific NixOS activation.2312machine # [ 71.306658] systemd[581]: Stopping Run user-specific NixOS activation...2313machine # [ 71.308842] systemd[581]: Starting Run user-specific NixOS activation...2314machine # [ 71.338669] systemd[581]: Finished Run user-specific NixOS activation.2315machine # [ 71.342355] systemd[1]: Stopped target Reactivate sysinit units.2316machine # [ 71.345286] systemd[1]: Stopping Reactivate sysinit units...2317machine # [ 71.346179] systemd[1]: Reached target Reactivate sysinit units.2318machine # [ 71.347788] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2319machine # [ 71.352640] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2320machine # [ 71.353955] systemd[1]: Reached target Remote File Systems.2321machine # [ 71.357495] systemd[1]: Starting linger-users.service...2322machine # [ 71.361574] systemd[1]: Reached target Local File Systems.2323machine # [ 71.370379] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2324machine # [ 71.371920] systemd[1]: Starting Create SUID/SGID Wrappers...2325machine # [ 71.380507] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2326machine # [ 71.381802] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672327machine # [ 71.383937] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2328machine # [ 71.387654] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2329machine # [ 71.389089] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2330machine # [ 71.445224] systemd[1]: linger-users.service: Deactivated successfully.2331machine # [ 71.448118] systemd[1]: Finished linger-users.service.2332machine # [ 71.572177] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2333machine # [ 71.574400] systemd[1]: Finished Create SUID/SGID Wrappers.2334machine # [ 71.832777] nixos[4613]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test2335machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.70 seconds)2336machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2337machine # [ 71.903201] sudo[4750]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2338machine # [ 71.920696] sudo[4750]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2339machine # [ 71.931651] sudo[4750]: pam_unix(sudo:session): session closed for user usertest2340machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.09 seconds)2341machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service2342machine # [ 71.966253] sudo[4756]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service2343machine # [ 71.979466] sudo[4756]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2344machine # [ 71.988996] sudo[4756]: pam_unix(sudo:session): session closed for user usertest2345machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.06 seconds)2346machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2347machine # [ 72.018270] sudo[4762]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service2348machine # [ 72.029194] sudo[4762]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2349machine # [ 72.036915] sudo[4762]: pam_unix(sudo:session): session closed for user usertest2350machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)2351machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2352machine # [ 72.062384] sudo[4768]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2353machine # [ 72.071500] sudo[4768]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2354machine # [ 72.079638] systemd[581]: Reload requested from client PID 4773 ('systemctl')...2355machine # [ 72.083504] systemd[581]: Reloading...2356machine # [ 72.192552] systemd[581]: Reloading finished in 110 ms.2357machine # [ 72.208166] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2358machine # [ 72.219214] sudo[4768]: pam_unix(sudo:session): session closed for user usertest2359machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.18 seconds)2360machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2361machine # [ 72.247504] sudo[4778]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2362machine # [ 72.256906] sudo[4778]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2363machine # [ 72.269358] systemd[581]: Starting migrated.service...2364machine # [ 72.293866] systemd[581]: Finished migrated.service.2365machine # [ 72.297725] sudo[4778]: pam_unix(sudo:session): session closed for user usertest2366machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)2367machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2368machine # [ 72.323422] sudo[4786]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2369machine # [ 72.332743] sudo[4786]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2370machine # [ 72.341884] sudo[4786]: pam_unix(sudo:session): session closed for user usertest2371machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds)2372machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2373machine # [ 72.367041] sudo[4792]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2374machine # [ 72.376800] sudo[4792]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2375machine # [ 72.383386] sudo[4792]: pam_unix(sudo:session): session closed for user usertest2376machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.04 seconds)2377machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2378machine # [ 72.408707] sudo[4798]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2379machine # [ 72.418000] sudo[4798]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2380machine # [ 72.433201] sudo[4798]: pam_unix(sudo:session): session closed for user usertest2381machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)2382machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12383machine # [ 72.624696] nixos[4807]: switching to system configuration /nix/store/dyva96i4sypfa7gqsq2nw0np5sb9w6p4-nixos-system-machine-test2384machine # [ 72.628552] systemd[1]: Stopped target Local File Systems.2385machine # [ 72.629375] systemd[1]: Stopped target Remote File Systems.2386machine # [ 72.876421] systemd[1]: Reload requested from client PID 4807 ('.switch-to-conf') (unit backdoor.service)...2387machine # [ 72.878237] systemd[1]: Reloading...2388machine # [ 73.176803] systemd[1]: Reloading finished in 297 ms.2389machine # [ 73.259694] systemd[581]: Reexecution requested from client PID 4876 ('switch-to-confi')...2390machine # [ 73.262546] systemd[581]: Reexecuting.2391machine # [ 73.327902] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2392machine # [ 73.446842] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2393machine # [ 73.452946] systemd[581]: Reload requested from client PID 4876 ('switch-to-confi')...2394machine # [ 73.455159] systemd[581]: Reloading...2395machine # [ 73.563644] systemd[581]: Reloading finished in 107 ms.2396machine # [ 73.580156] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2397machine # [ 73.589620] systemd[581]: Stopped Run user-specific NixOS activation.2398machine # [ 73.590601] systemd[581]: Stopping Run user-specific NixOS activation...2399machine # [ 73.593256] systemd[581]: Starting Run user-specific NixOS activation...2400machine # [ 73.622654] systemd[581]: Finished Run user-specific NixOS activation.2401machine # [ 73.624600] systemd[581]: Reload requested from client PID 4876 ('switch-to-confi')...2402machine # [ 73.628193] systemd[581]: Reloading...2403machine # [ 73.735457] systemd[581]: Reloading finished in 108 ms.2404machine # [ 73.752149] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2405machine # [ 73.763622] systemd[1]: Stopped target Reactivate sysinit units.2406machine # [ 73.766934] systemd[1]: Stopping Reactivate sysinit units...2407machine # [ 73.767754] systemd[1]: Reached target Reactivate sysinit units.2408machine # [ 73.769274] systemd[1]: Reached target Remote File Systems.2409machine # [ 73.774528] systemd[1]: Starting linger-users.service...2410machine # [ 73.782250] systemd[1]: Reached target Local File Systems.2411machine # [ 73.786535] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2412machine # [ 73.794020] systemd[1]: Starting Create SUID/SGID Wrappers...2413machine # [ 73.794907] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2414machine # [ 73.802992] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672415machine # [ 73.808189] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2416machine # [ 73.811583] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2417machine # [ 73.815255] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2418machine # [ 73.818464] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2419machine # [ 73.821909] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2420machine # [ 73.853564] systemd[1]: linger-users.service: Deactivated successfully.2421machine # [ 73.855926] systemd[1]: Finished linger-users.service.2422machine # [ 73.987004] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2423machine # [ 73.989028] systemd[1]: Finished Create SUID/SGID Wrappers.2424machine # [ 74.248163] nixos[4807]: finished switching to system configuration /nix/store/dyva96i4sypfa7gqsq2nw0np5sb9w6p4-nixos-system-machine-test2425machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.83 seconds)2426machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2427machine # [ 74.318828] sudo[4948]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2428machine # [ 74.338845] sudo[4948]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2429machine # [ 74.364092] sudo[4948]: pam_unix(sudo:session): session closed for user usertest2430machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.11 seconds)2431machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2432machine # [ 74.402982] sudo[4954]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2433machine # [ 74.415073] sudo[4954]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2434machine # [ 74.423440] sudo[4954]: pam_unix(sudo:session): session closed for user usertest2435machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)2436machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd2437machine # [ 74.451053] sudo[4960]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd2438machine # [ 74.460595] sudo[4960]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2439machine # [ 74.468690] sudo[4960]: pam_unix(sudo:session): session closed for user usertest2440machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.04 seconds)2441machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2442machine # [ 74.494217] sudo[4966]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2443machine # [ 74.503254] sudo[4966]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2444machine # [ 74.511312] systemd[581]: Reload requested from client PID 4971 ('systemctl')...2445machine # [ 74.513471] systemd[581]: Reloading...2446machine # [ 74.626544] systemd[581]: Reloading finished in 111 ms.2447machine # [ 74.640154] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2448machine # [ 74.649721] sudo[4966]: pam_unix(sudo:session): session closed for user usertest2449machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.18 seconds)2450machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service2451machine # [ 74.676682] sudo[4976]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user stop migrated.service2452machine # [ 74.685754] sudo[4976]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2453machine # [ 74.696427] systemd[581]: Stopped migrated.service.2454machine # [ 74.699797] sudo[4976]: pam_unix(sudo:session): session closed for user usertest2455machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.05 seconds)2456machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&12457machine # [ 74.889610] nixos[4985]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test2458machine # [ 74.892225] systemd[1]: Stopped target Local File Systems.2459machine # [ 74.894347] systemd[1]: Stopped target Remote File Systems.2460machine # [ 75.142308] systemd[1]: Reload requested from client PID 4985 ('.switch-to-conf') (unit backdoor.service)...2461machine # [ 75.144250] systemd[1]: Reloading...2462machine # [ 75.447984] systemd[1]: Reloading finished in 302 ms.2463machine # [ 75.532242] systemd[581]: Reexecution requested from client PID 5054 ('switch-to-confi')...2464machine # [ 75.533623] systemd[581]: Reexecuting.2465machine # [ 75.587064] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2466machine # [ 75.699217] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2467machine # [ 75.706148] systemd[581]: Reload requested from client PID 5054 ('switch-to-confi')...2468machine # [ 75.707440] systemd[581]: Reloading...2469machine # [ 75.814326] systemd[581]: Reloading finished in 105 ms.2470machine # [ 75.828154] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2471machine # [ 75.836734] systemd[581]: Stopped Run user-specific NixOS activation.2472machine # [ 75.840862] systemd[581]: Stopping Run user-specific NixOS activation...2473machine # [ 75.841825] systemd[581]: Starting Run user-specific NixOS activation...2474machine # [ 75.870310] systemd[581]: Finished Run user-specific NixOS activation.2475machine # [ 75.873906] systemd[1]: Stopped target Reactivate sysinit units.2476machine # [ 75.876517] systemd[1]: Stopping Reactivate sysinit units...2477machine # [ 75.877329] systemd[1]: Reached target Reactivate sysinit units.2478machine # [ 75.881824] systemd[1]: Reached target Local File Systems.2479machine # [ 75.884815] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2480machine # [ 75.888801] systemd[1]: Starting Create SUID/SGID Wrappers...2481machine # [ 75.889621] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2482machine # [ 75.890828] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672483machine # [ 75.902396] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2484machine # [ 75.903926] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2485machine # [ 75.908319] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2486machine # [ 75.909525] systemd[1]: Reached target Remote File Systems.2487machine # [ 75.910284] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2488machine # [ 75.911489] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2489machine # [ 76.062242] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2490machine # [ 76.066840] systemd[1]: Finished Create SUID/SGID Wrappers.2491machine # [ 76.068683] systemd[1]: Starting linger-users.service...2492machine # [ 76.107149] systemd[1]: linger-users.service: Deactivated successfully.2493machine # [ 76.110130] systemd[1]: Finished linger-users.service.2494machine # [ 76.366640] nixos[4985]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test2495machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.68 seconds)2496(finished: subtest: user services, in 50.90 seconds)2497(finished: run the VM test script, in 77.23 seconds)2498test script finished in 77.29s2499cleanup2500kill QemuMachine (pid 45)2501machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2502machine # [2026-09-23T03:01:06Z INFO virtiofsd] Client disconnected, shutting down2503machine # [2026-09-23T03:01:06Z INFO virtiofsd] Client disconnected, shutting down2504machine # [2026-09-23T03:01:06Z INFO virtiofsd] Client disconnected, shutting down2505(finished: cleanup, in 0.17 seconds)2506additionally exposed symbols:2507 machine, other,2508 vlan1,2509 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh