vm-test-run-switch-test-user
checks.aarch64-linux.switchTest-user
· build #63
· 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.OmdMdYVkDi', 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: 76300bdd-19c7-4d03-95ea-d02c3b23ee9417machine # Superblock backups stored on blocks:18machine # 32768, 98304, 163840, 22937619machine # 20machine # Allocating group tables: 0/8 done21machine # Writing inode tables: 0/8 done22machine # Creating journal (8192 blocks): done23machine # Writing superblocks and filesystem accounting information: 0/8 done24machine # 25machine # Virtualisation disk image created.26machine # Starting virtiofs daemons...27machine # [2026-10-21T03:00:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28machine # [2026-10-21T03:00:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29machine # [2026-10-21T03:00:37Z INFO virtiofsd] Waiting for vhost-user socket connection...30machine # [2026-10-21T03:00:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31machine # [2026-10-21T03:00:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32machine # [2026-10-21T03:00:37Z INFO virtiofsd] Waiting for vhost-user socket connection...33machine # [2026-10-21T03:00:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34machine # [2026-10-21T03:00:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35machine # [2026-10-21T03:00:37Z INFO virtiofsd] Waiting for vhost-user socket connection...36machine # [2026-10-21T03:00:37Z INFO virtiofsd] Client connected, servicing requests37machine # [2026-10-21T03:00:37Z INFO virtiofsd] Client connected, servicing requests38machine # [2026-10-21T03:00:37Z INFO virtiofsd] Client connected, servicing requests39machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]40machine # [ 0.000000] Linux version 6.18.57 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @179205830041machine # [ 0.000000] KASLR enabled42machine # [ 0.000000] random: crng init done43machine # [ 0.000000] Machine model: linux,dummy-virt44machine # [ 0.000000] efi: UEFI not found.45machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT46machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]47machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]48machine # [ 0.000000] Zone ranges:49machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]50machine # [ 0.000000] DMA32 empty51machine # [ 0.000000] Normal empty52machine # [ 0.000000] Device empty53machine # [ 0.000000] Movable zone start for each node54machine # [ 0.000000] Early memory node ranges55machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]56machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]57machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000058machine # [ 0.000000] psci: probing for conduit method from DT.59machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.60machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs61machine # [ 0.000000] psci: Trusted OS migration not required62machine # [ 0.000000] psci: SMC Calling Convention v1.163machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)64machine # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 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/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/init regInfo=/nix/.ro-store/rg7gxyfivs5lq7rvpkx0dhiizgjd2znb-closure-info/registration console=ttyAMA0,115200n8 console=tty074machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/rg7gxyfivs5lq7rvpkx0dhiizgjd2znb-closure-info/registration", will be passed to user space.75machine # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 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 75301 entries in 296 pages88machine # [ 0.000000] ftrace: allocated 296 pages with 3 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=512 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 @450f0000 (indirect, esz 8, psz 64K, shr 1)109machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @45100000 (flat, esz 8, psz 64K, shr 1)110machine # [ 0.000000] GICv3: using LPI property table @0x0000000045110000111machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000112machine # [ 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.000511] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)119machine # [ 0.000699] Console: colour dummy device 80x25120machine # [ 0.000708] printk: legacy console [tty0] enabled121machine # [ 0.000910] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)122machine # [ 0.000917] pid_max: default: 32768 minimum: 301123machine # [ 0.001009] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima124machine # [ 0.001140] landlock: Up and running.125machine # [ 0.001143] Yama: becoming mindful.126machine # [ 0.001600] LSM support for eBPF active127machine # [ 0.001728] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)128machine # [ 0.001750] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)129machine # [ 0.004150] rcu: Hierarchical SRCU implementation.130machine # [ 0.004158] rcu: Max phase no-delay instances is 1000.131machine # [ 0.005456] fsl-mc MSI: its@8080000 domain created132machine # [ 0.005584] EFI services will not be available.133machine # [ 0.005663] smp: Bringing up secondary CPUs ...134machine # [ 0.005673] smp: Brought up 1 node, 1 CPU135machine # [ 0.005677] SMP: Total of 1 processors activated.136machine # [ 0.005680] CPU: All CPU(s) started at EL1137machine # [ 0.005691] CPU features: detected: Branch Target Identification138machine # [ 0.005695] CPU features: detected: ARMv8.4 Translation Table Level139machine # [ 0.005698] CPU features: detected: Instruction cache invalidation not required for I/D coherence140machine # [ 0.005701] CPU features: detected: Data cache clean to the PoU not required for I/D coherence141machine # [ 0.005709] CPU features: detected: Common not Private translations142machine # [ 0.005713] CPU features: detected: CRC32 instructions143machine # [ 0.005715] CPU features: detected: Data cache clean to Point of Deep Persistence144machine # [ 0.005719] CPU features: detected: Data cache clean to Point of Persistence145machine # [ 0.005722] CPU features: detected: Data independent timing control (DIT)146machine # [ 0.005725] CPU features: detected: E0PD147machine # [ 0.005728] CPU features: detected: Enhanced Counter Virtualization148machine # [ 0.005731] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)149machine # [ 0.005734] CPU features: detected: Enhanced Virtualization Traps150machine # [ 0.005737] CPU features: detected: Fine Grained Traps151machine # [ 0.005741] CPU features: detected: Generic authentication (architected QARMA5 algorithm)152machine # [ 0.005746] CPU features: detected: RCpc load-acquire (LDAPR)153machine # [ 0.005749] CPU features: detected: LSE atomic instructions154machine # [ 0.005752] CPU features: detected: Privileged Access Never155machine # [ 0.005755] CPU features: detected: PMUv3156machine # [ 0.005758] CPU features: detected: RAS Extension Support157machine # [ 0.005760] CPU features: detected: RASv1p1 Extension Support158machine # [ 0.005763] CPU features: detected: Random Number Generator159machine # [ 0.005768] CPU features: detected: Speculation barrier (SB)160machine # [ 0.005771] CPU features: detected: Stage-2 Force Write-Back161machine # [ 0.005774] CPU features: detected: TLB range maintenance instructions162machine # [ 0.005778] CPU features: detected: Speculative Store Bypassing Safe (SSBS)163machine # [ 0.005816] alternatives: applying system-wide alternatives164machine # [ 0.009191] CPU features: detected: BBM Level 2 without TLB conflict abort165machine # [ 0.009362] Memory: 886664K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 120612K reserved, 32768K cma-reserved)166machine # [ 0.009733] devtmpfs: initialized167machine # [ 0.011561] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)168machine # [ 0.011585] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).169machine # [ 0.011789] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL170machine # [ 0.011793] 0 pages in range for non-PLT usage171machine # [ 0.011794] 506368 pages in range for PLT usage172machine # [ 0.011910] pinctrl core: initialized pinctrl subsystem173machine # [ 0.012794] DMI not present or invalid.174machine # [ 0.016214] NET: Registered PF_NETLINK/PF_ROUTE protocol family175machine # [ 0.018684] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations176machine # [ 0.018840] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations177machine # [ 0.019001] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations178machine # [ 0.019024] audit: initializing netlink subsys (disabled)179machine # [ 0.019635] thermal_sys: Registered thermal governor 'fair_share'180machine # [ 0.019638] thermal_sys: Registered thermal governor 'bang_bang'181machine # [ 0.019641] thermal_sys: Registered thermal governor 'step_wise'182machine # [ 0.019644] thermal_sys: Registered thermal governor 'user_space'183machine # [ 0.019647] thermal_sys: Registered thermal governor 'power_allocator'184machine # [ 0.019682] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1185machine # [ 0.019691] cpuidle: using governor ladder186machine # [ 0.019698] cpuidle: using governor menu187machine # [ 0.019892] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.188machine # [ 0.019910] ASID allocator initialised with 65536 entries189machine # [ 0.021216] Serial: AMBA PL011 UART driver190machine # [ 0.026878] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1191machine # [ 0.027048] printk: console [ttyAMA0] enabled192machine # [ 0.156390] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages193machine # [ 0.156404] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page194machine # [ 0.156407] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages195machine # [ 0.156410] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page196machine # [ 0.156413] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages197machine # [ 0.156416] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page198machine # [ 0.156419] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages199machine # [ 0.156421] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page200machine # [ 0.157751] fbcon: Taking over console201machine # [ 0.157762] ACPI: Interpreter disabled.202machine # [ 0.167717] iommu: Default domain type: Translated203machine # [ 0.167725] iommu: DMA domain TLB invalidation policy: strict mode204machine # [ 0.168164] SCSI subsystem initialized205machine # [ 0.170344] usbcore: registered new interface driver usbfs206machine # [ 0.170378] usbcore: registered new interface driver hub207machine # [ 0.170395] usbcore: registered new device driver usb208machine # [ 0.170712] pps_core: LinuxPPS API ver. 1 registered209machine # [ 0.170717] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>210machine # [ 0.170725] PTP clock support registered211machine # [ 0.170779] EDAC MC: Ver: 3.0.0212machine # [ 0.172415] scmi_core: SCMI protocol bus registered213machine # [ 0.178152] FPGA manager framework214machine # [ 0.180509] vgaarb: loaded215machine # [ 0.181622] clocksource: Switched to clocksource arch_sys_counter216machine # [ 0.187441] VFS: Disk quotas dquot_6.6.0217machine # [ 0.187475] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)218machine # [ 0.187898] netfs: FS-Cache loaded219machine # [ 0.188027] pnp: PnP ACPI: disabled220machine # [ 0.196012] NET: Registered PF_INET protocol family221machine # [ 0.196182] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)222machine # [ 0.227050] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)223machine # [ 0.227094] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)224machine # [ 0.227118] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)225machine # [ 0.227166] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)226machine # [ 0.227245] TCP: Hash tables configured (established 8192 bind 8192)227machine # [ 0.227375] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)228machine # [ 0.227433] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)229machine # [ 0.227455] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)230machine # [ 0.227542] NET: Registered PF_UNIX/PF_LOCAL protocol family231machine # [ 0.227561] NET: Registered PF_XDP protocol family232machine # [ 0.227576] PCI: CLS 0 bytes, default 64233machine # [ 0.227832] Trying to unpack rootfs image as initramfs...234machine # [ 0.239926] kvm [1]: HYP mode not available235machine # [ 0.358251] Initialise system trusted keyrings236machine # [ 0.358298] Key type blacklist registered237machine # [ 0.358430] workingset: timestamp_bits=42 max_order=18 bucket_order=0238machine # [ 0.358825] squashfs: version 4.0 (2009/01/31) Phillip Lougher239machine # [ 0.358890] 9p: Installing v9fs 9p2000 file system support240machine # [ 0.358978] integrity: Platform Keyring initialized241machine # [ 0.358995] integrity: Machine keyring initialized242machine # [ 0.391083] Key type asymmetric registered243machine # [ 0.391101] Asymmetric key parser 'x509' registered244machine # [ 0.391181] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)245machine # [ 0.391317] io scheduler mq-deadline registered246machine # [ 0.391322] io scheduler kyber registered247machine # [ 0.405804] pl061_gpio 9030000.pl061: PL061 GPIO chip registered248machine # [ 0.406520] ledtrig-cpu: registered to indicate activity on CPUs249machine # [ 0.406957] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:250machine # [ 0.406973] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000251machine # [ 0.406987] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000252machine # [ 0.406993] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000253machine # [ 0.407013] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits254machine # [ 0.407040] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]255machine # [ 0.407119] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00256machine # [ 0.407126] pci_bus 0000:00: root bus resource [bus 00-ff]257machine # [ 0.407131] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]258machine # [ 0.407134] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]259machine # [ 0.407137] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]260machine # [ 0.407224] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint261machine # [ 0.407707] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint262machine # [ 0.407897] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]263machine # [ 0.407909] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]264machine # [ 0.407935] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]265machine # [ 0.407948] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]266machine # [ 0.408533] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint267machine # [ 0.408723] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]268machine # [ 0.408735] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]269machine # [ 0.408762] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]270machine # [ 0.409228] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint271machine # [ 0.409413] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]272machine # [ 0.409425] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]273machine # [ 0.409451] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]274machine # [ 0.434182] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint275machine # [ 0.434369] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]276machine # [ 0.434382] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]277machine # [ 0.434408] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]278machine # [ 0.434420] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]279machine # [ 0.434882] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint280machine # [ 0.435077] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.435103] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.435571] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint283machine # [ 0.435767] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]284machine # [ 0.435793] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]285machine # [ 0.436203] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint286machine # [ 0.436398] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]287machine # [ 0.436661] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint288machine # [ 0.436854] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]289machine # [ 0.436881] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]290machine # [ 0.437349] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint291machine # [ 0.437551] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]292machine # [ 0.437578] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]293machine # [ 0.462222] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint294machine # [ 0.462441] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]295machine # [ 0.462468] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]296machine # [ 0.462957] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint297machine # [ 0.463240] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]298machine # [ 0.463258] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]299machine # [ 0.463284] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]300machine # [ 0.463759] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint301machine # [ 0.463949] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]302machine # [ 0.463961] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]303machine # [ 0.463988] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]304machine # [ 0.464705] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned305machine # [ 0.464712] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned306machine # [ 0.464716] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned307machine # [ 0.464764] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned308machine # [ 0.464810] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned309machine # [ 0.464856] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned310machine # [ 0.464904] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned311machine # [ 0.464955] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned312machine # [ 0.465000] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned313machine # [ 0.465046] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned314machine # [ 0.465092] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned315machine # [ 0.465136] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned316machine # [ 0.465209] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned317machine # [ 0.465255] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned318machine # [ 0.465274] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned319machine # [ 0.465292] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned320machine # [ 0.465310] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned321machine # [ 0.465329] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned322machine # [ 0.465348] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned323machine # [ 0.465367] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned324machine # [ 0.465387] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned325machine # [ 0.465406] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned326machine # [ 0.465426] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned327machine # [ 0.465445] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned328machine # [ 0.465463] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned329machine # [ 0.465482] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned330machine # [ 0.465500] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned331machine # [ 0.465518] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned332machine # [ 0.465536] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned333machine # [ 0.465554] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned334machine # [ 0.465572] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned335machine # [ 0.465597] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]336machine # [ 0.465602] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]337machine # [ 0.465606] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]338machine # [ 0.506471] pci 0000:00:07.0: enabling device (0000 -> 0002)339machine # [ 0.537710] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)340machine # [ 0.539889] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)341machine # [ 0.549961] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)342machine # [ 0.552179] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)343machine # [ 0.562327] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)344machine # [ 0.564477] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)345machine # [ 0.568050] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)346machine # [ 0.577842] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)347machine # [ 0.580090] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)348machine # [ 0.583643] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)349machine # [ 0.594389] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)350machine # [ 0.600905] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled351machine # [ 0.603650] msm_serial: driver initialized352machine # [ 0.603804] SuperH (H)SCI(F) driver initialized353machine # [ 0.603866] STM32 USART driver initialized354machine # [ 0.640720] loop: module loaded355machine # [ 0.640949] virtio_blk virtio2: 1/0/0 default/read/poll queues356machine # [ 0.649995] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)357machine # [ 0.655501] megasas: 07.734.00.00-rc1358machine # [ 0.656314] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]359machine # [ 0.660050] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000360machine # [ 0.660069] Intel/Sharp Extended Query Table at 0x0031361machine # [ 0.663661] Using buffer write method362machine # [ 0.663699] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]363machine # [ 0.665261] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000364machine # [ 0.665277] Intel/Sharp Extended Query Table at 0x0031365machine # [ 0.680685] Using buffer write method366machine # [ 0.680732] Concatenating MTD devices:367machine # [ 0.680735] (0): "0.flash"368machine # [ 0.680738] (1): "0.flash"369machine # [ 0.680740] into device "0.flash"370machine # [ 0.978222] Freeing initrd memory: 26072K371machine # [ 0.984632] tun: Universal TUN/TAP device driver, 1.6372machine # [ 0.988576] thunder_xcv, ver 1.0373machine # [ 0.988607] thunder_bgx, ver 1.0374machine # [ 0.988626] nicpf, ver 1.0375machine # [ 0.989239] e1000: Intel(R) PRO/1000 Network Driver376machine # [ 0.989243] e1000: Copyright (c) 1999-2006 Intel Corporation.377machine # [ 0.989271] e1000e: Intel(R) PRO/1000 Network Driver378machine # [ 0.989278] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.379machine # [ 0.989302] igb: Intel(R) Gigabit Ethernet Network Driver380machine # [ 0.989306] igb: Copyright (c) 2007-2014 Intel Corporation.381machine # [ 0.989332] igbvf: Intel(R) Gigabit Virtual Function Network Driver382machine # [ 0.989336] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.383machine # [ 0.989473] sky2: driver version 1.30384machine # [ 0.998956] usbcore: registered new interface driver usb-storage385machine # [ 0.999065] usbcore: registered new interface driver usbserial_generic386machine # [ 0.999078] usbserial: USB Serial support registered for generic387machine # [ 0.999710] hv_vmbus: registering driver hyperv_keyboard388machine # [ 1.001172] ehci-pci 0000:00:07.0: EHCI Host Controller389machine # [ 1.001196] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1390machine # [ 1.001394] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000391machine # [ 1.007080] rtc-pl031 9010000.pl031: registered as rtc0392machine # [ 1.007104] rtc-pl031 9010000.pl031: setting system clock to 2026-10-21T03:00:38 UTC (1792551638)393machine # [ 1.007513] i2c_dev: i2c /dev entries driver394machine # [ 1.009684] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00395machine # [ 1.010007] hub 1-0:1.0: USB hub found396machine # [ 1.010022] hub 1-0:1.0: 6 ports detected397machine # [ 1.015928] sdhci: Secure Digital Host Controller Interface driver398machine # [ 1.015934] sdhci: Copyright(c) Pierre Ossman399machine # [ 1.016221] Synopsys Designware Multimedia Card Interface Driver400machine # [ 1.016719] sdhci-pltfm: SDHCI platform and OF driver helper401machine # [ 1.021237] hid: raw HID events driver (C) Jiri Kosina402machine # [ 1.021492] usbcore: registered new interface driver usbhid403machine # [ 1.021496] usbhid: USB HID core driver404machine # [ 1.024678] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available405machine # [ 1.027459] drop_monitor: Initializing network drop monitor service406machine # [ 1.027648] NET: Registered PF_INET6 protocol family407machine # [ 1.028203] Segment Routing with IPv6408machine # [ 1.028216] In-situ OAM (IOAM) with IPv6409machine # [ 1.028241] NET: Registered PF_PACKET protocol family410machine # [ 1.028294] 9pnet: Installing 9P2000 support411machine # [ 1.028334] Key type dns_resolver registered412machine # [ 1.038871] registered taskstats version 1413machine # [ 1.039007] Loading compiled-in X.509 certificates414machine # [ 1.047972] Demotion targets for Node 0: null415machine # [ 1.048094] Key type .fscrypt registered416machine # [ 1.048097] Key type fscrypt-provisioning registered417machine # [ 1.048192] ima: No TPM chip found, activating TPM-bypass!418machine # [ 1.048214] ima: Allocated hash algorithm: sha1419machine # [ 1.048242] ima: No architecture policies found420machine # [ 1.049051] input: gpio-keys as /devices/platform/gpio-keys/input/input0421machine # [ 1.071332] clk: Disabling unused clocks422machine # [ 1.071351] PM: genpd: Disabling unused power domains423machine # [ 1.077297] Freeing unused kernel memory: 7808K424machine # [ 1.077524] Run /init as init process425machine # [ 1.094716] fuse: module verification failed: signature and/or required key missing - tainting kernel426machine # [ 1.097422] fuse: init (API version 7.45)427machine # [ 1.102202] virtiofs virtio6: discovered new tag: nix-store428machine # [ 1.102991] virtiofs virtio6: virtio_fs_setup_dax: No cache capability429machine # [ 1.119433] virtiofs virtio7: discovered new tag: shared430machine # [ 1.120266] virtiofs virtio7: virtio_fs_setup_dax: No cache capability431machine # [ 1.123282] virtiofs virtio8: discovered new tag: xchg432machine # [ 1.124061] virtiofs virtio8: virtio_fs_setup_dax: No cache capability433machine # [ 1.136541] systemd[1]: Successfully made /usr/ read-only.434machine # [ 1.137087] systemd[1]: systemd 263-cb90bff 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 +EXPERIMENTAL-ENVVAR)435machine # [ 1.137104] systemd[1]: Detected virtualization qemu.436machine # [ 1.137145] systemd[1]: Detected architecture arm64.437machine # [ 1.137152] systemd[1]: Running in initrd.438machine # [ 1.137432] systemd[1]: Initializing machine ID from random generator.439machine # [ 1.137541] systemd[1]: Hostname set to <machine>.440machine # [ 1.243398] systemd[1]: bpf-restrict-fs: LSM BPF program attached441machine # [ 1.261770] usb 1-1: new high-speed USB device number 2 using ehci-pci442machine # [ 1.307411] systemd[1]: Queued start job for default target Initrd Default Target.443machine # [ 1.315912] systemd[1]: Created slice Slice /system/modprobe.444machine # [ 1.316111] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.445machine # [ 1.316140] systemd[1]: Expecting device /dev/disk/by-label/nixos...446machine # [ 1.316163] systemd[1]: Reached target Path Units.447machine # [ 1.316177] systemd[1]: Reached target Slice Units.448machine # [ 1.316190] systemd[1]: Reached target Swaps.449machine # [ 1.316203] systemd[1]: Reached target Timer Units.450machine # [ 1.316341] systemd[1]: Listening on D-Bus System Message Bus Socket.451machine # [ 1.316617] systemd[1]: Listening on Journal Socket (/dev/log).452machine # [ 1.316771] systemd[1]: Listening on Journal Sockets.453machine # [ 1.316844] systemd[1]: Listening on udev Kernel Socket.454machine # [ 1.317002] systemd[1]: Listening on udev Varlink Socket.455machine # [ 1.317018] systemd[1]: Reached target Socket Units.456machine # [ 1.328172] systemd[1]: Starting Create List of Static Device Nodes...457machine # [ 1.328243] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs458machine # [ 1.341888] systemd[1]: Mounting Kernel Configuration File System...459machine # [ 1.352888] systemd[1]: Starting Journal Service...460machine # [ 1.370175] systemd[1]: Starting Load Kernel Modules...461machine # [ 1.370302] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os462machine # [ 1.389883] systemd[1]: Starting Coldplug All udev Devices...463machine # [ 1.392139] systemd-journald[71]: Collecting audit messages is disabled.464machine # [ 1.415629] systemd[1]: Finished Create List of Static Device Nodes.465machine # [ 1.424900] 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/input1466machine # [ 1.443316] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...467machine # [ 1.458754] systemd[1]: Mounted Kernel Configuration File System.468machine # [ 1.494064] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.469machine # [ 1.494332] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev470machine # [ 1.514726] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0471machine # [ 1.514997] [drm] features: -virgl +edid -resource_blob -host_visible472machine # [ 1.515000] [drm] features: -context_init473machine # [ 1.515774] [drm] number of scanouts: 1474machine # [ 1.515790] [drm] number of cap sets: 0475machine # [ 1.516139] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0476machine # [ 1.517185] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.477machine # [ 1.534053] systemd[1]: Starting Create Static Device Nodes in /dev...478machine # [ 1.538064] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic479machine # [ 1.538082] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0480machine # [ 1.558062] Console: switching to colour frame buffer device 160x50481machine # [ 1.560881] systemd-modules-load[72]: Inserted module 'dm_mod'482machine # [ 1.562589] systemd-modules-load[72]: Module 'virtio_balloon' is built in483machine # [ 1.579767] systemd[1]: Started Journal Service.484machine # [ 1.567470] systemd-modules-load[72]: Module 'virtio_console' is built in[ 1.584005] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device485machine # 486machine # [ 1.579287] systemd-modules-load[72]: Inserted module 'virtio_gpu'487machine # [ 1.588306] systemd-modules-load[72]: Module 'virtio_rng' is built in488machine # [ 1.592324] systemd[1]: Finished Load Kernel Modules.489machine # [ 1.593276] systemd[1]: Starting Apply Kernel Variables...490machine # [ 1.596894] systemd[1]: Finished Create Static Device Nodes in /dev.491machine # [ 1.602104] systemd[1]: Reached target Preparation for Local File Systems.492machine # [ 1.603207] systemd[1]: Reached target Local File Systems.493machine # [ 1.619421] systemd[1]: Starting Create System Files and Directories...[ 1.633707] usb 1-2: new high-speed USB device number 3 using ehci-pci494machine # 495machine # [ 1.629855] systemd[1]: Starting Rule-based Manager for Device Events and Files...496machine # [ 1.652932] systemd[1]: Finished Apply Kernel Variables.497machine # [ 1.660628] systemd[1]: Finished Create System Files and Directories.498machine # [ 1.713417] systemd-udevd[86]: Using default interface naming scheme 'v261'.499machine # [ 1.742207] systemd[1]: Started Rule-based Manager for Device Events and Files.500machine # [ 1.783543] 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/input2501machine # [ 1.783819] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0502machine # [ 1.844092] systemd[1]: Starting Virtual Console Setup...503machine # [ 1.929580] systemd-vconsole-setup[98]: Configuration of first virtual console was skipped, ignoring remaining ones.504machine # [ 1.932583] systemd[1]: Finished Virtual Console Setup.505machine # [ 2.548266] (udev-worker)[94]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.506machine # [ 2.583504] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.507machine # [ 2.588235] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.508machine # [ 2.640402] systemd[1]: Finished Coldplug All udev Devices.509machine # [ 2.642032] systemd[1]: Found device /dev/disk/by-label/nixos.510machine # [ 2.647765] systemd[1]: Reached target Initrd Root Device.511machine # [ 2.650267] systemd[1]: Reached target System Initialization.512machine # [ 2.652144] systemd[1]: Reached target Basic System.513machine # [ 2.654975] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...514machine # [ 2.702071] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks515machine # [ 2.709278] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.516machine # [ 2.712112] systemd[1]: Mounting /sysroot...517machine # [ 2.755824] EXT4-fs (vda): mounted filesystem 76300bdd-19c7-4d03-95ea-d02c3b23ee94 r/w with ordered data mode. Quota mode: none.518machine # [ 2.742381] systemd[1]: Mounted /sysroot.519machine # [ 2.744966] systemd[1]: Reached target Initrd Root File System.520machine # [ 2.750044] systemd[1]: Starting Mountpoints Configured in the Real Root...521machine # [ 2.775781] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.522machine # [ 2.781564] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...523machine # [ 2.786555] systemd[1]: Reloading...524machine # [ 2.967616] systemd[1]: Reloading finished in 182 ms.525machine # [ 3.000136] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...526machine # [ 3.003522] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...527machine # [ 3.008821] systemd[1]: initrd-parse-etc.service: Deactivated successfully.528machine # [ 3.010806] systemd[1]: Finished Mountpoints Configured in the Real Root.529machine # [ 3.013165] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.530machine # [ 3.377214] systemd[1]: Mounting /sysroot/nix/.ro-store...531machine # [ 3.394864] systemd[1]: Mounting /sysroot/nix/.rw-store...532machine # [ 3.424266] systemd[1]: Mounting /sysroot/run...533machine # [ 3.428238] systemd[1]: Mounting /sysroot/tmp/shared...534machine # [ 3.445483] systemd[1]: Mounting /sysroot/tmp/xchg...535machine # [ 3.470268] systemd[1]: Mounted /sysroot/nix/.ro-store.536machine # [ 3.480002] systemd[1]: Mounted /sysroot/nix/.rw-store.537machine # [ 3.501391] systemd[1]: Mounted /sysroot/run.538machine # [ 3.520763] systemd[1]: Starting rw-sysroot-nix-store.service...539machine # [ 3.532514] systemd[1]: Mounted /sysroot/tmp/shared.540machine # [ 3.536170] systemd[1]: Mounted /sysroot/tmp/xchg.541machine # [ 3.554708] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.542machine # [ 3.556330] systemd[1]: Finished rw-sysroot-nix-store.service.543machine # [ 3.885542] (udev-worker)[93]: mtd0ro: Failed to find and pin callout binary "/nix/store/qxvgvvwldpjx75dk3nq89xjz7vvsyq6l-systemd-263-cb90bff/lib/udev/mtd_probe": No such file or directory544machine # [ 3.894815] (udev-worker)[93]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory545machine # [ 3.928393] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.546machine # [ 3.930790] systemd[1]: Stopped Virtual Console Setup.547machine # [ 3.935964] systemd[1]: Stopping Virtual Console Setup...548machine # [ 3.936989] systemd[1]: Starting Virtual Console Setup...549machine # [ 3.962835] systemd-vconsole-setup[150]: Configuration of first virtual console was skipped, ignoring remaining ones.550machine # [ 3.966430] systemd[1]: Finished Virtual Console Setup.551machine # [ 4.377823] systemd[1]: Mounting /sysroot/nix/store...552machine # [ 4.423298] systemd[1]: Mounted /sysroot/nix/store.553machine # [ 4.425856] systemd[1]: Reached target Initrd File Systems.554machine # [ 4.430002] systemd[1]: Starting Find NixOS closure...555machine # [ 4.437853] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...556machine # [ 4.480361] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.557machine # [ 4.483895] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.558machine # [ 4.493049] systemd[1]: Finished Find NixOS closure.559machine # [ 4.495548] systemd[1]: Reached target Initrd Default Target.560machine # [ 4.497237] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...561machine # [ 4.524579] systemd[1]: Stopped target Initrd Default Target.562machine # [ 4.528634] systemd[1]: Stopped target Basic System.563machine # [ 4.529606] systemd[1]: Stopped target Initrd Root Device.564machine # [ 4.531364] systemd[1]: Stopped target Path Units.565machine # [ 4.534281] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.566machine # [ 4.536186] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.567machine # [ 4.540871] systemd[1]: Stopped target Slice Units.568machine # [ 4.544847] systemd[1]: Stopped target Socket Units.569machine # [ 4.546866] systemd[1]: Stopped target System Initialization.570machine # [ 4.552816] systemd[1]: Stopped target Swaps.571machine # [ 4.554573] systemd[1]: Stopped target Timer Units.572machine # [ 4.556694] systemd[1]: dbus.socket: Deactivated successfully.573machine # [ 4.558648] systemd[1]: Closed D-Bus System Message Bus Socket.574machine # [ 4.560596] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.575machine # [ 4.566038] systemd[1]: Stopped Find NixOS closure.576machine # [ 4.567140] systemd[1]: Starting rw-sysroot-nix-store.service...577machine # [ 4.570002] systemd[1]: systemd-sysctl.service: Deactivated successfully.578machine # [ 4.572380] systemd[1]: Stopped Apply Kernel Variables.579machine # [ 4.573311] systemd[1]: systemd-modules-load.service: Deactivated successfully.580machine # [ 4.574421] systemd[1]: Stopped Load Kernel Modules.581machine # [ 4.575449] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.582machine # [ 4.576868] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.583machine # [ 4.578052] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.584machine # [ 4.579180] systemd[1]: Stopped Create System Files and Directories.585machine # [ 4.587136] systemd[1]: Stopped target Local File Systems.586machine # [ 4.591420] systemd[1]: Stopped target Preparation for Local File Systems.587machine # [ 4.596338] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.588machine # [ 4.597420] systemd[1]: Stopped Coldplug All udev Devices.589machine # [ 4.598221] systemd[1]: Stopping Rule-based Manager for Device Events and Files...590machine # [ 4.599335] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.591machine # [ 4.604309] systemd[1]: Stopped Virtual Console Setup.592machine # [ 4.605114] systemd[1]: systemd-udevd.service: Deactivated successfully.593machine # [ 4.606891] systemd[1]: Stopped Rule-based Manager for Device Events and Files.594machine # [ 4.608992] systemd[1]: systemd-udevd.service: Consumed 1.322s CPU time over 2.942s wall clock time, 27.4M memory peak.595machine # [ 4.612241] systemd[1]: initrd-cleanup.service: Deactivated successfully.596machine # [ 4.613411] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.597machine # [ 4.614456] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully.598machine # [ 4.615587] systemd[1]: Closed udev Varlink Socket.599machine # [ 4.616476] systemd[1]: Starting Cleanup udev Database...600machine # [ 4.617265] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.601machine # [ 4.618357] systemd[1]: Stopped Create Static Device Nodes in /dev.602machine # [ 4.619262] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.603machine # [ 4.624316] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.604machine # [ 4.625394] systemd[1]: kmod-static-nodes.service: Deactivated successfully.605machine # [ 4.627421] systemd[1]: Stopped Create List of Static Device Nodes.606machine # [ 4.632278] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.607machine # [ 4.633365] systemd[1]: Finished rw-sysroot-nix-store.service.608machine # [ 4.667492] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.609machine # [ 4.671126] systemd[1]: Finished Cleanup udev Database.610machine # [ 4.671952] systemd[1]: Reached target Switch Root.611machine # [ 4.676155] systemd[1]: Starting NixOS Activation...612machine # [ 4.755602] initrd-nixos-activation-start[173]: booting system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test613machine # [ 4.787203] initrd-nixos-activation-start[173]: running activation script...614machine # [ 5.017722] initrd-nixos-activation-start[196]: setting up /etc...615machine # [ 5.139338] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.616machine # [ 5.142538] systemd[1]: Finished NixOS Activation.617machine # [ 5.143910] systemd[1]: Starting Switch Root...618machine # [ 5.168730] systemd[1]: Switching root.619machine # [ 5.351526] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).620machine # [ 5.447222] NET: Registered PF_VSOCK protocol family621machine # [ 5.519023] systemd[1]: systemd 263-cb90bff 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 +EXPERIMENTAL-ENVVAR)622machine # [ 5.519975] systemd[1]: Detected virtualization qemu.623machine # [ 5.520043] systemd[1]: Detected architecture arm64.624machine # [ 5.520143] systemd[1]: Detected first boot.625machine # [ 5.528808] systemd[1]: Initializing machine ID from random generator.626machine # [ 5.686885] systemd[1]: bpf-restrict-fs: LSM BPF program attached627machine # [ 5.838002] systemd[1]: Applying preset policy.628machine # [ 6.060764] systemd[1]: Populated /etc with preset unit settings.629machine # [ 6.263876] systemd[1]: initrd-switch-root.service: Deactivated successfully.630machine # [ 6.264338] systemd[1]: Stopped initrd-switch-root.service.631machine # [ 6.268297] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.632machine # [ 6.269524] systemd[1]: Created slice Slice /system/getty.633machine # [ 6.273175] systemd[1]: Created slice User and Session Slice.634machine # [ 6.273430] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.635machine # [ 6.276504] systemd[1]: Started Forward Password Requests to Wall Directory Watch.636machine # [ 6.276945] systemd[1]: Expecting device /dev/hvc0...637machine # [ 6.277211] systemd[1]: Expecting device /dev/ttyAMA0...638machine # [ 6.277471] systemd[1]: Reached target Local Encrypted Volumes.639machine # [ 6.280982] systemd[1]: Stopped target initrd-fs.target.640machine # [ 6.282884] systemd[1]: Stopped target initrd-root-fs.target.641machine # [ 6.283285] systemd[1]: Stopped target initrd-switch-root.target.642machine # [ 6.283893] systemd[1]: Reached target Virtual Machines and Containers.643machine # [ 6.284494] systemd[1]: Reached target Path Units.644machine # [ 6.285033] systemd[1]: Reached target Remote File Systems.645machine # [ 6.285553] systemd[1]: Reached target Slice Units.646machine # [ 6.291045] systemd[1]: Reached target Swaps.647machine # [ 6.292671] systemd[1]: Listening on Query the User Interactively for a Password.648machine # [ 6.296173] systemd[1]: Listening on Process Core Dump Socket.649machine # [ 6.297570] systemd[1]: Listening on Credential Encryption/Decryption.650machine # [ 6.300475] systemd[1]: Listening on Factory Reset Management.651machine # [ 6.300993] systemd[1]: Listening on Hostname Service Socket.652machine # [ 6.306706] systemd[1]: Starting Journal Log Access Socket...653machine # [ 6.307751] systemd[1]: Listening on Journal Audit Socket.654machine # [ 6.311223] systemd[1]: Listening on Console Output Muting Service Socket.655machine # [ 6.311933] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.656machine # [ 6.312266] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os657machine # [ 6.312557] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki658machine # [ 6.322115] systemd[1]: Listening on Disk Repartitioning Service Socket.659machine # [ 6.322722] systemd[1]: Listening on udev Varlink Socket.660machine # [ 6.325379] systemd[1]: Mounting Huge Pages File System...661machine # [ 6.334032] systemd[1]: Mounting POSIX Message Queue File System...662machine # [ 6.346015] systemd[1]: Mounting Kernel Debug File System...663machine # [ 6.359796] systemd[1]: Mounting Kernel Trace File System...664machine # [ 6.375295] systemd[1]: Starting Create List of Static Device Nodes...665machine # [ 6.376508] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs666machine # [ 6.393346] systemd[1]: Mounting Kernel Configuration File System...667machine # [ 6.397752] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm668machine # [ 6.408541] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore669machine # [ 6.409047] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse670machine # [ 6.430513] systemd[1]: Mounting FUSE Control File System...671machine # [ 6.438276] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67672machine # [ 6.472934] systemd[1]: Starting Journal Service...673machine # [ 6.487025] systemd[1]: Starting Load Kernel Modules...674machine # [ 6.514028] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...675machine # [ 6.534576] systemd[1]: Starting Remount Root and Kernel File Systems...676machine # [ 6.535006] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os677machine # [ 6.542311] systemd-journald[267]: Collecting audit messages is enabled.678machine # [ 6.537206] systemd[1]: Queued start job for default target Multi-User System.679machine # [ 6.538853] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0.680machine # [ 6.545683] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0.681machine # [ 6.547185] systemd[1]: systemd-journald.service: Deactivated successfully.682machine # [ 6.566224] systemd[1]: Starting Coldplug All udev Devices...683machine # [ 6.569187] systemd[1]: Started Journal Service.684machine # [ 6.573122] systemd-modules-load[268]: Module 'atkbd' is built in685machine # [ 6.578189] systemd-modules-load[268]: Module 'loop' is built in686machine # [ 6.586017] systemd[1]: Listening on Journal Log Access Socket.687machine # [ 6.600355] systemd[1]: Mounted Huge Pages File System.688machine # [ 6.604362] systemd[1]: Mounted POSIX Message Queue File System.689machine # [ 6.609334] systemd[1]: Mounted Kernel Debug File System.690machine # [ 6.612596] systemd[1]: Mounted Kernel Trace File System.691machine # [ 6.613534] systemd[1]: Finished Create List of Static Device Nodes.692machine # [ 6.614498] systemd[1]: Finished Load Kernel Modules.693machine # [ 6.630785] systemd[1]: Mounted FUSE Control File System.694machine # [ 6.644102] systemd[1]: Starting Firewall...695machine # [ 6.655912] systemd[1]: Starting Apply Kernel Variables...696machine # [ 6.662144] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...697machine # [ 6.663407] systemd[1]: Mounted Kernel Configuration File System.698machine # [ 6.703428] systemd-oomd[269]: No swap; memory pressure usage will be degraded699machine # [ 6.727353] EXT4-fs (vda): re-mounted 76300bdd-19c7-4d03-95ea-d02c3b23ee94.700machine # [ 6.722533] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.701machine # [ 6.743678] systemd[1]: Finished Remount Root and Kernel File Systems.702machine # [ 6.748468] systemd[1]: Listening on Disk Image Download Service Socket.703machine # [ 6.757956] systemd[1]: Starting Flush Journal to Persistent Storage...704machine # [ 6.759136] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore705machine # [ 6.776698] systemd[1]: Starting Load/Save OS Random Seed...706machine # [ 6.777602] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os707machine # [ 6.860445] systemd[1]: Finished Apply Kernel Variables.708machine # [ 6.890991] systemd-journald[267]: Received client request to flush runtime journal.709machine # [ 6.932465] systemd[1]: Finished Load/Save OS Random Seed.710machine # [ 6.933477] systemd[1]: Reached target First Boot Complete.711machine # [ 6.934318] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.712machine # [ 6.948670] systemd[1]: Starting Create Static Device Nodes in /dev...713machine # [ 6.952201] systemd[1]: Finished Flush Journal to Persistent Storage.714machine # [ 7.106119] systemd[1]: Finished Create Static Device Nodes in /dev.715machine # [ 7.107277] systemd[1]: Reached target Preparation for Local File Systems.716machine # [ 7.109995] systemd[1]: Starting Rule-based Manager for Device Events and Files...717machine # [ 7.252933] systemd[1]: Mounting /run/wrappers...718machine # [ 7.265823] systemd-udevd[307]: Using default interface naming scheme 'v261'.719machine # [ 7.329926] systemd[1]: Mounted /run/wrappers.720machine # [ 7.330826] systemd[1]: Reached target Local File Systems.721machine # [ 7.339226] systemd[1]: Listening on Boot Loader Control Service Socket.722machine # [ 7.346363] systemd[1]: Starting Create SUID/SGID Wrappers...723machine # [ 7.347386] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.724machine # [ 7.350501] systemd[1]: Starting Save Transient machine-id to Disk...725machine # [ 7.365792] systemd[1]: Starting Create System Files and Directories...726machine # [ 7.493594] systemd[1]: Started Rule-based Manager for Device Events and Files.727machine # [ 7.513984] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.728machine # [ 7.527061] systemd[1]: Finished Save Transient machine-id to Disk.729machine # [ 7.592608] systemd[1]: Finished Create System Files and Directories.730machine # [ 7.604879] systemd[1]: Starting Rebuild Journal Catalog...731machine # [ 7.612275] systemd[1]: Starting Record System Boot/Shutdown in UTMP...732machine # [ 7.712989] systemd[1]: Finished Record System Boot/Shutdown in UTMP.733machine # [ 7.754398] systemd[1]: Finished Rebuild Journal Catalog.734machine # [ 7.764501] systemd[1]: Starting Update is Completed...735machine # [ 7.832741] systemd[1]: Finished Update is Completed.736machine # [ 8.015601] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs737machine # [ 8.085148] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse738machine # [ 8.224168] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.739machine # [ 8.227052] systemd[1]: Finished Create SUID/SGID Wrappers.740machine # [ 8.369003] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.741machine # [ 8.388699] systemd[1]: Finished Coldplug All udev Devices.742machine # [ 8.392160] systemd[1]: Reached target System Initialization.743machine # [ 8.393198] systemd[1]: Started Discard unused filesystem blocks once a week.744machine # [ 8.394261] systemd[1]: Started Daily Cleanup of Temporary Directories.745machine # [ 8.395260] systemd[1]: Reached target Timer Units.746machine # [ 8.405944] systemd[1]: Listening on D-Bus System Message Bus Socket.747machine # [ 8.407137] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.748machine # [ 8.414449] systemd[1]: Reached target Socket Units.749machine # [ 8.415404] systemd[1]: Reached target Basic System.750machine # [ 8.421118] systemd[1]: Starting Import lastlog data into lastlog2 database...751machine # [ 8.422204] systemd[1]: Starting Name Service Cache Daemon (nsncd)...752machine # [ 8.423318] systemd[1]: Starting Post-Boot Actions...753machine # [ 8.437506] systemd[1]: Started Reset console on configuration changes.754machine # [ 8.465734] systemd[1]: Starting resolvconf update...755machine # [ 8.483775] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.756machine # [ 8.567441] systemd[1]: Started backdoor.service.757machine # [ 8.578934] systemd[1]: Starting D-Bus System Message Bus...758machine # [ 8.617286] systemd[1]: Started Name Service Cache Daemon (nsncd).759machine # [ 8.653369] systemd[1]: Reached target Host and Network Name Lookups.760machine # [ 8.661293] systemd[1]: Reached target User and Group Name Lookups.761machine # [ 8.664430] nsncd[428]: Oct 21 03:00:46.106 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"762machine # [ 8.702112] systemd[1]: Starting User Login Management...763machine # [ 8.717280] systemd[1]: Finished Post-Boot Actions.764machine # [ 8.803809] systemd[1]: Finished Import lastlog data into lastlog2 database.765machine # connecting to host...766machine # [ 8.870436] (udev-worker)[336]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.767machine # [ 8.888344] (udev-worker)[336]: Network interface NamePolicy= disabled on kernel command line.768machine # [ 8.889998] dbus-broker-launch[439]: Looking up NSS user entry for 'systemd-timesync'...769machine # [ 8.891344] dbus-broker-launch[439]: NSS returned no entry for 'systemd-timesync'770machine # [ 8.908659] dbus-broker-launch[439]: Invalid user-name in /nix/store/hi2awyip155pp6lbgmqp52dshc41z2n6-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"771machine # [ 8.911579] (udev-worker)[394]: Network interface NamePolicy= disabled on kernel command line.772machine: Guest shell says: b'Spawning backdoor root shell...\n'773machine: connected to guest root shell774machine: (connecting took 9.21 seconds)775machine: (finished: waiting for the VM to finish booting, in 9.70 seconds)776machine # [ 8.959836] systemd[1]: Started D-Bus System Message Bus.777machine # [ 9.022568] dbus-broker-launch[439]: Ready778machine # [ 9.041072] systemd-logind[454]: New seat seat0.779machine # [ 9.047766] systemd[1]: Started User Login Management.780machine # [ 9.059921] systemd[1]: Starting linger-users.service...781machine # [ 9.110636] systemd[1]: Stopped target Host and Network Name Lookups.782machine # [ 9.120884] systemd[1]: Stopping Host and Network Name Lookups...783machine # [ 9.121904] systemd[1]: Stopped target User and Group Name Lookups.784machine # [ 9.122841] systemd[1]: Stopping User and Group Name Lookups...785machine # [ 9.123709] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...786machine # [ 9.211114] systemd[1]: nscd.service: Deactivated successfully.787machine # [ 9.216466] systemd[1]: Stopped Name Service Cache Daemon (nsncd).788machine # [ 9.297528] systemd[1]: Created slice Slice /user/1001.789machine # [ 9.320265] systemd[1]: Starting Name Service Cache Daemon (nsncd)...790machine # [ 9.340159] systemd[1]: Starting User Runtime Directory /run/user/1001...791machine # [ 9.403238] systemd[1]: linger-users.service: Deactivated successfully.792machine # [ 9.411962] systemd[1]: Finished linger-users.service.793machine # [ 9.435287] systemd[1]: Finished resolvconf update.794machine # [ 9.450412] systemd[1]: Starting DHCP Client...795machine # [ 9.456810] systemd[1]: Started Name Service Cache Daemon (nsncd).796machine # [ 9.463669] systemd[1]: Finished Firewall.797machine # [ 9.469033] nsncd[557]: Oct 21 03:00:46.965 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"798machine # [ 9.479795] systemd[1]: Condition check resulted in Virtio network device being skipped.799machine # [ 9.482317] systemd[1]: Condition check resulted in Virtio network device being skipped.800machine # [ 9.493259] systemd[1]: Reached target Preparation for Network.801machine # [ 9.494176] systemd[1]: Reached target Host and Network Name Lookups.802machine # [ 9.495182] systemd[1]: Reached target User and Group Name Lookups.803machine # [ 9.502515] systemd[1]: Starting Address configuration of eth1...804machine # [ 9.512380] systemd[1]: Starting Extra networking commands....805machine # [ 9.524098] systemd-logind[454]: Watching system buttons on /dev/input/event0 (gpio-keys)806machine # [ 9.576874] systemd[1]: Finished User Runtime Directory /run/user/1001.807machine # [ 9.609897] systemd[1]: Starting User Manager for UID 1001...808machine # [ 9.767286] mousedev: PS/2 mouse device common for all mice809machine # [ 9.772439] (systemd)[589]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)810machine # [ 9.807473] systemd-logind[454]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.811machine # [ 9.871422] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy812machine # [ 10.271412] network-addresses-eth1-start[584]: adding address 192.168.1.1/24... done813machine # [ 10.277763] systemd-logind[454]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)814machine # [ 10.294425] network-addresses-eth1-start[584]: adding address 2001:db8:1::1/64... done815machine # [ 10.295754] systemd[589]: Queued start job for default target Main User Target.816machine # [ 10.326223] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy817machine # [ 10.338937] systemd[589]: Created slice User Application Slice.818machine # [ 10.345881] systemd[589]: Started Daily Cleanup of User's Temporary Directories.819machine # [ 10.347163] systemd[589]: Reached target Paths.820machine # [ 10.347854] systemd[589]: Reached target Timers.821machine # [ 10.359339] systemd[589]: Starting D-Bus User Message Bus Socket...822machine # [ 10.362871] systemd[589]: Starting Create User Files and Directories...823machine # [ 10.363893] systemd[589]: Finished Create User Files and Directories.824machine # [ 10.373558] systemd[589]: Listening on D-Bus User Message Bus Socket.825machine # [ 10.374537] systemd[589]: Reached target Sockets.826machine # [ 10.375328] systemd[589]: Reached target Basic System.827machine # [ 10.381504] systemd[1]: Started User Manager for UID 1001.828machine # [ 10.382367] systemd[589]: Starting Run user-specific NixOS activation...829machine # [ 10.383426] systemd[589]: Finished Run user-specific NixOS activation.830machine # [ 10.394605] systemd[589]: Reached target Main User Target.831machine # [ 10.395511] systemd[589]: Startup finished in 481ms.832machine # [ 10.402189] systemd[1]: Finished Address configuration of eth1.833machine # [ 10.411479] dhcpcd[595]: dhcpcd-10.3.2 starting834machine # [ 10.432738] dhcpcd[656]: dev: loaded udev835machine # [ 10.479319] 8021q: 802.1Q VLAN Support v1.8836machine # [ 10.479678] 8021q: adding VLAN 0 to HW filter on device eth1837machine # [ 10.589089] cfg80211: Loading compiled-in X.509 certificates for regulatory database838machine # [ 10.598210] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'839machine # [ 10.598699] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'840machine # [ 10.600466] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2841machine # [ 10.600776] cfg80211: failed to load regulatory.db842machine # [ 10.777273] systemd[1]: Finished Extra networking commands..843machine # [ 10.802394] systemd[1]: Reached target Network.844machine # [ 10.803264] systemd[1]: Starting Permit User Sessions...845machine # [ 11.078233] systemd[1]: Finished Permit User Sessions.846machine # [ 11.097589] 8021q: adding VLAN 0 to HW filter on device eth0847machine # [ 11.097207] systemd[1]: Started Getty on tty1.848machine # [ 11.110208] systemd[1]: Reached target Login Prompts.849machine # [ 11.124501] dhcpcd[656]: eth0: waiting for carrier850machine # [ 11.125348] dhcpcd[656]: eth0: carrier acquired851machine # [ 11.178160] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3852machine # [ 11.212249] dhcpcd[656]: DUID 00:01:00:01:32:6a:e7:60:52:54:00:12:34:56853machine # [ 11.213366] dhcpcd[656]: eth0: IAID 00:12:34:56854machine # [ 11.214044] dhcpcd[656]: eth0: adding address fe80::5054:ff:fe12:3456855machine # [ 11.457469] dhcpcd[656]: eth0: soliciting a DHCP lease856machine # [ 11.471512] dhcpcd[656]: eth0: offered 10.0.2.15 from 10.0.2.2857machine # [ 11.497262] dhcpcd[656]: eth0: probing address 10.0.2.15/24858machine # [ 11.537664] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.859machine # [ 11.555353] systemd[1]: Starting Virtual Console Setup...860machine # [ 11.583335] systemd-logind[454]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)861machine # [ 11.592896] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.862machine # [ 11.594046] systemd[1]: Stopped Virtual Console Setup.863machine # [ 11.594861] systemd[1]: Starting Virtual Console Setup...864machine # [ 11.705037] systemd-vconsole-setup[705]: Configuration of first virtual console was skipped, ignoring remaining ones.865machine # [ 11.712567] systemd[1]: Finished Virtual Console Setup.866machine # [ 13.104828] dhcpcd[656]: eth0: soliciting an IPv6 router867machine # [ 13.106980] dhcpcd[656]: eth0: Router Advertisement from fe80::2868machine # [ 13.108933] dhcpcd[656]: eth0: adding address fec0::5054:ff:fe12:3456/64869machine # [ 13.110442] dhcpcd[656]: eth0: adding route to fec0::/64870machine # [ 13.111682] dhcpcd[656]: eth0: adding default route via fe80::2871machine # [ 16.536681] dhcpcd[656]: eth0: leased 10.0.2.15 for 86400 seconds872machine # [ 16.536864] dhcpcd[656]: eth0: adding route to 10.0.2.0/24873machine # [ 16.536933] dhcpcd[656]: eth0: adding default route via 10.0.2.2874machine # [ 16.647255] systemd[1]: Started DHCP Client.875machine # [ 16.649847] systemd[1]: Reached target Multi-User System.876machine # [ 16.651099] systemd[1]: Startup finished in 1.064s (kernel) + 4.345s (initrd) + 11.239s (userspace) = 16.649s.877machine: (finished: waiting for unit multi-user.target, in 18.23 seconds)878machine: must succeed: /nix/store/kabiwhgyb1j3wv90hzpkic8zvq2cw8s3-stderr-runner /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test879machine # Checking switch inhibitors... done880machine # [ 17.681732] nixos[822]: switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test881machine # [ 17.684530] systemd[1]: Stopped target Remote File Systems.882machine # activating the configuration...883machine # [ 17.687853] systemd[1]: Stopped target Local File Systems.884machine # setting up /etc...885machine # [ 17.966364] systemd[1]: Reload requested from client PID 822 ('.switch-to-conf') (unit backdoor.service)...886machine # [ 17.968423] systemd[1]: Reloading...887machine # [ 18.265126] systemd[1]: Reloading finished in 295 ms.888machine # reloading user units for usertest...889machine # [ 18.317955] systemd[589]: Created slice User Core Session Slice.890machine # [ 18.320162] systemd[589]: Starting D-Bus User Message Bus...891machine # [ 18.355554] dbus-broker-launch[892]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/hi2awyip155pp6lbgmqp52dshc41z2n6-system-path/share/dbus-1/services/org.freedesktop.import1.service'892machine # [ 18.358997] dbus-broker-launch[892]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/hi2awyip155pp6lbgmqp52dshc41z2n6-system-path/share/dbus-1/services/org.freedesktop.machine1.service'893machine # [ 18.362147] dbus-broker-launch[892]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/hi2awyip155pp6lbgmqp52dshc41z2n6-system-path/share/dbus-1/services/org.freedesktop.portable1.service'894machine # [ 18.365157] dbus-broker-launch[892]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/hi2awyip155pp6lbgmqp52dshc41z2n6-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'895machine # [ 18.370804] dbus-broker-launch[892]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored896machine # [ 18.373038] dbus-broker-launch[892]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored897machine # [ 18.374898] systemd[589]: Started D-Bus User Message Bus.898machine # [ 18.378901] dbus-broker-launch[892]: Ready899machine # [ 18.420044] systemd[589]: Reexecution requested from client PID 891 ('switch-to-confi')...900machine # [ 18.424348] systemd[589]: Reexecuting.901machine # [ 18.483807] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy902machine # [ 18.601336] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy903machine # [ 18.608790] systemd[589]: Reload requested from client PID 891 ('switch-to-confi')...904machine # [ 18.612776] systemd[589]: Reloading...905machine # [ 18.718519] systemd[589]: Reloading finished in 106 ms.906machine # [ 18.736183] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy907machine # restarting the following user units: nixos-activation.service908machine # [ 18.746961] systemd[589]: Stopped Run user-specific NixOS activation.909machine # [ 18.749407] systemd[589]: Stopping Run user-specific NixOS activation...910machine # [ 18.751899] systemd[589]: Starting Run user-specific NixOS activation...911machine # [ 18.783527] systemd[589]: Finished Run user-specific NixOS activation.912machine # restarting sysinit-reactivation.target913machine # [ 18.796475] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....914machine # [ 18.884389] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..915machine # [ 18.888609] systemd[1]: Reached target Reactivate sysinit units.916machine # [ 18.891182] systemd[1]: Reached target Local File Systems.917machine # [ 18.896286] systemd[1]: Reached target Remote File Systems.918machine # [ 18.898953] systemd[1]: Starting linger-users.service...919machine # [ 18.907947] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os920machine # [ 18.917354] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki921machine # [ 18.918823] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore922machine # [ 18.929116] systemd[1]: Starting Create SUID/SGID Wrappers...923machine # [ 18.930022] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.924machine # [ 18.931356] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67925machine # [ 18.940325] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore926machine # [ 18.942071] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os927machine # [ 18.953640] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os928machine # [ 18.992613] systemd[1]: linger-users.service: Deactivated successfully.929machine # [ 18.995670] systemd[1]: Finished linger-users.service.930machine # [ 19.123006] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.931machine # [ 19.125887] systemd[1]: Finished Create SUID/SGID Wrappers.932machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service933machine # [ 19.383776] nixos[822]: finished switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test934machine: (finished: must succeed: /nix/store/kabiwhgyb1j3wv90hzpkic8zvq2cw8s3-stderr-runner /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test, in 1.93 seconds)935machine: must succeed: rm /etc/NIXOS936machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds)937machine: must succeed: /nix/store/kabiwhgyb1j3wv90hzpkic8zvq2cw8s3-stderr-runner /nix/store/miybgfqh6c31k35kpb8izvqp13m4634j-nixos-system-other-test/bin/switch-to-configuration test938machine # this should succeed (config: /nix/store/miybgfqh6c31k35kpb8izvqp13m4634j-nixos-system-other-test, action: test)939machine # Checking switch inhibitors... done940machine # [ 19.635733] nixos[969]: switching to system configuration /nix/store/miybgfqh6c31k35kpb8izvqp13m4634j-nixos-system-other-test941machine # [ 19.638704] systemd[1]: Stopped target Remote File Systems.942machine # [ 19.640423] systemd[1]: Stopped target Local File Systems.943machine # activating the configuration...944machine # removing user ‘usertest’945machine # setting up /etc...946machine # [ 19.917101] systemd[1]: Reload requested from client PID 969 ('.switch-to-conf') (unit backdoor.service)...947machine # [ 19.919414] systemd[1]: Reloading...948machine # [ 20.230298] systemd[1]: Reloading finished in 309 ms.949machine # reloading user units for usertest...950machine # [ 20.321631] systemd[589]: Reexecution requested from client PID 1040 ('switch-to-confi')...951machine # [ 20.326318] systemd[589]: Reexecuting.952machine # [ 20.384140] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy953machine # [ 20.543694] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy954machine # [ 20.550332] systemd[589]: Reload requested from client PID 1040 ('switch-to-confi')...955machine # [ 20.554107] systemd[589]: Reloading...956machine # [ 20.658884] systemd[589]: Reloading finished in 105 ms.957machine # [ 20.676215] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy958machine # restarting the following user units: nixos-activation.service959machine # [ 20.686506] systemd[589]: Stopped Run user-specific NixOS activation.960machine # [ 20.689095] systemd[589]: Stopping Run user-specific NixOS activation...961machine # [ 20.691130] systemd[589]: Starting Run user-specific NixOS activation...962machine # [ 20.723544] systemd[589]: Finished Run user-specific NixOS activation.963machine # restarting sysinit-reactivation.target964machine # [ 20.727801] systemd[1]: Stopped target Reactivate sysinit units.965machine # [ 20.729202] systemd[1]: Stopping Reactivate sysinit units...966machine # [ 20.730041] systemd[1]: Reached target Reactivate sysinit units.967machine # reloading the following units: dbus-broker.service968machine # [ 20.736482] dbus-broker-launch[439]: Caught SIGHUP, trigger reload.969machine # [ 20.737495] systemd[1]: Reloading D-Bus System Message Bus...970machine # [ 20.742955] dbus-broker-launch[439]: Looking up NSS user entry for 'systemd-timesync'...971machine # [ 20.745135] dbus-broker-launch[439]: NSS returned no entry for 'systemd-timesync'972machine # [ 20.746289] dbus-broker-launch[439]: Invalid user-name in /nix/store/3a5x2ri1v5a895rksnf2x58dawh39zhm-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"973machine # [ 20.760854] systemd[1]: Reloaded D-Bus System Message Bus.974machine # restarting the following units: network-addresses-eth1.service975machine # [ 20.767471] systemd[1]: Stopping Address configuration of eth1...976machine # [ 20.798074] network-addresses-eth1-pre-stop[1053]: deleting address 192.168.1.1/24... done977machine # [ 20.804324] network-addresses-eth1-pre-stop[1053]: deleting address 2001:db8:1::1/64... done978machine # [ 20.810946] systemd[1]: network-addresses-eth1.service: Deactivated successfully.979machine # [ 20.813200] systemd[1]: Stopped Address configuration of eth1.980machine # [ 20.817369] systemd[1]: Starting Address configuration of eth1...981machine # [ 20.856598] network-addresses-eth1-start[1058]: adding address 192.168.1.2/24... done982machine # [ 20.863147] network-addresses-eth1-start[1058]: adding address 2001:db8:1::2/64... done983machine # [ 20.872850] systemd[1]: Finished Address configuration of eth1.984machine # [ 20.879400] systemd[1]: Reached target Remote File Systems.985machine # [ 20.883737] systemd[1]: Starting linger-users.service...986machine # [ 20.892890] systemd[1]: Reached target Local File Systems.987machine # [ 20.893917] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore988machine # [ 20.899636] systemd[1]: Starting Create SUID/SGID Wrappers...989machine # [ 20.906812] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.990machine # [ 20.915310] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67991machine # [ 20.923849] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore992machine # [ 20.927635] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os993machine # [ 20.929428] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os994machine # [ 20.930693] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os995machine # [ 20.937293] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki996machine # [ 20.952969] linger-users-start[1072]: id: ‘usertest’: no such user997machine # [ 20.956429] linger-users-start[1069]: Removing linger for missing user usertest998machine # [ 20.967741] systemd[1]: linger-users.service: Deactivated successfully.999machine # [ 20.971323] systemd[1]: Finished linger-users.service.1000machine # [ 21.131780] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1001machine # [ 21.135410] systemd[1]: Finished Create SUID/SGID Wrappers.1002machine # [ 21.389503] nixos[969]: finished switching to system configuration /nix/store/miybgfqh6c31k35kpb8izvqp13m4634j-nixos-system-other-test1003machine: (finished: must succeed: /nix/store/kabiwhgyb1j3wv90hzpkic8zvq2cw8s3-stderr-runner /nix/store/miybgfqh6c31k35kpb8izvqp13m4634j-nixos-system-other-test/bin/switch-to-configuration test, in 1.98 seconds)1004machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&11005machine # [ 21.609796] nixos[1136]: switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test1006machine # [ 21.613972] systemd[1]: Stopped target Local File Systems.1007machine # [ 21.615711] systemd[1]: Stopped target Remote File Systems.1008machine # [ 21.884348] systemd[1]: Reload requested from client PID 1136 ('.switch-to-conf') (unit backdoor.service)...1009machine # [ 21.886382] systemd[1]: Reloading...1010machine # [ 22.192038] systemd[1]: Reloading finished in 303 ms.1011machine # [ 22.276225] systemd[589]: Reexecution requested from client PID 1205 ('switch-to-confi')...1012machine # [ 22.277652] systemd[589]: Reexecuting.1013machine # [ 22.339565] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1014machine # [ 22.450312] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1015machine # [ 22.456876] systemd[589]: Reload requested from client PID 1205 ('switch-to-confi')...1016machine # [ 22.460064] systemd[589]: Reloading...1017machine # [ 22.563871] systemd[589]: Reloading finished in 104 ms.1018machine # [ 22.580165] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1019machine # [ 22.590174] systemd[589]: Stopped Run user-specific NixOS activation.1020machine # [ 22.591343] systemd[589]: Stopping Run user-specific NixOS activation...1021machine # [ 22.594327] systemd[589]: Starting Run user-specific NixOS activation...1022machine # [ 22.622812] systemd[589]: Finished Run user-specific NixOS activation.1023machine # [ 22.626736] systemd[1]: Stopped target Reactivate sysinit units.1024machine # [ 22.628135] systemd[1]: Stopping Reactivate sysinit units...1025machine # [ 22.628966] systemd[1]: Reached target Reactivate sysinit units.1026machine # [ 22.632607] dbus-broker-launch[439]: Caught SIGHUP, trigger reload.1027machine # [ 22.633565] systemd[1]: Reloading D-Bus System Message Bus...1028machine # [ 22.638649] dbus-broker-launch[439]: Looking up NSS user entry for 'systemd-timesync'...1029machine # [ 22.640773] dbus-broker-launch[439]: NSS returned no entry for 'systemd-timesync'1030machine # [ 22.641844] dbus-broker-launch[439]: Invalid user-name in /nix/store/hi2awyip155pp6lbgmqp52dshc41z2n6-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1031machine # [ 22.668074] systemd[1]: Reloaded D-Bus System Message Bus.1032machine # [ 22.673123] systemd[1]: Stopping Address configuration of eth1...1033machine # [ 22.701398] network-addresses-eth1-pre-stop[1217]: deleting address 192.168.1.2/24... done1034machine # [ 22.707214] network-addresses-eth1-pre-stop[1217]: deleting address 2001:db8:1::2/64... done1035machine # [ 22.713451] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1036machine # [ 22.717714] systemd[1]: Stopped Address configuration of eth1.1037machine # [ 22.719935] systemd[1]: Starting Address configuration of eth1...1038machine # [ 22.761457] network-addresses-eth1-start[1222]: adding address 192.168.1.1/24... done1039machine # [ 22.767899] network-addresses-eth1-start[1222]: adding address 2001:db8:1::1/64... done1040machine # [ 22.777658] systemd[1]: Finished Address configuration of eth1.1041machine # [ 22.783644] systemd[1]: Reached target Local File Systems.1042machine # [ 22.788479] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1043machine # [ 22.797514] systemd[1]: Starting Create SUID/SGID Wrappers...1044machine # [ 22.798473] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1045machine # [ 22.799846] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671046machine # [ 22.807837] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1047machine # [ 22.814359] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1048machine # [ 22.815859] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1049machine # [ 22.819476] systemd[1]: Reached target Remote File Systems.1050machine # [ 22.822966] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1051machine # [ 22.824999] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1052machine # [ 22.973743] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1053machine # [ 22.976308] systemd[1]: Finished Create SUID/SGID Wrappers.1054machine # [ 22.984423] systemd[1]: Starting linger-users.service...1055machine # [ 23.015020] systemd[1]: linger-users.service: Deactivated successfully.1056machine # [ 23.017016] systemd[1]: Finished linger-users.service.1057machine # [ 23.273957] nixos[1136]: finished switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test1058machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.88 seconds)1059subtest: user services1060machine: waiting for unit user@1001.service1061machine: (finished: waiting for unit user@1001.service, in 0.06 seconds)1062machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11063machine # [ 23.535218] nixos[1295]: switching to system configuration /nix/store/snc3fpqb680pgnj3x1n44xyadpri0x4j-nixos-system-machine-test1064machine # [ 23.538169] systemd[1]: Stopped target Local File Systems.1065machine # [ 23.540561] systemd[1]: Stopped target Remote File Systems.1066machine # [ 23.863489] systemd[1]: Reload requested from client PID 1295 ('.switch-to-conf') (unit backdoor.service)...1067machine # [ 23.865676] systemd[1]: Reloading...1068machine # [ 24.169491] systemd[1]: Reloading finished in 301 ms.1069machine # [ 24.256508] systemd[589]: Reexecution requested from client PID 1365 ('switch-to-confi')...1070machine # [ 24.259876] systemd[589]: Reexecuting.1071machine # [ 24.311250] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1072machine # [ 24.422822] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1073machine # [ 24.429471] systemd[589]: Reload requested from client PID 1365 ('switch-to-confi')...1074machine # [ 24.432950] systemd[589]: Reloading...1075machine # [ 24.536049] systemd[589]: Reloading finished in 103 ms.1076machine # [ 24.552167] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1077machine # [ 24.563767] systemd[589]: Starting usertest.service...1078machine # [ 24.588867] systemd[589]: Finished usertest.service.1079machine # [ 24.592111] systemd[589]: Stopped Run user-specific NixOS activation.1080machine # [ 24.593164] systemd[589]: Stopping Run user-specific NixOS activation...1081machine # [ 24.595905] systemd[589]: Starting Run user-specific NixOS activation...1082machine # [ 24.627208] systemd[589]: Finished Run user-specific NixOS activation.1083machine # [ 24.631562] systemd[1]: Stopped target Reactivate sysinit units.1084machine # [ 24.634468] systemd[1]: Stopping Reactivate sysinit units...1085machine # [ 24.635354] systemd[1]: Reached target Reactivate sysinit units.1086machine # [ 24.640896] systemd[1]: Reached target Remote File Systems.1087machine # [ 24.641779] systemd[1]: Starting linger-users.service...1088machine # [ 24.649867] systemd[1]: Reached target Local File Systems.1089machine # [ 24.653032] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1090machine # [ 24.654383] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1091machine # [ 24.657869] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1092machine # [ 24.669463] systemd[1]: Starting Create SUID/SGID Wrappers...1093machine # [ 24.670393] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1094machine # [ 24.671652] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671095machine # [ 24.686059] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1096machine # [ 24.690051] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1097machine # [ 24.691474] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1098machine # [ 24.735490] systemd[1]: linger-users.service: Deactivated successfully.1099machine # [ 24.738391] systemd[1]: Finished linger-users.service.1100machine # [ 24.875338] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1101machine # [ 24.878618] systemd[1]: Finished Create SUID/SGID Wrappers.1102machine # [ 25.132585] nixos[1295]: finished switching to system configuration /nix/store/snc3fpqb680pgnj3x1n44xyadpri0x4j-nixos-system-machine-test1103machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.79 seconds)1104machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1105machine # [ 25.188838] sudo[1435]: 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.service1106machine # [ 25.206811] sudo[1435]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1107machine # [ 25.222879] sudo[1435]: pam_unix(sudo:session): session closed for user usertest1108machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.09 seconds)1109machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11110machine # [ 25.417142] nixos[1444]: switching to system configuration /nix/store/snc3fpqb680pgnj3x1n44xyadpri0x4j-nixos-system-machine-test1111machine # [ 25.419940] systemd[1]: Stopped target Local File Systems.1112machine # [ 25.423455] systemd[1]: Stopped target Remote File Systems.1113machine # [ 25.732652] systemd[1]: Reload requested from client PID 1444 ('.switch-to-conf') (unit backdoor.service)...1114machine # [ 25.734339] systemd[1]: Reloading...1115machine # [ 26.073877] systemd[1]: Reloading finished in 336 ms.1116machine # [ 26.164621] systemd[589]: Reexecution requested from client PID 1513 ('switch-to-confi')...1117machine # [ 26.167203] systemd[589]: Reexecuting.1118machine # [ 26.223750] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1119machine # [ 26.337625] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1120machine # [ 26.344526] systemd[589]: Reload requested from client PID 1513 ('switch-to-confi')...1121machine # [ 26.347190] systemd[589]: Reloading...1122machine # [ 26.457698] systemd[589]: Reloading finished in 109 ms.1123machine # [ 26.472227] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1124machine # [ 26.482147] systemd[589]: Stopped Run user-specific NixOS activation.1125machine # [ 26.484633] systemd[589]: Stopping Run user-specific NixOS activation...1126machine # [ 26.486747] systemd[589]: Starting Run user-specific NixOS activation...1127machine # [ 26.520440] systemd[589]: Finished Run user-specific NixOS activation.1128machine # [ 26.524609] systemd[1]: Stopped target Reactivate sysinit units.1129machine # [ 26.526801] systemd[1]: Stopping Reactivate sysinit units...1130machine # [ 26.527782] systemd[1]: Reached target Reactivate sysinit units.1131machine # [ 26.530745] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1132machine # [ 26.533973] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1133machine # [ 26.535522] systemd[1]: Reached target Remote File Systems.1134machine # [ 26.543840] systemd[1]: Starting linger-users.service...1135machine # [ 26.554858] systemd[1]: Reached target Local File Systems.1136machine # [ 26.558545] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1137machine # [ 26.567983] systemd[1]: Starting Create SUID/SGID Wrappers...1138machine # [ 26.571307] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1139machine # [ 26.580883] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671140machine # [ 26.583363] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1141machine # [ 26.591540] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1142machine # [ 26.596422] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1143machine # [ 26.643357] systemd[1]: linger-users.service: Deactivated successfully.1144machine # [ 26.646226] systemd[1]: Finished linger-users.service.1145machine # [ 26.792155] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1146machine # [ 26.795750] systemd[1]: Finished Create SUID/SGID Wrappers.1147machine # [ 27.049981] nixos[1444]: finished switching to system configuration /nix/store/snc3fpqb680pgnj3x1n44xyadpri0x4j-nixos-system-machine-test1148machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.83 seconds)1149machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&11150machine # [ 27.309896] nixos[1584]: switching to system configuration /nix/store/mfm9gz9kjr65c9871d33gmqjvlv8s2jz-nixos-system-machine-test1151machine # [ 27.312823] systemd[1]: Stopped target Local File Systems.1152machine # [ 27.315637] systemd[1]: Stopped target Remote File Systems.1153machine # [ 27.631391] systemd[1]: Reload requested from client PID 1584 ('.switch-to-conf') (unit backdoor.service)...1154machine # [ 27.633818] systemd[1]: Reloading...1155machine # [ 27.949634] systemd[1]: Reloading finished in 314 ms.1156machine # [ 28.043823] systemd[589]: Stopped usertest.service.1157machine # [ 28.047263] systemd[589]: Reexecution requested from client PID 1654 ('switch-to-confi')...1158machine # [ 28.049288] systemd[589]: Reexecuting.1159machine # [ 28.107699] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1160machine # [ 28.221654] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1161machine # [ 28.228619] systemd[589]: Reload requested from client PID 1654 ('switch-to-confi')...1162machine # [ 28.232135] systemd[589]: Reloading...1163machine # [ 28.341576] systemd[589]: Reloading finished in 110 ms.1164machine # [ 28.356212] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1165machine # [ 28.367478] systemd[589]: Starting usertest.service...1166machine # [ 28.393370] systemd[589]: Finished usertest.service.1167machine # [ 28.397303] systemd[589]: Stopped Run user-specific NixOS activation.1168machine # [ 28.398325] systemd[589]: Stopping Run user-specific NixOS activation...1169machine # [ 28.400499] systemd[589]: Starting Run user-specific NixOS activation...1170machine # [ 28.430714] systemd[589]: Finished Run user-specific NixOS activation.1171machine # [ 28.434805] systemd[1]: Stopped target Reactivate sysinit units.1172machine # [ 28.438716] systemd[1]: Stopping Reactivate sysinit units...1173machine # [ 28.439630] systemd[1]: Reached target Reactivate sysinit units.1174machine # [ 28.443567] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1175machine # [ 28.448574] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1176machine # [ 28.449941] systemd[1]: Reached target Remote File Systems.1177machine # [ 28.450808] systemd[1]: Starting linger-users.service...1178machine # [ 28.463681] systemd[1]: Reached target Local File Systems.1179machine # [ 28.468092] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1180machine # [ 28.469672] systemd[1]: Starting Create SUID/SGID Wrappers...1181machine # [ 28.470485] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1182machine # [ 28.471838] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671183machine # [ 28.480787] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1184machine # [ 28.482538] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1185machine # [ 28.483819] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1186machine # [ 28.543089] systemd[1]: linger-users.service: Deactivated successfully.1187machine # [ 28.545888] systemd[1]: Finished linger-users.service.1188machine # [ 28.672155] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1189machine # [ 28.675240] systemd[1]: Finished Create SUID/SGID Wrappers.1190machine # [ 28.930126] nixos[1584]: finished switching to system configuration /nix/store/mfm9gz9kjr65c9871d33gmqjvlv8s2jz-nixos-system-machine-test1191machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 1.88 seconds)1192machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1193machine # [ 28.977163] sudo[1724]: 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.service1194machine # [ 28.993174] sudo[1724]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1195machine # [ 29.008148] sudo[1724]: pam_unix(sudo:session): session closed for user usertest1196machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1197machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&11198machine # [ 29.192584] nixos[1733]: switching to system configuration /nix/store/1ivf068wnh79mzk3s3xmx2vq8awhnkl5-nixos-system-machine-test1199machine # [ 29.195517] systemd[1]: Stopped target Remote File Systems.1200machine # [ 29.198025] systemd[1]: Stopped target Local File Systems.1201machine # [ 29.496804] systemd[1]: Reload requested from client PID 1733 ('.switch-to-conf') (unit backdoor.service)...1202machine # [ 29.498831] systemd[1]: Reloading...1203machine # [ 29.813382] systemd[1]: Reloading finished in 313 ms.1204machine # [ 29.906279] systemd[589]: Reexecution requested from client PID 1802 ('switch-to-confi')...1205machine # [ 29.907832] systemd[589]: Reexecuting.1206machine # [ 29.971945] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1207machine # [ 30.087819] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1208machine # [ 30.094707] systemd[589]: Reload requested from client PID 1802 ('switch-to-confi')...1209machine # [ 30.097249] systemd[589]: Reloading...1210machine # [ 30.204651] systemd[589]: Reloading finished in 106 ms.1211machine # [ 30.220221] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1212machine # [ 30.226963] systemd[589]: Stopped usertest.service.1213machine # [ 30.229116] systemd[589]: Stopping usertest.service...1214machine # [ 30.231177] systemd[589]: Starting usertest.service...1215machine # [ 30.257490] systemd[589]: Finished usertest.service.1216machine # [ 30.262877] systemd[589]: Stopped Run user-specific NixOS activation.1217machine # [ 30.265304] systemd[589]: Stopping Run user-specific NixOS activation...1218machine # [ 30.267419] systemd[589]: Starting Run user-specific NixOS activation...1219machine # [ 30.300115] systemd[589]: Finished Run user-specific NixOS activation.1220machine # [ 30.304492] systemd[1]: Stopped target Reactivate sysinit units.1221machine # [ 30.310450] systemd[1]: Stopping Reactivate sysinit units...1222machine # [ 30.311516] systemd[1]: Reached target Reactivate sysinit units.1223machine # [ 30.312531] systemd[1]: Reached target Local File Systems.1224machine # [ 30.313355] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1225machine # [ 30.316500] systemd[1]: Starting Create SUID/SGID Wrappers...1226machine # [ 30.317425] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1227machine # [ 30.318819] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671228machine # [ 30.330722] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1229machine # [ 30.340195] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1230machine # [ 30.341645] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1231machine # [ 30.343153] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1232machine # [ 30.354235] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1233machine # [ 30.355654] systemd[1]: Reached target Remote File Systems.1234machine # [ 30.556708] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1235machine # [ 30.558703] systemd[1]: Finished Create SUID/SGID Wrappers.1236machine # [ 30.563661] systemd[1]: Starting linger-users.service...1237machine # [ 30.605956] systemd[1]: linger-users.service: Deactivated successfully.1238machine # [ 30.609412] systemd[1]: Finished linger-users.service.1239machine # [ 30.863538] nixos[1733]: finished switching to system configuration /nix/store/1ivf068wnh79mzk3s3xmx2vq8awhnkl5-nixos-system-machine-test1240machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 1.86 seconds)1241machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1242machine # [ 30.908622] sudo[1873]: 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.service1243machine # [ 30.924872] sudo[1873]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1244machine # [ 30.940736] sudo[1873]: pam_unix(sudo:session): session closed for user usertest1245machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1246machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&11247machine # [ 31.145387] nixos[1882]: switching to system configuration /nix/store/p49cxbrbppynswby2q9gkj45c5rm8d7p-nixos-system-machine-test1248machine # [ 31.149703] systemd[1]: Stopped target Remote File Systems.1249machine # [ 31.152171] systemd[1]: Stopped target Local File Systems.1250machine # [ 31.472878] systemd[1]: Reload requested from client PID 1882 ('.switch-to-conf') (unit backdoor.service)...1251machine # [ 31.474991] systemd[1]: Reloading...1252machine # [ 31.794204] systemd[1]: Reloading finished in 317 ms.1253machine # [ 31.898642] systemd[589]: Reexecution requested from client PID 1951 ('switch-to-confi')...1254machine # [ 31.901362] systemd[589]: Reexecuting.1255machine # [ 31.960666] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1256machine # [ 32.087395] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1257machine # [ 32.094377] systemd[589]: Reload requested from client PID 1951 ('switch-to-confi')...1258machine # [ 32.098599] systemd[589]: Reloading...1259machine # [ 32.206337] systemd[589]: Reloading finished in 108 ms.1260machine # [ 32.224213] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1261machine # [ 32.233099] systemd[589]: Reloading usertest.service...1262machine # [ 32.258287] systemd[589]: Reloaded usertest.service.1263machine # [ 32.264144] systemd[589]: Stopped Run user-specific NixOS activation.1264machine # [ 32.266605] systemd[589]: Stopping Run user-specific NixOS activation...1265machine # [ 32.268939] systemd[589]: Starting Run user-specific NixOS activation...1266machine # [ 32.300672] systemd[589]: Finished Run user-specific NixOS activation.1267machine # [ 32.305035] systemd[1]: Stopped target Reactivate sysinit units.1268machine # [ 32.308378] systemd[1]: Stopping Reactivate sysinit units...1269machine # [ 32.309359] systemd[1]: Reached target Reactivate sysinit units.1270machine # [ 32.313281] systemd[1]: Reached target Local File Systems.1271machine # [ 32.317033] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1272machine # [ 32.321764] systemd[1]: Starting Create SUID/SGID Wrappers...1273machine # [ 32.322714] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1274machine # [ 32.328552] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671275machine # [ 32.330994] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1276machine # [ 32.343808] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1277machine # [ 32.345359] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1278machine # [ 32.346618] systemd[1]: Reached target Remote File Systems.1279machine # [ 32.347468] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1280machine # [ 32.349308] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1281machine # [ 32.516135] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1282machine # [ 32.520674] systemd[1]: Finished Create SUID/SGID Wrappers.1283machine # [ 32.523458] systemd[1]: Starting linger-users.service...1284machine # [ 32.566119] systemd[1]: linger-users.service: Deactivated successfully.1285machine # [ 32.568807] systemd[1]: Finished linger-users.service.1286machine # [ 32.824140] nixos[1882]: finished switching to system configuration /nix/store/p49cxbrbppynswby2q9gkj45c5rm8d7p-nixos-system-machine-test1287machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 1.89 seconds)1288machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1289machine # [ 32.866878] sudo[2021]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1290machine # [ 32.882368] sudo[2021]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1291machine # [ 32.896670] sudo[2021]: pam_unix(sudo:session): session closed for user usertest1292machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1293machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11294machine # [ 33.085471] nixos[2030]: switching to system configuration /nix/store/snc3fpqb680pgnj3x1n44xyadpri0x4j-nixos-system-machine-test1295machine # [ 33.088065] systemd[1]: Stopped target Remote File Systems.1296machine # [ 33.090396] systemd[1]: Stopped target Local File Systems.1297machine # [ 33.361694] systemd[1]: Reload requested from client PID 2030 ('.switch-to-conf') (unit backdoor.service)...1298machine # [ 33.363749] systemd[1]: Reloading...1299machine # [ 33.702204] systemd[1]: Reloading finished in 335 ms.1300machine # [ 33.853780] systemd[589]: Stopped usertest.service.1301machine # [ 33.858069] systemd[589]: Reexecution requested from client PID 2099 ('switch-to-confi')...1302machine # [ 33.859509] systemd[589]: Reexecuting.1303machine # [ 33.920123] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1304machine # [ 34.046263] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1305machine # [ 34.054417] systemd[589]: Reload requested from client PID 2099 ('switch-to-confi')...1306machine # [ 34.058814] systemd[589]: Reloading...1307machine # [ 34.176492] systemd[589]: Reloading finished in 118 ms.1308machine # [ 34.192210] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1309machine # [ 34.203475] systemd[589]: Starting usertest.service...1310machine # [ 34.229836] systemd[589]: Finished usertest.service.1311machine # [ 34.232684] systemd[589]: Stopped Run user-specific NixOS activation.1312machine # [ 34.235053] systemd[589]: Stopping Run user-specific NixOS activation...1313machine # [ 34.237374] systemd[589]: Starting Run user-specific NixOS activation...1314machine # [ 34.268110] systemd[589]: Finished Run user-specific NixOS activation.1315machine # [ 34.272967] systemd[1]: Stopped target Reactivate sysinit units.1316machine # [ 34.276366] systemd[1]: Stopping Reactivate sysinit units...1317machine # [ 34.277290] systemd[1]: Reached target Reactivate sysinit units.1318machine # [ 34.280527] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1319machine # [ 34.283883] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1320machine # [ 34.288551] systemd[1]: Reached target Local File Systems.1321machine # [ 34.289429] systemd[1]: Reached target Remote File Systems.1322machine # [ 34.292952] systemd[1]: Starting linger-users.service...1323machine # [ 34.305627] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1324machine # [ 34.313816] systemd[1]: Starting Create SUID/SGID Wrappers...1325machine # [ 34.314764] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1326machine # [ 34.320629] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671327machine # [ 34.323004] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1328machine # [ 34.332303] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1329machine # [ 34.333688] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1330machine # [ 34.380901] systemd[1]: linger-users.service: Deactivated successfully.1331machine # [ 34.384952] systemd[1]: Finished linger-users.service.1332machine # [ 34.535336] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1333machine # [ 34.538972] systemd[1]: Finished Create SUID/SGID Wrappers.1334machine # [ 34.795248] nixos[2030]: finished switching to system configuration /nix/store/snc3fpqb680pgnj3x1n44xyadpri0x4j-nixos-system-machine-test1335machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.90 seconds)1336machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1337machine # [ 34.847015] sudo[2170]: 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.service1338machine # [ 34.863549] sudo[2170]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1339machine # [ 34.878389] sudo[2170]: pam_unix(sudo:session): session closed for user usertest1340machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1341machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&11342machine # [ 35.085566] nixos[2179]: switching to system configuration /nix/store/b2y7k3bk97hcnnr7m2iryacna305a5gq-nixos-system-machine-test1343machine # [ 35.088647] systemd[1]: Stopped target Local File Systems.1344machine # [ 35.091353] systemd[1]: Stopped target Remote File Systems.1345machine # [ 35.453844] systemd[1]: Reload requested from client PID 2179 ('.switch-to-conf') (unit backdoor.service)...1346machine # [ 35.455988] systemd[1]: Reloading...1347machine # [ 35.795027] systemd[1]: Reloading finished in 337 ms.1348machine # [ 35.895801] systemd[589]: Reexecution requested from client PID 2248 ('switch-to-confi')...1349machine # [ 35.900238] systemd[589]: Reexecuting.1350machine # [ 35.951186] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1351machine # [ 36.074852] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1352machine # [ 36.082031] systemd[589]: Reload requested from client PID 2248 ('switch-to-confi')...1353machine # [ 36.084511] systemd[589]: Reloading...1354machine # [ 36.199562] systemd[589]: Reloading finished in 113 ms.1355machine # [ 36.216312] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1356machine # [ 36.225381] systemd[589]: Reloading usertest.service...1357machine # [ 36.250262] systemd[589]: Reloaded usertest.service.1358machine # [ 36.258294] systemd[589]: Stopped Run user-specific NixOS activation.1359machine # [ 36.259484] systemd[589]: Stopping Run user-specific NixOS activation...1360machine # [ 36.262541] systemd[589]: Starting Run user-specific NixOS activation...1361machine # [ 36.297257] systemd[589]: Finished Run user-specific NixOS activation.1362machine # [ 36.301783] systemd[1]: Stopped target Reactivate sysinit units.1363machine # [ 36.303895] systemd[1]: Stopping Reactivate sysinit units...1364machine # [ 36.307020] systemd[1]: Reached target Reactivate sysinit units.1365machine # [ 36.311098] systemd[1]: Reached target Remote File Systems.1366machine # [ 36.313840] systemd[1]: Starting linger-users.service...1367machine # [ 36.326725] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1368machine # [ 36.328654] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1369machine # [ 36.335720] systemd[1]: Reached target Local File Systems.1370machine # [ 36.344305] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1371machine # [ 36.345969] systemd[1]: Starting Create SUID/SGID Wrappers...1372machine # [ 36.347008] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1373machine # [ 36.357381] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671374machine # [ 36.359825] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1375machine # [ 36.364992] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1376machine # [ 36.366991] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1377machine # [ 36.412093] systemd[1]: linger-users.service: Deactivated successfully.1378machine # [ 36.417347] systemd[1]: Finished linger-users.service.1379machine # [ 36.559316] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1380machine # [ 36.563357] systemd[1]: Finished Create SUID/SGID Wrappers.1381machine # [ 36.819299] nixos[2179]: finished switching to system configuration /nix/store/b2y7k3bk97hcnnr7m2iryacna305a5gq-nixos-system-machine-test1382machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 1.94 seconds)1383machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1384machine # [ 36.870213] sudo[2318]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1385machine # [ 36.889946] sudo[2318]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1386machine # [ 36.905019] sudo[2318]: pam_unix(sudo:session): session closed for user usertest1387machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1388machine: must fail: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&11389machine # [ 37.155382] nixos[2327]: switching to system configuration /nix/store/xy81r0j227v6zd9j00gvc5cikbjdhcpl-nixos-system-machine-test1390machine # [ 37.162706] systemd[1]: Stopped target Local File Systems.1391machine # [ 37.163591] systemd[1]: Stopped target Remote File Systems.1392machine # [ 37.488250] systemd[1]: Reload requested from client PID 2327 ('.switch-to-conf') (unit backdoor.service)...1393machine # [ 37.490376] systemd[1]: Reloading...1394machine # [ 37.823719] systemd[1]: Reloading finished in 331 ms.1395machine # [ 37.924717] systemd[589]: Stopped usertest.service.1396machine # [ 37.928331] systemd[589]: Reexecution requested from client PID 2397 ('switch-to-confi')...1397machine # [ 37.931081] systemd[589]: Reexecuting.1398machine # [ 37.992375] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1399machine # [ 38.119670] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1400machine # [ 38.127563] systemd[589]: Reload requested from client PID 2397 ('switch-to-confi')...1401machine # [ 38.131857] systemd[589]: Reloading...1402machine # [ 38.250478] systemd[589]: Reloading finished in 119 ms.1403machine # [ 38.268202] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1404machine # [ 38.280249] systemd[589]: Starting usertest.service...1405machine # [ 38.307113] systemd[589]: usertest.service: Main process exited, code=exited, status=1/FAILURE1406machine # [ 38.311861] systemd[589]: usertest.service: Failed with result 'exit-code'.1407machine # [ 38.316347] systemd[589]: Failed to start usertest.service.1408machine # [ 38.317391] systemd[589]: Stopped Run user-specific NixOS activation.1409machine # [ 38.318320] systemd[589]: Stopping Run user-specific NixOS activation...1410machine # [ 38.322202] systemd[589]: Starting Run user-specific NixOS activation...1411machine # [ 38.357312] systemd[589]: Finished Run user-specific NixOS activation.1412machine # [ 38.362129] systemd[1]: Stopped target Reactivate sysinit units.1413machine # [ 38.365910] systemd[1]: Stopping Reactivate sysinit units...1414machine # [ 38.367028] systemd[1]: Reached target Reactivate sysinit units.1415machine # [ 38.367974] systemd[1]: Reached target Local File Systems.1416machine # [ 38.372419] systemd[1]: Reached target Remote File Systems.1417machine # [ 38.373344] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1418machine # [ 38.374793] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1419machine # [ 38.383060] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1420machine # [ 38.393109] systemd[1]: Starting Create SUID/SGID Wrappers...1421machine # [ 38.394066] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1422machine # [ 38.395405] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671423machine # [ 38.407439] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1424machine # [ 38.409518] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1425machine # [ 38.410867] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1426machine # [ 38.579536] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1427machine # [ 38.583996] systemd[1]: Finished Create SUID/SGID Wrappers.1428machine # [ 38.586162] systemd[1]: Starting linger-users.service...1429machine # [ 38.629475] systemd[1]: linger-users.service: Deactivated successfully.1430machine # [ 38.633089] systemd[1]: Finished linger-users.service.1431machine # [ 38.887750] nixos[2327]: switching to system configuration /nix/store/xy81r0j227v6zd9j00gvc5cikbjdhcpl-nixos-system-machine-test failed (status 4)1432machine: (finished: must fail: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 1.99 seconds)1433machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11434machine # [ 39.116955] nixos[2470]: switching to system configuration /nix/store/snc3fpqb680pgnj3x1n44xyadpri0x4j-nixos-system-machine-test1435machine # [ 39.119660] systemd[1]: Stopped target Remote File Systems.1436machine # [ 39.123137] systemd[1]: Stopped target Local File Systems.1437machine # [ 39.406729] systemd[1]: Reload requested from client PID 2470 ('.switch-to-conf') (unit backdoor.service)...1438machine # [ 39.409129] systemd[1]: Reloading...1439machine # [ 39.735117] systemd[1]: Reloading finished in 324 ms.1440machine # [ 39.828453] systemd[589]: Reexecution requested from client PID 2539 ('switch-to-confi')...1441machine # [ 39.831482] systemd[589]: Reexecuting.1442machine # [ 39.887780] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1443machine # [ 40.004528] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1444machine # [ 40.012102] systemd[589]: Reload requested from client PID 2539 ('switch-to-confi')...1445machine # [ 40.013443] systemd[589]: Reloading...1446machine # [ 40.128975] systemd[589]: Reloading finished in 113 ms.1447machine # [ 40.144187] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1448machine # [ 40.155326] systemd[589]: Starting usertest.service...1449machine # [ 40.181615] systemd[589]: Finished usertest.service.1450machine # [ 40.185685] systemd[589]: Stopped Run user-specific NixOS activation.1451machine # [ 40.186771] systemd[589]: Stopping Run user-specific NixOS activation...1452machine # [ 40.188787] systemd[589]: Starting Run user-specific NixOS activation...1453machine # [ 40.220637] systemd[589]: Finished Run user-specific NixOS activation.1454machine # [ 40.224908] systemd[1]: Stopped target Reactivate sysinit units.1455machine # [ 40.227847] systemd[1]: Stopping Reactivate sysinit units...1456machine # [ 40.230242] systemd[1]: Reached target Reactivate sysinit units.1457machine # [ 40.231266] systemd[1]: Reached target Local File Systems.1458machine # [ 40.235797] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1459machine # [ 40.239540] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1460machine # [ 40.243642] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1461machine # [ 40.252193] systemd[1]: Starting Create SUID/SGID Wrappers...1462machine # [ 40.253137] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1463machine # [ 40.254492] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671464machine # [ 40.265807] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1465machine # [ 40.267595] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1466machine # [ 40.269229] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1467machine # [ 40.270614] systemd[1]: Reached target Remote File Systems.1468machine # [ 40.468925] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1469machine # [ 40.472686] systemd[1]: Finished Create SUID/SGID Wrappers.1470machine # [ 40.477305] systemd[1]: Starting linger-users.service...1471machine # [ 40.546203] systemd[1]: linger-users.service: Deactivated successfully.1472machine # [ 40.549604] systemd[1]: Finished linger-users.service.1473machine # [ 40.804234] nixos[2470]: finished switching to system configuration /nix/store/snc3fpqb680pgnj3x1n44xyadpri0x4j-nixos-system-machine-test1474machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.91 seconds)1475machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1476machine # [ 40.852400] sudo[2610]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1477machine # [ 40.869211] sudo[2610]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1478machine # [ 40.884117] sudo[2610]: pam_unix(sudo:session): session closed for user usertest1479machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1480machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&11481machine # [ 41.084764] nixos[2619]: switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test1482machine # [ 41.087698] systemd[1]: Stopped target Local File Systems.1483machine # [ 41.090203] systemd[1]: Stopped target Remote File Systems.1484machine # [ 41.391055] systemd[1]: Reload requested from client PID 2619 ('.switch-to-conf') (unit backdoor.service)...1485machine # [ 41.393380] systemd[1]: Reloading...1486machine # [ 41.746616] systemd[1]: Reloading finished in 351 ms.1487machine # [ 41.851498] systemd[589]: Stopped usertest.service.1488machine # [ 41.855953] systemd[589]: Reexecution requested from client PID 2687 ('switch-to-confi')...1489machine # [ 41.858192] systemd[589]: Reexecuting.1490machine # [ 41.924123] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1491machine # [ 42.053383] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1492machine # [ 42.060937] systemd[589]: Reload requested from client PID 2687 ('switch-to-confi')...1493machine # [ 42.063559] systemd[589]: Reloading...1494machine # [ 42.186133] systemd[589]: Reloading finished in 121 ms.1495machine # [ 42.204228] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1496machine # [ 42.217265] systemd[589]: Stopped Run user-specific NixOS activation.1497machine # [ 42.220132] systemd[589]: Stopping Run user-specific NixOS activation...1498machine # [ 42.223105] systemd[589]: Starting Run user-specific NixOS activation...1499machine # [ 42.260544] systemd[589]: Finished Run user-specific NixOS activation.1500machine # [ 42.266241] systemd[1]: Stopped target Reactivate sysinit units.1501machine # [ 42.270597] systemd[1]: Stopping Reactivate sysinit units...1502machine # [ 42.271944] systemd[1]: Reached target Reactivate sysinit units.1503machine # [ 42.277535] systemd[1]: Reached target Remote File Systems.1504machine # [ 42.282740] systemd[1]: Starting linger-users.service...1505machine # [ 42.295108] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1506machine # [ 42.303028] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1507machine # [ 42.307940] systemd[1]: Reached target Local File Systems.1508machine # [ 42.315495] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1509machine # [ 42.323899] systemd[1]: Starting Create SUID/SGID Wrappers...1510machine # [ 42.326765] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1511machine # [ 42.330919] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671512machine # [ 42.336629] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1513machine # [ 42.338482] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1514machine # [ 42.347934] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1515machine # [ 42.394760] systemd[1]: linger-users.service: Deactivated successfully.1516machine # [ 42.397106] systemd[1]: Finished linger-users.service.1517machine # [ 42.570787] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1518machine # [ 42.574223] systemd[1]: Finished Create SUID/SGID Wrappers.1519machine # [ 42.831185] nixos[2619]: finished switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test1520machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.95 seconds)1521machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1522machine # [ 42.880914] sudo[2756]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1523machine # [ 42.898647] sudo[2756]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1524machine # [ 42.915538] sudo[2756]: pam_unix(sudo:session): session closed for user usertest1525machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1526machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1527machine # [ 42.954668] sudo[2762]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1528machine # [ 42.967932] sudo[2762]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1529machine # [ 42.978174] sudo[2762]: pam_unix(sudo:session): session closed for user usertest1530machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)1531machine: must succeed: sudo -u usertest cp /nix/store/c6j9pbdm0kjdchl4w0f5sxmdx7bag841-migrated.service ~usertest/.config/systemd/user/migrated.service1532machine # [ 43.011598] sudo[2768]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/c6j9pbdm0kjdchl4w0f5sxmdx7bag841-migrated.service /home/usertest/.config/systemd/user/migrated.service1533machine # [ 43.023738] sudo[2768]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1534machine # [ 43.033445] sudo[2768]: pam_unix(sudo:session): session closed for user usertest1535machine: (finished: must succeed: sudo -u usertest cp /nix/store/c6j9pbdm0kjdchl4w0f5sxmdx7bag841-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1536machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1537machine # [ 43.065695] sudo[2774]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service1538machine # [ 43.077628] sudo[2774]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1539machine # [ 43.086765] sudo[2774]: pam_unix(sudo:session): session closed for user usertest1540machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)1541machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1542machine # [ 43.118512] sudo[2780]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1543machine # [ 43.129891] sudo[2780]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1544machine # [ 43.140337] systemd[589]: Reload requested from client PID 2785 ('systemctl')...1545machine # [ 43.144221] systemd[589]: Reloading...1546machine # [ 43.261626] systemd[589]: Reloading finished in 118 ms.1547machine # [ 43.276186] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1548machine # [ 43.291577] sudo[2780]: pam_unix(sudo:session): session closed for user usertest1549machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.21 seconds)1550machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1551machine # [ 43.324497] sudo[2790]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1552machine # [ 43.335826] sudo[2790]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1553machine # [ 43.351185] systemd[589]: Starting migrated.service...1554machine # [ 43.379604] systemd[589]: Finished migrated.service.1555machine # [ 43.384721] sudo[2790]: pam_unix(sudo:session): session closed for user usertest1556machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)1557machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1558machine # [ 43.415487] sudo[2798]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1559machine # [ 43.426631] sudo[2798]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1560machine # [ 43.437608] sudo[2798]: pam_unix(sudo:session): session closed for user usertest1561machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1562machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1563machine # [ 43.472517] sudo[2804]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1564machine # [ 43.485973] sudo[2804]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1565machine # [ 43.495222] sudo[2804]: pam_unix(sudo:session): session closed for user usertest1566machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)1567machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1568machine # [ 43.527900] sudo[2810]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1569machine # [ 43.538926] sudo[2810]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1570machine # [ 43.557935] sudo[2810]: pam_unix(sudo:session): session closed for user usertest1571machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)1572machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&11573machine # [ 43.804937] nixos[2819]: switching to system configuration /nix/store/746lmkqq0b18dyimjxvqmi0844fi07ix-nixos-system-machine-test1574machine # [ 43.814788] systemd[1]: Stopped target Remote File Systems.1575machine # [ 43.815714] systemd[1]: Stopped target Local File Systems.1576machine # [ 44.175285] systemd[1]: Reload requested from client PID 2819 ('.switch-to-conf') (unit backdoor.service)...1577machine # [ 44.177535] systemd[1]: Reloading...1578machine # [ 44.519987] systemd[1]: Reloading finished in 340 ms.1579machine # [ 44.631994] systemd[589]: Reexecution requested from client PID 2888 ('switch-to-confi')...1580machine # [ 44.635354] systemd[589]: Reexecuting.1581machine # [ 44.700475] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1582machine # [ 44.831274] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1583machine # [ 44.839118] systemd[589]: Reload requested from client PID 2888 ('switch-to-confi')...1584machine # [ 44.843926] systemd[589]: Reloading...1585machine # [ 44.967204] systemd[589]: Reloading finished in 124 ms.1586machine # [ 44.984242] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1587machine # [ 44.994263] systemd[589]: Stopped Run user-specific NixOS activation.1588machine # [ 44.997102] systemd[589]: Stopping Run user-specific NixOS activation...1589machine # [ 44.999695] systemd[589]: Starting Run user-specific NixOS activation...1590machine # [ 45.060484] systemd[589]: Reload requested from client PID 2901 ('systemctl') (unit nixos-activation.service)...1591machine # [ 45.065783] systemd[589]: Reloading...1592machine # [ 45.196500] systemd[589]: Reloading finished in 132 ms.1593machine # [ 45.214643] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1594machine # [ 45.238022] systemd[589]: Stopped migrated.service.1595machine # [ 45.251723] systemd[589]: Finished Run user-specific NixOS activation.1596machine # [ 45.255309] systemd[589]: Reload requested from client PID 2888 ('switch-to-confi')...1597machine # [ 45.260331] systemd[589]: Reloading...1598machine # [ 45.381541] systemd[589]: Reloading finished in 122 ms.1599machine # [ 45.396184] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1600machine # [ 45.408576] systemd[589]: Starting migrated.service...1601machine # [ 45.436887] systemd[589]: Finished migrated.service.1602machine # [ 45.443613] systemd[1]: Stopped target Reactivate sysinit units.1603machine # [ 45.446550] systemd[1]: Stopping Reactivate sysinit units...1604machine # [ 45.447469] systemd[1]: Reached target Reactivate sysinit units.1605machine # [ 45.451891] systemd[1]: Reached target Local File Systems.1606machine # [ 45.453585] systemd[1]: Reached target Remote File Systems.1607machine # [ 45.462145] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1608machine # [ 45.471487] systemd[1]: Starting Create SUID/SGID Wrappers...1609machine # [ 45.476297] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1610machine # [ 45.477729] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671611machine # [ 45.479986] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1612machine # [ 45.488581] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1613machine # [ 45.489987] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1614machine # [ 45.491252] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1615machine # [ 45.492708] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1616machine # [ 45.677912] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1617machine # [ 45.680160] systemd[1]: Finished Create SUID/SGID Wrappers.1618machine # [ 45.686451] systemd[1]: Starting linger-users.service...1619machine # [ 45.734941] systemd[1]: linger-users.service: Deactivated successfully.1620machine # [ 45.737134] systemd[1]: Finished linger-users.service.1621machine # [ 45.995061] nixos[2819]: finished switching to system configuration /nix/store/746lmkqq0b18dyimjxvqmi0844fi07ix-nixos-system-machine-test1622machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 2.44 seconds)1623machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1624machine # [ 46.053028] sudo[2971]: 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.service1625machine # [ 46.071996] sudo[2971]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1626machine # [ 46.086327] sudo[2971]: pam_unix(sudo:session): session closed for user usertest1627machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.09 seconds)1628machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1629machine # [ 46.122166] sudo[2977]: 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.service1630machine # [ 46.134515] sudo[2977]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1631machine # [ 46.154594] sudo[2977]: pam_unix(sudo:session): session closed for user usertest1632machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)1633machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1634machine # [ 46.185293] sudo[2983]: 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-owner1635machine # [ 46.196837] sudo[2983]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1636machine # [ 46.206242] sudo[2983]: pam_unix(sudo:session): session closed for user usertest1637machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1638machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&11639machine # [ 46.417389] nixos[2992]: switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test1640machine # [ 46.420429] systemd[1]: Stopped target Local File Systems.1641machine # [ 46.424122] systemd[1]: Stopped target Remote File Systems.1642machine # [ 46.725208] systemd[1]: Reload requested from client PID 2992 ('.switch-to-conf') (unit backdoor.service)...1643machine # [ 46.727346] systemd[1]: Reloading...1644machine # [ 47.041203] systemd[1]: Reloading finished in 312 ms.1645machine # [ 47.128612] systemd[589]: Stopped migrated.service.1646machine # [ 47.132604] systemd[589]: Reexecution requested from client PID 3061 ('switch-to-confi')...1647machine # [ 47.134038] systemd[589]: Reexecuting.1648machine # [ 47.195585] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1649machine # [ 47.308923] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1650machine # [ 47.315603] systemd[589]: Reload requested from client PID 3061 ('switch-to-confi')...1651machine # [ 47.319235] systemd[589]: Reloading...1652machine # [ 47.422240] systemd[589]: Reloading finished in 102 ms.1653machine # [ 47.436159] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1654machine # [ 47.445125] systemd[589]: Stopped Run user-specific NixOS activation.1655machine # [ 47.447474] systemd[589]: Stopping Run user-specific NixOS activation...1656machine # [ 47.449596] systemd[589]: Starting Run user-specific NixOS activation...1657machine # [ 47.478845] systemd[589]: Finished Run user-specific NixOS activation.1658machine # [ 47.482682] systemd[1]: Stopped target Reactivate sysinit units.1659machine # [ 47.485371] systemd[1]: Stopping Reactivate sysinit units...1660machine # [ 47.486298] systemd[1]: Reached target Reactivate sysinit units.1661machine # [ 47.487235] systemd[1]: Reached target Remote File Systems.1662machine # [ 47.491903] systemd[1]: Starting linger-users.service...1663machine # [ 47.496824] systemd[1]: Reached target Local File Systems.1664machine # [ 47.497702] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1665machine # [ 47.511738] systemd[1]: Starting Create SUID/SGID Wrappers...1666machine # [ 47.513726] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1667machine # [ 47.515193] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671668machine # [ 47.523436] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1669machine # [ 47.525705] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1670machine # [ 47.527040] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1671machine # [ 47.531807] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1672machine # [ 47.536384] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1673machine # [ 47.583329] systemd[1]: linger-users.service: Deactivated successfully.1674machine # [ 47.586866] systemd[1]: Finished linger-users.service.1675machine # [ 47.708625] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1676machine # [ 47.711631] systemd[1]: Finished Create SUID/SGID Wrappers.1677machine # [ 47.967756] nixos[2992]: finished switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test1678machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.77 seconds)1679machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1680machine # [ 48.010229] sudo[3129]: 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.service1681machine # [ 48.025423] sudo[3129]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1682machine # [ 48.039874] sudo[3129]: pam_unix(sudo:session): session closed for user usertest1683machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1684machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1685machine # [ 48.071121] sudo[3135]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1686machine # [ 48.082365] sudo[3135]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1687machine # [ 48.090506] sudo[3135]: pam_unix(sudo:session): session closed for user usertest1688machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.05 seconds)1689machine: must succeed: sudo -u usertest cp /nix/store/c6j9pbdm0kjdchl4w0f5sxmdx7bag841-migrated.service ~usertest/.config/systemd/user/migrated.service1690machine # [ 48.117871] sudo[3141]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/c6j9pbdm0kjdchl4w0f5sxmdx7bag841-migrated.service /home/usertest/.config/systemd/user/migrated.service1691machine # [ 48.128745] sudo[3141]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1692machine # [ 48.135831] sudo[3141]: pam_unix(sudo:session): session closed for user usertest1693machine: (finished: must succeed: sudo -u usertest cp /nix/store/c6j9pbdm0kjdchl4w0f5sxmdx7bag841-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1694machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1695machine # [ 48.162147] sudo[3147]: 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.service1696machine # [ 48.172898] sudo[3147]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1697machine # [ 48.179713] sudo[3147]: pam_unix(sudo:session): session closed for user usertest1698machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.04 seconds)1699machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1700machine # [ 48.205889] sudo[3153]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1701machine # [ 48.215707] sudo[3153]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1702machine # [ 48.224178] systemd[589]: Reload requested from client PID 3158 ('systemctl')...1703machine # [ 48.228118] systemd[589]: Reloading...1704machine # [ 48.334286] systemd[589]: Reloading finished in 107 ms.1705machine # [ 48.348154] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1706machine # [ 48.359959] sudo[3153]: pam_unix(sudo:session): session closed for user usertest1707machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.18 seconds)1708machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1709machine # [ 48.386277] sudo[3163]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1710machine # [ 48.395766] sudo[3163]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1711machine # [ 48.408158] systemd[589]: Starting migrated.service...1712machine # [ 48.433351] systemd[589]: Finished migrated.service.1713machine # [ 48.436877] sudo[3163]: pam_unix(sudo:session): session closed for user usertest1714machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)1715machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1716machine # [ 48.462683] sudo[3171]: 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.service1717machine # [ 48.472809] sudo[3171]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1718machine # [ 48.481841] sudo[3171]: pam_unix(sudo:session): session closed for user usertest1719machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds)1720machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1721machine # [ 48.507141] sudo[3177]: 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-owner1722machine # [ 48.516643] sudo[3177]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1723machine # [ 48.523532] sudo[3177]: pam_unix(sudo:session): session closed for user usertest1724machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.04 seconds)1725machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1726machine # [ 48.548947] sudo[3183]: 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.service1727machine # [ 48.558567] sudo[3183]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1728machine # [ 48.574048] sudo[3183]: pam_unix(sudo:session): session closed for user usertest1729machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)1730machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&11731machine # [ 48.745825] nixos[3192]: switching to system configuration /nix/store/n5j00nyvzz4zzm9z128h92lllw1akw61-nixos-system-machine-test1732machine # [ 48.749769] systemd[1]: Stopped target Local File Systems.1733machine # [ 48.750869] systemd[1]: Stopped target Remote File Systems.1734machine # [ 49.035962] systemd[1]: Reload requested from client PID 3192 ('.switch-to-conf') (unit backdoor.service)...1735machine # [ 49.037992] systemd[1]: Reloading...1736machine # [ 49.333539] systemd[1]: Reloading finished in 294 ms.1737machine # [ 49.428453] systemd[589]: Reexecution requested from client PID 3261 ('switch-to-confi')...1738machine # [ 49.429926] systemd[589]: Reexecuting.1739machine # [ 49.487595] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1740machine # [ 49.602067] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1741machine # [ 49.608515] systemd[589]: Reload requested from client PID 3261 ('switch-to-confi')...1742machine # [ 49.612060] systemd[589]: Reloading...1743machine # [ 49.716224] systemd[589]: Reloading finished in 104 ms.1744machine # [ 49.732163] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1745machine # [ 49.741786] systemd[589]: Stopped Run user-specific NixOS activation.1746machine # [ 49.744347] systemd[589]: Stopping Run user-specific NixOS activation...1747machine # [ 49.746776] systemd[589]: Starting Run user-specific NixOS activation...1748machine # [ 49.783695] systemd[589]: Reload requested from client PID 3273 ('systemctl') (unit nixos-activation.service)...1749machine # [ 49.788191] systemd[589]: Reloading...1750machine # [ 49.896843] systemd[589]: Reloading finished in 109 ms.1751machine # [ 49.912177] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1752machine # [ 49.930517] systemd[589]: Finished Run user-specific NixOS activation.1753machine # [ 49.933278] systemd[589]: Reload requested from client PID 3261 ('switch-to-confi')...1754machine # [ 49.937393] systemd[589]: Reloading...1755machine # [ 50.043152] systemd[589]: Reloading finished in 106 ms.1756machine # [ 50.060178] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1757machine # [ 50.069955] systemd[589]: Stopped migrated.service.1758machine # [ 50.072133] systemd[589]: Stopping migrated.service...1759machine # [ 50.074006] systemd[589]: Starting migrated.service...1760machine # [ 50.099215] systemd[589]: Finished migrated.service.1761machine # [ 50.105890] systemd[1]: Stopped target Reactivate sysinit units.1762machine # [ 50.107320] systemd[1]: Stopping Reactivate sysinit units...1763machine # [ 50.109923] systemd[1]: Reached target Reactivate sysinit units.1764machine # [ 50.117090] systemd[1]: Reached target Remote File Systems.1765machine # [ 50.121475] systemd[1]: Starting linger-users.service...1766machine # [ 50.132456] systemd[1]: Reached target Local File Systems.1767machine # [ 50.133459] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1768machine # [ 50.136130] systemd[1]: Starting Create SUID/SGID Wrappers...1769machine # [ 50.137113] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1770machine # [ 50.149383] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671771machine # [ 50.151983] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1772machine # [ 50.163049] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1773machine # [ 50.165870] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1774machine # [ 50.167225] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1775machine # [ 50.173951] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1776machine # [ 50.207716] systemd[1]: linger-users.service: Deactivated successfully.1777machine # [ 50.212327] systemd[1]: Finished linger-users.service.1778machine # [ 50.351067] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1779machine # [ 50.353972] systemd[1]: Finished Create SUID/SGID Wrappers.1780machine # [ 50.609626] nixos[3192]: finished switching to system configuration /nix/store/n5j00nyvzz4zzm9z128h92lllw1akw61-nixos-system-machine-test1781machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 2.04 seconds)1782machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1783machine # [ 50.653131] sudo[3342]: 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.service1784machine # [ 50.669348] sudo[3342]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1785machine # [ 50.682638] sudo[3342]: pam_unix(sudo:session): session closed for user usertest1786machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1787machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1788machine # [ 50.716711] sudo[3348]: 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.service1789machine # [ 50.728755] sudo[3348]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1790machine # [ 50.747502] sudo[3348]: pam_unix(sudo:session): session closed for user usertest1791machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)1792machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1793machine # [ 50.775594] sudo[3354]: 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-owner1794machine # [ 50.785510] sudo[3354]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1795machine # [ 50.793879] sudo[3354]: pam_unix(sudo:session): session closed for user usertest1796machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1797machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&11798machine # [ 50.972807] nixos[3363]: switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test1799machine # [ 50.975332] systemd[1]: Stopped target Remote File Systems.1800machine # [ 50.977646] systemd[1]: Stopped target Local File Systems.1801machine # [ 51.241144] systemd[1]: Reload requested from client PID 3363 ('.switch-to-conf') (unit backdoor.service)...1802machine # [ 51.243119] systemd[1]: Reloading...1803machine # [ 51.543649] systemd[1]: Reloading finished in 299 ms.1804machine # [ 51.631857] systemd[589]: Stopped migrated.service.1805machine # [ 51.635965] systemd[589]: Reexecution requested from client PID 3432 ('switch-to-confi')...1806machine # [ 51.637444] systemd[589]: Reexecuting.1807machine # [ 51.695680] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1808machine # [ 51.812566] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1809machine # [ 51.820078] systemd[589]: Reload requested from client PID 3432 ('switch-to-confi')...1810machine # [ 51.823951] systemd[589]: Reloading...1811machine # [ 51.929838] systemd[589]: Reloading finished in 105 ms.1812machine # [ 51.945699] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1813machine # [ 51.954582] systemd[589]: Stopped Run user-specific NixOS activation.1814machine # [ 51.957089] systemd[589]: Stopping Run user-specific NixOS activation...1815machine # [ 51.959266] systemd[589]: Starting Run user-specific NixOS activation...1816machine # [ 51.991532] systemd[589]: Finished Run user-specific NixOS activation.1817machine # [ 51.995797] systemd[1]: Stopped target Reactivate sysinit units.1818machine # [ 51.998644] systemd[1]: Stopping Reactivate sysinit units...1819machine # [ 51.999588] systemd[1]: Reached target Reactivate sysinit units.1820machine # [ 52.002370] systemd[1]: Reached target Remote File Systems.1821machine # [ 52.008570] systemd[1]: Starting linger-users.service...1822machine # [ 52.023533] systemd[1]: Reached target Local File Systems.1823machine # [ 52.026963] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1824machine # [ 52.037682] systemd[1]: Starting Create SUID/SGID Wrappers...1825machine # [ 52.038692] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1826machine # [ 52.039950] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671827machine # [ 52.051312] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1828machine # [ 52.054211] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1829machine # [ 52.055657] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1830machine # [ 52.058626] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1831machine # [ 52.065535] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1832machine # [ 52.106220] systemd[1]: linger-users.service: Deactivated successfully.1833machine # [ 52.111090] systemd[1]: Finished linger-users.service.1834machine # [ 52.247254] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1835machine # [ 52.251052] systemd[1]: Finished Create SUID/SGID Wrappers.1836machine # [ 52.505770] nixos[3363]: finished switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test1837machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.71 seconds)1838machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1839machine # [ 52.547933] sudo[3500]: 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.service1840machine # [ 52.563849] sudo[3500]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1841machine # [ 52.579634] sudo[3500]: pam_unix(sudo:session): session closed for user usertest1842machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1843machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1844machine # [ 52.615197] sudo[3506]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1845machine # [ 52.626863] sudo[3506]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1846machine # [ 52.635105] sudo[3506]: pam_unix(sudo:session): session closed for user usertest1847machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.05 seconds)1848machine: must succeed: sudo -u usertest cp /nix/store/c6j9pbdm0kjdchl4w0f5sxmdx7bag841-migrated.service ~usertest/.config/systemd/user/migrated.service1849machine # [ 52.662904] sudo[3512]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/c6j9pbdm0kjdchl4w0f5sxmdx7bag841-migrated.service /home/usertest/.config/systemd/user/migrated.service1850machine # [ 52.674102] sudo[3512]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1851machine # [ 52.681319] sudo[3512]: pam_unix(sudo:session): session closed for user usertest1852machine: (finished: must succeed: sudo -u usertest cp /nix/store/c6j9pbdm0kjdchl4w0f5sxmdx7bag841-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1853machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1854machine # [ 52.711376] sudo[3518]: 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.service1855machine # [ 52.723886] sudo[3518]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1856machine # [ 52.732538] sudo[3518]: pam_unix(sudo:session): session closed for user usertest1857machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)1858machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1859machine # [ 52.765910] sudo[3524]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1860machine # [ 52.776934] sudo[3524]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1861machine # [ 52.786634] systemd[589]: Reload requested from client PID 3529 ('systemctl')...1862machine # [ 52.791315] systemd[589]: Reloading...1863machine # [ 52.905602] systemd[589]: Reloading finished in 115 ms.1864machine # [ 52.920235] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1865machine # [ 52.932881] sudo[3524]: pam_unix(sudo:session): session closed for user usertest1866machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.20 seconds)1867machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1868machine # [ 52.964922] sudo[3534]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1869machine # [ 52.976803] sudo[3534]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1870machine # [ 52.991628] systemd[589]: Starting migrated.service...1871machine # [ 53.020690] systemd[589]: Finished migrated.service.1872machine # [ 53.025955] sudo[3534]: pam_unix(sudo:session): session closed for user usertest1873machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)1874machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1875machine # [ 53.059926] sudo[3542]: 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.service1876machine # [ 53.071593] sudo[3542]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1877machine # [ 53.082977] sudo[3542]: pam_unix(sudo:session): session closed for user usertest1878machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)1879machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1880machine # [ 53.110090] sudo[3548]: 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-owner1881machine # [ 53.119994] sudo[3548]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1882machine # [ 53.128146] sudo[3548]: pam_unix(sudo:session): session closed for user usertest1883machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.04 seconds)1884machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1885machine # [ 53.155772] sudo[3554]: 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.service1886machine # [ 53.166257] sudo[3554]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1887machine # [ 53.183169] sudo[3554]: pam_unix(sudo:session): session closed for user usertest1888machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)1889machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&11890machine # [ 53.358952] nixos[3563]: switching to system configuration /nix/store/xxffiqv1q7q3xb5xpj7swjr14fmgcwg2-nixos-system-machine-test1891machine # [ 53.361833] systemd[1]: Stopped target Local File Systems.1892machine # [ 53.364212] systemd[1]: Stopped target Remote File Systems.1893machine # [ 53.649548] systemd[1]: Reload requested from client PID 3563 ('.switch-to-conf') (unit backdoor.service)...1894machine # [ 53.651537] systemd[1]: Reloading...1895machine # [ 53.945383] systemd[1]: Reloading finished in 292 ms.1896machine # [ 54.031846] systemd[589]: Reexecution requested from client PID 3632 ('switch-to-confi')...1897machine # [ 54.036290] systemd[589]: Reexecuting.1898machine # [ 54.087345] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1899machine # [ 54.199836] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1900machine # [ 54.206589] systemd[589]: Reload requested from client PID 3632 ('switch-to-confi')...1901machine # [ 54.207942] systemd[589]: Reloading...1902machine # [ 54.312115] systemd[589]: Reloading finished in 102 ms.1903machine # [ 54.328165] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1904machine # [ 54.337672] systemd[589]: Stopped Run user-specific NixOS activation.1905machine # [ 54.340059] systemd[589]: Stopping Run user-specific NixOS activation...1906machine # [ 54.342156] systemd[589]: Starting Run user-specific NixOS activation...1907machine # [ 54.374964] systemd[589]: Reload requested from client PID 3644 ('systemctl') (unit nixos-activation.service)...1908machine # [ 54.379328] systemd[589]: Reloading...1909machine # [ 54.485921] systemd[589]: Reloading finished in 107 ms.1910machine # [ 54.500174] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1911machine # [ 54.517707] systemd[589]: Finished Run user-specific NixOS activation.1912machine # [ 54.520561] systemd[589]: Reload requested from client PID 3632 ('switch-to-confi')...1913machine # [ 54.524298] systemd[589]: Reloading...1914machine # [ 54.629064] systemd[589]: Reloading finished in 105 ms.1915machine # [ 54.645579] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1916machine # [ 54.654581] systemd[589]: Reloading migrated.service...1917machine # [ 54.680494] systemd[589]: Reloaded migrated.service.1918machine # [ 54.687097] systemd[1]: Stopped target Reactivate sysinit units.1919machine # [ 54.689905] systemd[1]: Stopping Reactivate sysinit units...1920machine # [ 54.690857] systemd[1]: Reached target Reactivate sysinit units.1921machine # [ 54.694222] systemd[1]: Reached target Local File Systems.1922machine # [ 54.697921] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1923machine # [ 54.701873] systemd[1]: Starting Create SUID/SGID Wrappers...1924machine # [ 54.702877] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1925machine # [ 54.713040] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671926machine # [ 54.715380] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1927machine # [ 54.722758] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1928machine # [ 54.724167] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1929machine # [ 54.725372] systemd[1]: Reached target Remote File Systems.1930machine # [ 54.726179] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1931machine # [ 54.727559] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1932machine # [ 54.876625] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1933machine # [ 54.880379] systemd[1]: Finished Create SUID/SGID Wrappers.1934machine # [ 54.883520] systemd[1]: Starting linger-users.service...1935machine # [ 54.924365] systemd[1]: linger-users.service: Deactivated successfully.1936machine # [ 54.927091] systemd[1]: Finished linger-users.service.1937machine # [ 55.182540] nixos[3563]: finished switching to system configuration /nix/store/xxffiqv1q7q3xb5xpj7swjr14fmgcwg2-nixos-system-machine-test1938machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 2.00 seconds)1939machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1940machine # [ 55.226797] sudo[3713]: 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.service1941machine # [ 55.242484] sudo[3713]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1942machine # [ 55.256550] sudo[3713]: pam_unix(sudo:session): session closed for user usertest1943machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1944machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1945machine # [ 55.289208] sudo[3719]: 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-owner1946machine # [ 55.300947] sudo[3719]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1947machine # [ 55.308592] sudo[3719]: pam_unix(sudo:session): session closed for user usertest1948machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1949machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&11950machine # [ 55.481725] nixos[3728]: switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test1951machine # [ 55.484403] systemd[1]: Stopped target Remote File Systems.1952machine # [ 55.486886] systemd[1]: Stopped target Local File Systems.1953machine # [ 55.760637] systemd[1]: Reload requested from client PID 3728 ('.switch-to-conf') (unit backdoor.service)...1954machine # [ 55.762217] systemd[1]: Reloading...1955machine # [ 56.059627] systemd[1]: Reloading finished in 296 ms.1956machine # [ 56.144704] systemd[589]: Stopped migrated.service.1957machine # [ 56.148490] systemd[589]: Reexecution requested from client PID 3797 ('switch-to-confi')...1958machine # [ 56.149843] systemd[589]: Reexecuting.1959machine # [ 56.211915] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1960machine # [ 56.331333] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1961machine # [ 56.337970] systemd[589]: Reload requested from client PID 3797 ('switch-to-confi')...1962machine # [ 56.340596] systemd[589]: Reloading...1963machine # [ 56.447376] systemd[589]: Reloading finished in 105 ms.1964machine # [ 56.464212] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1965machine # [ 56.475026] systemd[589]: Stopped Run user-specific NixOS activation.1966machine # [ 56.477416] systemd[589]: Stopping Run user-specific NixOS activation...1967machine # [ 56.479795] systemd[589]: Starting Run user-specific NixOS activation...1968machine # [ 56.511224] systemd[589]: Finished Run user-specific NixOS activation.1969machine # [ 56.515389] systemd[1]: Stopped target Reactivate sysinit units.1970machine # [ 56.518569] systemd[1]: Stopping Reactivate sysinit units...1971machine # [ 56.519442] systemd[1]: Reached target Reactivate sysinit units.1972machine # [ 56.521983] systemd[1]: Reached target Remote File Systems.1973machine # [ 56.528067] systemd[1]: Reached target Local File Systems.1974machine # [ 56.530705] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1975machine # [ 56.538874] systemd[1]: Starting Create SUID/SGID Wrappers...1976machine # [ 56.539791] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1977machine # [ 56.546672] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671978machine # [ 56.551573] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1979machine # [ 56.553398] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1980machine # [ 56.554708] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1981machine # [ 56.555915] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1982machine # [ 56.557345] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1983machine # [ 56.714215] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1984machine # [ 56.718962] systemd[1]: Finished Create SUID/SGID Wrappers.1985machine # [ 56.721913] systemd[1]: Starting linger-users.service...1986machine # [ 56.761234] systemd[1]: linger-users.service: Deactivated successfully.1987machine # [ 56.764368] systemd[1]: Finished linger-users.service.1988machine # [ 57.019534] nixos[3728]: finished switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test1989machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.72 seconds)1990machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1991machine # [ 57.065298] sudo[3865]: 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.service1992machine # [ 57.080848] sudo[3865]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1993machine # [ 57.095881] sudo[3865]: pam_unix(sudo:session): session closed for user usertest1994machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1995machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1996machine # [ 57.128842] sudo[3871]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1997machine # [ 57.139925] sudo[3871]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1998machine # [ 57.148686] sudo[3871]: pam_unix(sudo:session): session closed for user usertest1999machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.05 seconds)2000machine: must succeed: sudo -u usertest cp /nix/store/c6j9pbdm0kjdchl4w0f5sxmdx7bag841-migrated.service ~usertest/.config/systemd/user/migrated.service2001machine # [ 57.176768] sudo[3877]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/c6j9pbdm0kjdchl4w0f5sxmdx7bag841-migrated.service /home/usertest/.config/systemd/user/migrated.service2002machine # [ 57.187565] sudo[3877]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2003machine # [ 57.195682] sudo[3877]: pam_unix(sudo:session): session closed for user usertest2004machine: (finished: must succeed: sudo -u usertest cp /nix/store/c6j9pbdm0kjdchl4w0f5sxmdx7bag841-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)2005machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2006machine # [ 57.223283] sudo[3883]: 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.service2007machine # [ 57.233748] sudo[3883]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2008machine # [ 57.241672] sudo[3883]: pam_unix(sudo:session): session closed for user usertest2009machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)2010machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2011machine # [ 57.272713] sudo[3889]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2012machine # [ 57.282613] sudo[3889]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2013machine # [ 57.291032] systemd[589]: Reload requested from client PID 3894 ('systemctl')...2014machine # [ 57.295298] systemd[589]: Reloading...2015machine # [ 57.403233] systemd[589]: Reloading finished in 109 ms.2016machine # [ 57.420203] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2017machine # [ 57.433949] sudo[3889]: pam_unix(sudo:session): session closed for user usertest2018machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)2019machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2020machine # [ 57.463528] sudo[3899]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2021machine # [ 57.473718] sudo[3899]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2022machine # [ 57.486734] systemd[589]: Starting migrated.service...2023machine # [ 57.515021] systemd[589]: Finished migrated.service.2024machine # [ 57.519261] sudo[3899]: pam_unix(sudo:session): session closed for user usertest2025machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)2026machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2027machine # [ 57.547669] sudo[3907]: 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.service2028machine # [ 57.558209] sudo[3907]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2029machine # [ 57.568930] sudo[3907]: pam_unix(sudo:session): session closed for user usertest2030machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)2031machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2032machine # [ 57.597033] sudo[3913]: 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-owner2033machine # [ 57.607371] sudo[3913]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2034machine # [ 57.615613] sudo[3913]: pam_unix(sudo:session): session closed for user usertest2035machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2036machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2037machine # [ 57.642872] sudo[3919]: 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.service2038machine # [ 57.653479] sudo[3919]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2039machine # [ 57.669783] sudo[3919]: pam_unix(sudo:session): session closed for user usertest2040machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)2041machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&12042machine # [ 57.849646] nixos[3928]: switching to system configuration /nix/store/rlahbksi6apwi7yizc1ymgfsfwhbcxss-nixos-system-machine-test2043machine # [ 57.853743] systemd[1]: Stopped target Remote File Systems.2044machine # [ 57.854725] systemd[1]: Stopped target Local File Systems.2045machine # [ 58.148238] systemd[1]: Reload requested from client PID 3928 ('.switch-to-conf') (unit backdoor.service)...2046machine # [ 58.150433] systemd[1]: Reloading...2047machine # [ 58.465443] systemd[1]: Reloading finished in 313 ms.2048machine # [ 58.557642] systemd[589]: Reexecution requested from client PID 3997 ('switch-to-confi')...2049machine # [ 58.559201] systemd[589]: Reexecuting.2050machine # [ 58.615594] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2051machine # [ 58.730978] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2052machine # [ 58.737855] systemd[589]: Reload requested from client PID 3997 ('switch-to-confi')...2053machine # [ 58.741902] systemd[589]: Reloading...2054machine # [ 58.848617] systemd[589]: Reloading finished in 107 ms.2055machine # [ 58.864186] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2056machine # [ 58.875551] systemd[589]: Stopped Run user-specific NixOS activation.2057machine # [ 58.878018] systemd[589]: Stopping Run user-specific NixOS activation...2058machine # [ 58.880747] systemd[589]: Starting Run user-specific NixOS activation...2059machine # [ 58.917267] systemd[589]: Reload requested from client PID 4009 ('systemctl') (unit nixos-activation.service)...2060machine # [ 58.920311] systemd[589]: Reloading...2061machine # [ 59.033620] systemd[589]: Reloading finished in 112 ms.2062machine # [ 59.048178] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2063machine # [ 59.065266] systemd[589]: Finished Run user-specific NixOS activation.2064machine # [ 59.068419] systemd[589]: Reload requested from client PID 3997 ('switch-to-confi')...2065machine # [ 59.072254] systemd[589]: Reloading...2066machine # [ 59.178343] systemd[589]: Reloading finished in 107 ms.2067machine # [ 59.192169] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2068machine # [ 59.205889] systemd[1]: Stopped target Reactivate sysinit units.2069machine # [ 59.209543] systemd[1]: Stopping Reactivate sysinit units...2070machine # [ 59.210472] systemd[1]: Reached target Reactivate sysinit units.2071machine # [ 59.211307] systemd[1]: Reached target Remote File Systems.2072machine # [ 59.216973] systemd[1]: Starting linger-users.service...2073machine # [ 59.224282] systemd[1]: Reached target Local File Systems.2074machine # [ 59.225166] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2075machine # [ 59.226735] systemd[1]: Starting Create SUID/SGID Wrappers...2076machine # [ 59.227552] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2077machine # [ 59.246434] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672078machine # [ 59.249326] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2079machine # [ 59.250983] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2080machine # [ 59.253439] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2081machine # [ 59.254844] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2082machine # [ 59.260859] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2083machine # [ 59.308629] systemd[1]: linger-users.service: Deactivated successfully.2084machine # [ 59.312293] systemd[1]: Finished linger-users.service.2085machine # [ 59.441712] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2086machine # [ 59.445704] systemd[1]: Finished Create SUID/SGID Wrappers.2087machine # [ 59.699915] nixos[3928]: finished switching to system configuration /nix/store/rlahbksi6apwi7yizc1ymgfsfwhbcxss-nixos-system-machine-test2088machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 2.03 seconds)2089machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2090machine # [ 59.744781] sudo[4076]: 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.service2091machine # [ 59.761053] sudo[4076]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2092machine # [ 59.774907] sudo[4076]: pam_unix(sudo:session): session closed for user usertest2093machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2094machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2095machine # [ 59.810609] sudo[4082]: 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-owner2096machine # [ 59.822440] sudo[4082]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2097machine # [ 59.830910] sudo[4082]: pam_unix(sudo:session): session closed for user usertest2098machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2099machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&12100machine # [ 60.014704] nixos[4091]: switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test2101machine # [ 60.019819] systemd[1]: Stopped target Remote File Systems.2102machine # [ 60.022144] systemd[1]: Stopped target Local File Systems.2103machine # [ 60.297240] systemd[1]: Reload requested from client PID 4091 ('.switch-to-conf') (unit backdoor.service)...2104machine # [ 60.299305] systemd[1]: Reloading...2105machine # [ 60.610779] systemd[1]: Reloading finished in 309 ms.2106machine # [ 60.698169] systemd[589]: Stopped migrated.service.2107machine # [ 60.701884] systemd[589]: Reexecution requested from client PID 4160 ('switch-to-confi')...2108machine # [ 60.703496] systemd[589]: Reexecuting.2109machine # [ 60.747050] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2110machine # [ 60.853520] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2111machine # [ 60.860479] systemd[589]: Reload requested from client PID 4160 ('switch-to-confi')...2112machine # [ 60.863229] systemd[589]: Reloading...2113machine # [ 60.969885] systemd[589]: Reloading finished in 105 ms.2114machine # [ 60.984181] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2115machine # [ 60.994152] systemd[589]: Stopped Run user-specific NixOS activation.2116machine # [ 60.996593] systemd[589]: Stopping Run user-specific NixOS activation...2117machine # [ 60.998717] systemd[589]: Starting Run user-specific NixOS activation...2118machine # [ 61.032103] systemd[589]: Finished Run user-specific NixOS activation.2119machine # [ 61.036127] systemd[1]: Stopped target Reactivate sysinit units.2120machine # [ 61.041082] systemd[1]: Stopping Reactivate sysinit units...2121machine # [ 61.042033] systemd[1]: Reached target Reactivate sysinit units.2122machine # [ 61.042969] systemd[1]: Reached target Local File Systems.2123machine # [ 61.043752] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2124machine # [ 61.050151] systemd[1]: Starting Create SUID/SGID Wrappers...2125machine # [ 61.051137] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2126machine # [ 61.058302] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672127machine # [ 61.068697] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2128machine # [ 61.070523] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2129machine # [ 61.071814] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2130machine # [ 61.073825] systemd[1]: Reached target Remote File Systems.2131machine # [ 61.074707] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2132machine # [ 61.078631] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2133machine # [ 61.234378] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2134machine # [ 61.237014] systemd[1]: Finished Create SUID/SGID Wrappers.2135machine # [ 61.242050] systemd[1]: Starting linger-users.service...2136machine # [ 61.285092] systemd[1]: linger-users.service: Deactivated successfully.2137machine # [ 61.286918] systemd[1]: Finished linger-users.service.2138machine # [ 61.543199] nixos[4091]: finished switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test2139machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.72 seconds)2140machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2141machine # [ 61.585502] sudo[4228]: 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.service2142machine # [ 61.600930] sudo[4228]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2143machine # [ 61.616161] sudo[4228]: pam_unix(sudo:session): session closed for user usertest2144machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2145machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user2146machine # [ 61.649880] sudo[4234]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user2147machine # [ 61.661130] sudo[4234]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2148machine # [ 61.669485] sudo[4234]: pam_unix(sudo:session): session closed for user usertest2149machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.05 seconds)2150machine: must succeed: sudo -u usertest cp /nix/store/7dxyzxcyc376cgbcs2l6kyzz1i1qp53j-migrated.service ~usertest/.local/share/systemd/user/migrated.service2151machine # [ 61.696901] sudo[4240]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/7dxyzxcyc376cgbcs2l6kyzz1i1qp53j-migrated.service /home/usertest/.local/share/systemd/user/migrated.service2152machine # [ 61.707174] sudo[4240]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2153machine # [ 61.714885] sudo[4240]: pam_unix(sudo:session): session closed for user usertest2154machine: (finished: must succeed: sudo -u usertest cp /nix/store/7dxyzxcyc376cgbcs2l6kyzz1i1qp53j-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.05 seconds)2155machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2156machine # [ 61.740879] sudo[4246]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2157machine # [ 61.750653] sudo[4246]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2158machine # [ 61.759008] systemd[589]: Reload requested from client PID 4251 ('systemctl')...2159machine # [ 61.761285] systemd[589]: Reloading...2160machine # [ 61.867575] systemd[589]: Reloading finished in 105 ms.2161machine # [ 61.884169] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2162machine # [ 61.897958] sudo[4246]: pam_unix(sudo:session): session closed for user usertest2163machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.18 seconds)2164machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2165machine # [ 61.924931] sudo[4256]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2166machine # [ 61.934673] sudo[4256]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2167machine # [ 61.949308] systemd[589]: Starting migrated.service...2168machine # [ 61.973983] systemd[589]: Finished migrated.service.2169machine # [ 61.978063] sudo[4256]: pam_unix(sudo:session): session closed for user usertest2170machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)2171machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2172machine # [ 62.005199] sudo[4264]: 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.service2173machine # [ 62.015180] sudo[4264]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2174machine # [ 62.025110] sudo[4264]: pam_unix(sudo:session): session closed for user usertest2175machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)2176machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2177machine # [ 62.051116] sudo[4270]: 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-owner2178machine # [ 62.060732] sudo[4270]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2179machine # [ 62.068504] sudo[4270]: pam_unix(sudo:session): session closed for user usertest2180machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.04 seconds)2181machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2182machine # [ 62.094829] sudo[4276]: 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.service2183machine # [ 62.104866] sudo[4276]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2184machine # [ 62.123890] sudo[4276]: pam_unix(sudo:session): session closed for user usertest2185machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)2186machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12187machine # [ 62.300118] nixos[4285]: switching to system configuration /nix/store/j9xg8w0x2mp6qp2r6439vvic6zh4a6gy-nixos-system-machine-test2188machine # [ 62.302850] systemd[1]: Stopped target Local File Systems.2189machine # [ 62.305097] systemd[1]: Stopped target Remote File Systems.2190machine # [ 62.579503] systemd[1]: Reload requested from client PID 4285 ('.switch-to-conf') (unit backdoor.service)...2191machine # [ 62.581639] systemd[1]: Reloading...2192machine # [ 62.881074] systemd[1]: Reloading finished in 298 ms.2193machine # [ 62.969152] systemd[589]: Reexecution requested from client PID 4354 ('switch-to-confi')...2194machine # [ 62.973466] systemd[589]: Reexecuting.2195machine # [ 63.031251] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2196machine # [ 63.146765] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2197machine # [ 63.153516] systemd[589]: Reload requested from client PID 4354 ('switch-to-confi')...2198machine # [ 63.157071] systemd[589]: Reloading...2199machine # [ 63.259964] systemd[589]: Reloading finished in 103 ms.2200machine # [ 63.276174] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2201machine # [ 63.286046] systemd[589]: Stopped Run user-specific NixOS activation.2202machine # [ 63.288441] systemd[589]: Stopping Run user-specific NixOS activation...2203machine # [ 63.290461] systemd[589]: Starting Run user-specific NixOS activation...2204machine # [ 63.317927] systemd[589]: Finished Run user-specific NixOS activation.2205machine # [ 63.320829] systemd[589]: Reload requested from client PID 4354 ('switch-to-confi')...2206machine # [ 63.322116] systemd[589]: Reloading...2207machine # [ 63.429621] systemd[589]: Reloading finished in 106 ms.2208machine # [ 63.444166] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2209machine # [ 63.452722] systemd[589]: Stopped migrated.service.2210machine # [ 63.454821] systemd[589]: Stopping migrated.service...2211machine # [ 63.456758] systemd[589]: Starting migrated.service...2212machine # [ 63.481880] systemd[589]: Finished migrated.service.2213machine # [ 63.488416] systemd[1]: Stopped target Reactivate sysinit units.2214machine # [ 63.495136] systemd[1]: Stopping Reactivate sysinit units...2215machine # [ 63.498272] systemd[1]: Reached target Reactivate sysinit units.2216machine # [ 63.499222] systemd[1]: Reached target Local File Systems.2217machine # [ 63.508196] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2218machine # [ 63.509764] systemd[1]: Starting Create SUID/SGID Wrappers...2219machine # [ 63.510694] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2220machine # [ 63.511927] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672221machine # [ 63.525879] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2222machine # [ 63.527655] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2223machine # [ 63.532181] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2224machine # [ 63.533451] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2225machine # [ 63.534803] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2226machine # [ 63.536457] systemd[1]: Reached target Remote File Systems.2227machine # [ 63.674622] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2228machine # [ 63.679518] systemd[1]: Finished Create SUID/SGID Wrappers.2229machine # [ 63.681715] systemd[1]: Starting linger-users.service...2230machine # [ 63.719316] systemd[1]: linger-users.service: Deactivated successfully.2231machine # [ 63.722029] systemd[1]: Finished linger-users.service.2232machine # [ 63.977140] nixos[4285]: finished switching to system configuration /nix/store/j9xg8w0x2mp6qp2r6439vvic6zh4a6gy-nixos-system-machine-test2233machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.86 seconds)2234machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2235machine # [ 64.018909] sudo[4428]: 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.service2236machine # [ 64.033912] sudo[4428]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2237machine # [ 64.046804] sudo[4428]: pam_unix(sudo:session): session closed for user usertest2238machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2239machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2240machine # [ 64.079157] sudo[4434]: 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.service2241machine # [ 64.090999] sudo[4434]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2242machine # [ 64.109312] sudo[4434]: pam_unix(sudo:session): session closed for user usertest2243machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)2244machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2245machine # [ 64.136314] sudo[4440]: 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-owner2246machine # [ 64.145962] sudo[4440]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2247machine # [ 64.154275] sudo[4440]: pam_unix(sudo:session): session closed for user usertest2248machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.04 seconds)2249machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12250machine # [ 64.325118] nixos[4449]: switching to system configuration /nix/store/j9xg8w0x2mp6qp2r6439vvic6zh4a6gy-nixos-system-machine-test2251machine # [ 64.327649] systemd[1]: Stopped target Local File Systems.2252machine # [ 64.329682] systemd[1]: Stopped target Remote File Systems.2253machine # [ 64.597404] systemd[1]: Reload requested from client PID 4449 ('.switch-to-conf') (unit backdoor.service)...2254machine # [ 64.599460] systemd[1]: Reloading...2255machine # [ 64.896411] systemd[1]: Reloading finished in 295 ms.2256machine # [ 64.983672] systemd[589]: Reexecution requested from client PID 4518 ('switch-to-confi')...2257machine # [ 64.987957] systemd[589]: Reexecuting.2258machine # [ 65.047410] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2259machine # [ 65.160994] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2260machine # [ 65.167969] systemd[589]: Reload requested from client PID 4518 ('switch-to-confi')...2261machine # [ 65.169541] systemd[589]: Reloading...2262machine # [ 65.273374] systemd[589]: Reloading finished in 102 ms.2263machine # [ 65.289619] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2264machine # [ 65.299422] systemd[589]: Stopped Run user-specific NixOS activation.2265machine # [ 65.300503] systemd[589]: Stopping Run user-specific NixOS activation...2266machine # [ 65.302710] systemd[589]: Starting Run user-specific NixOS activation...2267machine # [ 65.334546] systemd[589]: Finished Run user-specific NixOS activation.2268machine # [ 65.338257] systemd[1]: Stopped target Reactivate sysinit units.2269machine # [ 65.340959] systemd[1]: Stopping Reactivate sysinit units...2270machine # [ 65.341837] systemd[1]: Reached target Reactivate sysinit units.2271machine # [ 65.345346] systemd[1]: Reached target Remote File Systems.2272machine # [ 65.348188] systemd[1]: Starting linger-users.service...2273machine # [ 65.351253] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2274machine # [ 65.356771] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2275machine # [ 65.364577] systemd[1]: Reached target Local File Systems.2276machine # [ 65.368483] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2277machine # [ 65.373906] systemd[1]: Starting Create SUID/SGID Wrappers...2278machine # [ 65.374906] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2279machine # [ 65.381099] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672280machine # [ 65.383554] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2281machine # [ 65.392391] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2282machine # [ 65.394456] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2283machine # [ 65.434248] systemd[1]: linger-users.service: Deactivated successfully.2284machine # [ 65.437158] systemd[1]: Finished linger-users.service.2285machine # [ 65.571875] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2286machine # [ 65.574003] systemd[1]: Finished Create SUID/SGID Wrappers.2287machine # [ 65.830070] nixos[4449]: finished switching to system configuration /nix/store/j9xg8w0x2mp6qp2r6439vvic6zh4a6gy-nixos-system-machine-test2288machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.68 seconds)2289machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd2290machine # [ 65.873265] sudo[4586]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd2291machine # [ 65.889389] sudo[4586]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2292machine # [ 65.900110] sudo[4586]: pam_unix(sudo:session): session closed for user usertest2293machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.07 seconds)2294machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2295machine # [ 65.934565] sudo[4592]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2296machine # [ 65.945951] sudo[4592]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2297machine # [ 65.954421] systemd[589]: Reload requested from client PID 4597 ('systemctl')...2298machine # [ 65.956841] systemd[589]: Reloading...2299machine # [ 66.065997] systemd[589]: Reloading finished in 107 ms.2300machine # [ 66.080237] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2301machine # [ 66.094041] sudo[4592]: pam_unix(sudo:session): session closed for user usertest2302machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)2303machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&12304machine # [ 66.269896] nixos[4605]: switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test2305machine # [ 66.272746] systemd[1]: Stopped target Local File Systems.2306machine # [ 66.275028] systemd[1]: Stopped target Remote File Systems.2307machine # [ 66.537972] systemd[1]: Reload requested from client PID 4605 ('.switch-to-conf') (unit backdoor.service)...2308machine # [ 66.539609] systemd[1]: Reloading...2309machine # [ 66.839803] systemd[1]: Reloading finished in 298 ms.2310machine # [ 66.923833] systemd[589]: Stopped migrated.service.2311machine # [ 66.925757] systemd[589]: Reexecution requested from client PID 4674 ('switch-to-confi')...2312machine # [ 66.929518] systemd[589]: Reexecuting.2313machine # [ 66.975059] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2314machine # [ 67.083106] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2315machine # [ 67.090097] systemd[589]: Reload requested from client PID 4674 ('switch-to-confi')...2316machine # [ 67.093815] systemd[589]: Reloading...2317machine # [ 67.197142] systemd[589]: Reloading finished in 104 ms.2318machine # [ 67.212168] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2319machine # [ 67.222324] systemd[589]: Stopped Run user-specific NixOS activation.2320machine # [ 67.224711] systemd[589]: Stopping Run user-specific NixOS activation...2321machine # [ 67.226840] systemd[589]: Starting Run user-specific NixOS activation...2322machine # [ 67.259691] systemd[589]: Finished Run user-specific NixOS activation.2323machine # [ 67.263704] systemd[1]: Stopped target Reactivate sysinit units.2324machine # [ 67.265700] systemd[1]: Stopping Reactivate sysinit units...2325machine # [ 67.266566] systemd[1]: Reached target Reactivate sysinit units.2326machine # [ 67.270927] systemd[1]: Reached target Local File Systems.2327machine # [ 67.271803] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2328machine # [ 67.280781] systemd[1]: Starting Create SUID/SGID Wrappers...2329machine # [ 67.281666] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2330machine # [ 67.282969] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672331machine # [ 67.292956] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2332machine # [ 67.294693] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2333machine # [ 67.295979] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2334machine # [ 67.302749] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2335machine # [ 67.304476] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2336machine # [ 67.306880] systemd[1]: Reached target Remote File Systems.2337machine # [ 67.453971] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2338machine # [ 67.459054] systemd[1]: Finished Create SUID/SGID Wrappers.2339machine # [ 67.461213] systemd[1]: Starting linger-users.service...2340machine # [ 67.500708] systemd[1]: linger-users.service: Deactivated successfully.2341machine # [ 67.503424] systemd[1]: Finished linger-users.service.2342machine # [ 67.758687] nixos[4605]: finished switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test2343machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.67 seconds)2344machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2345machine # [ 67.800997] sudo[4742]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2346machine # [ 67.815685] sudo[4742]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2347machine # [ 67.826709] sudo[4742]: pam_unix(sudo:session): session closed for user usertest2348machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)2349machine: must succeed: sudo -u usertest cp /nix/store/c6j9pbdm0kjdchl4w0f5sxmdx7bag841-migrated.service ~usertest/.config/systemd/user/migrated.service2350machine # [ 67.860732] sudo[4748]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/c6j9pbdm0kjdchl4w0f5sxmdx7bag841-migrated.service /home/usertest/.config/systemd/user/migrated.service2351machine # [ 67.872855] sudo[4748]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2352machine # [ 67.881334] sudo[4748]: pam_unix(sudo:session): session closed for user usertest2353machine: (finished: must succeed: sudo -u usertest cp /nix/store/c6j9pbdm0kjdchl4w0f5sxmdx7bag841-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)2354machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2355machine # [ 67.911844] sudo[4754]: 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.service2356machine # [ 67.923498] sudo[4754]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2357machine # [ 67.933319] sudo[4754]: pam_unix(sudo:session): session closed for user usertest2358machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)2359machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2360machine # [ 67.965440] sudo[4760]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2361machine # [ 67.976629] sudo[4760]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2362machine # [ 67.985120] systemd[589]: Reload requested from client PID 4765 ('systemctl')...2363machine # [ 67.989553] systemd[589]: Reloading...2364machine # [ 68.103591] systemd[589]: Reloading finished in 115 ms.2365machine # [ 68.120225] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2366machine # [ 68.131121] sudo[4760]: pam_unix(sudo:session): session closed for user usertest2367machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.20 seconds)2368machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2369machine # [ 68.161477] sudo[4770]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2370machine # [ 68.171663] sudo[4770]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2371machine # [ 68.186027] systemd[589]: Starting migrated.service...2372machine # [ 68.216308] systemd[589]: Finished migrated.service.2373machine # [ 68.220585] sudo[4770]: pam_unix(sudo:session): session closed for user usertest2374machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)2375machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2376machine # [ 68.251111] sudo[4778]: 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.service2377machine # [ 68.261563] sudo[4778]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2378machine # [ 68.273227] sudo[4778]: pam_unix(sudo:session): session closed for user usertest2379machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)2380machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2381machine # [ 68.303468] sudo[4784]: 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-owner2382machine # [ 68.314650] sudo[4784]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2383machine # [ 68.322970] sudo[4784]: pam_unix(sudo:session): session closed for user usertest2384machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2385machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2386machine # [ 68.350629] sudo[4790]: 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.service2387machine # [ 68.361013] sudo[4790]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2388machine # [ 68.377558] sudo[4790]: pam_unix(sudo:session): session closed for user usertest2389machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)2390machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12391machine # [ 68.556766] nixos[4799]: switching to system configuration /nix/store/j9xg8w0x2mp6qp2r6439vvic6zh4a6gy-nixos-system-machine-test2392machine # [ 68.559668] systemd[1]: Stopped target Local File Systems.2393machine # [ 68.562092] systemd[1]: Stopped target Remote File Systems.2394machine # [ 68.833135] systemd[1]: Reload requested from client PID 4799 ('.switch-to-conf') (unit backdoor.service)...2395machine # [ 68.837066] systemd[1]: Reloading...2396machine # [ 69.153167] systemd[1]: Reloading finished in 315 ms.2397machine # [ 69.240461] systemd[589]: Reexecution requested from client PID 4868 ('switch-to-confi')...2398machine # [ 69.243906] systemd[589]: Reexecuting.2399machine # [ 69.303520] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2400machine # [ 69.417749] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2401machine # [ 69.424953] systemd[589]: Reload requested from client PID 4868 ('switch-to-confi')...2402machine # [ 69.426319] systemd[589]: Reloading...2403machine # [ 69.533475] systemd[589]: Reloading finished in 105 ms.2404machine # [ 69.549868] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2405machine # [ 69.557918] systemd[589]: Stopped Run user-specific NixOS activation.2406machine # [ 69.560144] systemd[589]: Stopping Run user-specific NixOS activation...2407machine # [ 69.562768] systemd[589]: Starting Run user-specific NixOS activation...2408machine # [ 69.595993] systemd[589]: Finished Run user-specific NixOS activation.2409machine # [ 69.598672] systemd[589]: Reload requested from client PID 4868 ('switch-to-confi')...2410machine # [ 69.602456] systemd[589]: Reloading...2411machine # [ 69.712347] systemd[589]: Reloading finished in 111 ms.2412machine # [ 69.728364] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2413machine # [ 69.742446] systemd[1]: Stopped target Reactivate sysinit units.2414machine # [ 69.744941] systemd[1]: Stopping Reactivate sysinit units...2415machine # [ 69.745953] systemd[1]: Reached target Reactivate sysinit units.2416machine # [ 69.751353] systemd[1]: Reached target Remote File Systems.2417machine # [ 69.755461] systemd[1]: Starting linger-users.service...2418machine # [ 69.766044] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2419machine # [ 69.767722] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2420machine # [ 69.770706] systemd[1]: Reached target Local File Systems.2421machine # [ 69.776077] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2422machine # [ 69.787198] systemd[1]: Starting Create SUID/SGID Wrappers...2423machine # [ 69.794508] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2424machine # [ 69.795878] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672425machine # [ 69.807137] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2426machine # [ 69.811856] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2427machine # [ 69.813719] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2428machine # [ 69.854453] systemd[1]: linger-users.service: Deactivated successfully.2429machine # [ 69.858078] systemd[1]: Finished linger-users.service.2430machine # [ 69.994586] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2431machine # [ 69.998065] systemd[1]: Finished Create SUID/SGID Wrappers.2432machine # [ 70.252359] nixos[4799]: finished switching to system configuration /nix/store/j9xg8w0x2mp6qp2r6439vvic6zh4a6gy-nixos-system-machine-test2433machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.88 seconds)2434machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2435machine # [ 70.297335] sudo[4940]: 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.service2436machine # [ 70.313504] sudo[4940]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2437machine # [ 70.335344] sudo[4940]: pam_unix(sudo:session): session closed for user usertest2438machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds)2439machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2440machine # [ 70.370940] sudo[4946]: 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-owner2441machine # [ 70.382046] sudo[4946]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2442machine # [ 70.390610] sudo[4946]: pam_unix(sudo:session): session closed for user usertest2443machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2444machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd2445machine # [ 70.418518] sudo[4952]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd2446machine # [ 70.428973] sudo[4952]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2447machine # [ 70.436645] sudo[4952]: pam_unix(sudo:session): session closed for user usertest2448machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.05 seconds)2449machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2450machine # [ 70.463957] sudo[4958]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2451machine # [ 70.474016] sudo[4958]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2452machine # [ 70.482028] systemd[589]: Reload requested from client PID 4963 ('systemctl')...2453machine # [ 70.486071] systemd[589]: Reloading...2454machine # [ 70.595489] systemd[589]: Reloading finished in 110 ms.2455machine # [ 70.608173] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2456machine # [ 70.621148] sudo[4958]: pam_unix(sudo:session): session closed for user usertest2457machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.18 seconds)2458machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service2459machine # [ 70.650274] sudo[4968]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user stop migrated.service2460machine # [ 70.660530] sudo[4968]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2461machine # [ 70.671990] systemd[589]: Stopped migrated.service.2462machine # [ 70.676067] sudo[4968]: pam_unix(sudo:session): session closed for user usertest2463machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.05 seconds)2464machine: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&12465machine # [ 70.855869] nixos[4977]: switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test2466machine # [ 70.859007] systemd[1]: Stopped target Remote File Systems.2467machine # [ 70.861397] systemd[1]: Stopped target Local File Systems.2468machine # [ 71.142977] systemd[1]: Reload requested from client PID 4977 ('.switch-to-conf') (unit backdoor.service)...2469machine # [ 71.145450] systemd[1]: Reloading...2470machine # [ 71.463795] systemd[1]: Reloading finished in 317 ms.2471machine # [ 71.551938] systemd[589]: Reexecution requested from client PID 5046 ('switch-to-confi')...2472machine # [ 71.555727] systemd[589]: Reexecuting.2473machine # [ 71.603225] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2474machine # [ 71.715628] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2475machine # [ 71.722707] systemd[589]: Reload requested from client PID 5046 ('switch-to-confi')...2476machine # [ 71.726756] systemd[589]: Reloading...2477machine # [ 71.834515] systemd[589]: Reloading finished in 109 ms.2478machine # [ 71.852198] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2479machine # [ 71.863239] systemd[589]: Stopped Run user-specific NixOS activation.2480machine # [ 71.865834] systemd[589]: Stopping Run user-specific NixOS activation...2481machine # [ 71.868077] systemd[589]: Starting Run user-specific NixOS activation...2482machine # [ 71.902063] systemd[589]: Finished Run user-specific NixOS activation.2483machine # [ 71.906907] systemd[1]: Stopped target Reactivate sysinit units.2484machine # [ 71.908962] systemd[1]: Stopping Reactivate sysinit units...2485machine # [ 71.909898] systemd[1]: Reached target Reactivate sysinit units.2486machine # [ 71.914617] systemd[1]: Reached target Local File Systems.2487machine # [ 71.920292] systemd[1]: Reached target Remote File Systems.2488machine # [ 71.924502] systemd[1]: Starting linger-users.service...2489machine # [ 71.929320] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2490machine # [ 71.930816] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2491machine # [ 71.940726] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2492machine # [ 71.947810] systemd[1]: Starting Create SUID/SGID Wrappers...2493machine # [ 71.948954] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2494machine # [ 71.950389] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672495machine # [ 71.959962] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2496machine # [ 71.961802] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2497machine # [ 71.964463] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2498machine # [ 72.020638] systemd[1]: linger-users.service: Deactivated successfully.2499machine # [ 72.024999] systemd[1]: Finished linger-users.service.2500machine # [ 72.174728] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2501machine # [ 72.178026] systemd[1]: Finished Create SUID/SGID Wrappers.2502machine # [ 72.434075] nixos[4977]: finished switching to system configuration /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test2503machine: (finished: must succeed: /nix/store/dy691hjp8wr8ivrs5sgcicgb4bq39ddg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.76 seconds)2504(finished: subtest: user services, in 49.16 seconds)2505(finished: run the VM test script, in 73.21 seconds)2506test script finished in 73.39s2507cleanup2508kill QemuMachine (pid 45)2509machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lq2fsnmg0q3y0vlxn91w8s8wvj1m6fld-python3-3.14.8/bin/python3.14)2510machine # [2026-10-21T03:01:50Z INFO virtiofsd] Client disconnected, shutting down2511machine # [2026-10-21T03:01:50Z INFO virtiofsd] Client disconnected, shutting down2512machine # [2026-10-21T03:01:50Z INFO virtiofsd] Client disconnected, shutting down2513(finished: cleanup, in 0.16 seconds)2514additionally exposed symbols:2515 machine, other,2516 vlan1,2517 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