vm-test-run-switch-test-user
checks.aarch64-linux.switchTest-user
· build #31
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7machine: waiting for unit multi-user.target8machine: waiting for the VM to finish booting9machine: starting vm10machine # Disk image does not exist, creating the virtualisation disk image...11machine: QEMU running (pid 45)12machine # Formatting '/build/vm-state-machine/tmp.oL35NqkeAC', 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: 7b5cf406-d577-4923-86ce-8644149f7c3617machine # 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 # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]27machine # [ 0.000000] Linux version 6.18.50 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 7 15:23:00 UTC 202628machine # [ 0.000000] KASLR enabled29machine # [ 0.000000] random: crng init done30machine # [ 0.000000] Machine model: linux,dummy-virt31machine # [ 0.000000] efi: UEFI not found.32machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT33machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]34machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]35machine # [ 0.000000] Zone ranges:36machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]37machine # [ 0.000000] DMA32 empty38machine # [ 0.000000] Normal empty39machine # [ 0.000000] Device empty40machine # [ 0.000000] Movable zone start for each node41machine # [ 0.000000] Early memory node ranges42machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]43machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]44machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000045machine # [ 0.000000] psci: probing for conduit method from DT.46machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.47machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs48machine # [ 0.000000] psci: Trusted OS migration not required49machine # [ 0.000000] psci: SMC Calling Convention v1.150machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)51machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u31129652machine # [ 0.000000] Detected PIPT I-cache on CPU053machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)54machine # [ 0.000000] CPU features: detected: GICv3 CPU interface55machine # [ 0.000000] CPU features: detected: Spectre-v456machine # [ 0.000000] CPU features: detected: Spectre-BHB57machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_3858machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_2359machine # [ 0.000000] alternatives: applying boot alternatives60machine # [ 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/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/init regInfo=/nix/store/xws75d06h0xdmz5g3fn2467sdxyj4y79-closure-info/registration console=ttyAMA0,115200n8 console=tty061machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/xws75d06h0xdmz5g3fn2467sdxyj4y79-closure-info/registration", will be passed to user space.62machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes63machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)64machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)65machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB66machine # [ 0.000000] software IO TLB: area num 1.67machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)68machine # [ 0.000000] Fallback order for Node 0: 069machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214470machine # [ 0.000000] Policy zone: DMA71machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off72machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=173machine # [ 0.000000] allocated 2097152 bytes of page_ext74machine # [ 0.000000] ftrace: allocating 74885 entries in 294 pages75machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups76machine # [ 0.000000] rcu: Hierarchical RCU implementation.77machine # [ 0.000000] rcu: RCU event tracing is enabled.78machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.79machine # [ 0.000000] Trampoline variant of Tasks RCU enabled.80machine # [ 0.000000] Rude variant of Tasks RCU enabled.81machine # [ 0.000000] Tracing variant of Tasks RCU enabled.82machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.83machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=184machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.85machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.86machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.87machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 088machine # [ 0.000000] GICv3: 256 SPIs implemented89machine # [ 0.000000] GICv3: 0 Extended SPIs implemented90machine # [ 0.000000] Root IRQ handler: gic_handle_irq91machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI92machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=093machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a000094machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]95machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)96machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)97machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d1000098machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d2000099machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.100machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns101machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).102machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns103machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns104machine # [ 0.000031] arm-pv: using stolen time PV105machine # [ 0.000387] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)106machine # [ 0.000542] Console: colour dummy device 80x25107machine # [ 0.000550] printk: legacy console [tty0] enabled108machine # [ 0.000732] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)109machine # [ 0.000739] pid_max: default: 32768 minimum: 301110machine # [ 0.000809] LSM: initializing lsm=capability,landlock,yama,bpf,ima111machine # [ 0.000946] landlock: Up and running.112machine # [ 0.000949] Yama: becoming mindful.113machine # [ 0.001388] LSM support for eBPF active114machine # [ 0.001515] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)115machine # [ 0.001539] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)116machine # [ 0.002596] cacheinfo: Unable to detect cache hierarchy for CPU 0117machine # [ 0.003322] rcu: Hierarchical SRCU implementation.118machine # [ 0.003326] rcu: Max phase no-delay instances is 1000.119machine # [ 0.004532] fsl-mc MSI: its@8080000 domain created120machine # [ 0.004623] EFI services will not be available.121machine # [ 0.004693] smp: Bringing up secondary CPUs ...122machine # [ 0.004702] smp: Brought up 1 node, 1 CPU123machine # [ 0.004705] SMP: Total of 1 processors activated.124machine # [ 0.004708] CPU: All CPU(s) started at EL1125machine # [ 0.004721] CPU features: detected: Branch Target Identification126machine # [ 0.004726] CPU features: detected: ARMv8.4 Translation Table Level127machine # [ 0.004729] CPU features: detected: Instruction cache invalidation not required for I/D coherence128machine # [ 0.004733] CPU features: detected: Data cache clean to the PoU not required for I/D coherence129machine # [ 0.004737] CPU features: detected: Common not Private translations130machine # [ 0.004740] CPU features: detected: CRC32 instructions131machine # [ 0.004742] CPU features: detected: Data cache clean to Point of Deep Persistence132machine # [ 0.004746] CPU features: detected: Data cache clean to Point of Persistence133machine # [ 0.004749] CPU features: detected: Data independent timing control (DIT)134machine # [ 0.004752] CPU features: detected: E0PD135machine # [ 0.004754] CPU features: detected: Enhanced Counter Virtualization136machine # [ 0.004758] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)137machine # [ 0.004761] CPU features: detected: Enhanced Virtualization Traps138machine # [ 0.004764] CPU features: detected: Fine Grained Traps139machine # [ 0.004768] CPU features: detected: Generic authentication (architected QARMA5 algorithm)140machine # [ 0.004773] CPU features: detected: RCpc load-acquire (LDAPR)141machine # [ 0.004776] CPU features: detected: LSE atomic instructions142machine # [ 0.004779] CPU features: detected: Privileged Access Never143machine # [ 0.004781] CPU features: detected: PMUv3144machine # [ 0.004784] CPU features: detected: RAS Extension Support145machine # [ 0.004787] CPU features: detected: RASv1p1 Extension Support146machine # [ 0.004790] CPU features: detected: Random Number Generator147machine # [ 0.004792] CPU features: detected: Speculation barrier (SB)148machine # [ 0.004795] CPU features: detected: Stage-2 Force Write-Back149machine # [ 0.004798] CPU features: detected: TLB range maintenance instructions150machine # [ 0.004803] CPU features: detected: Speculative Store Bypassing Safe (SSBS)151machine # [ 0.004841] alternatives: applying system-wide alternatives152machine # [ 0.007844] CPU features: detected: BBM Level 2 without TLB conflict abort153machine # [ 0.008027] Memory: 894652K/1048576K available (24448K kernel code, 7090K rwdata, 26348K rodata, 4736K init, 1107K bss, 112608K reserved, 32768K cma-reserved)154machine # [ 0.008418] devtmpfs: initialized155machine # [ 0.010077] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)156machine # [ 0.010098] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).157machine # [ 0.010282] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL158machine # [ 0.010287] 0 pages in range for non-PLT usage159machine # [ 0.010288] 508288 pages in range for PLT usage160machine # [ 0.010400] pinctrl core: initialized pinctrl subsystem161machine # [ 0.011138] DMI not present or invalid.162machine # [ 0.014191] NET: Registered PF_NETLINK/PF_ROUTE protocol family163machine # [ 0.016437] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations164machine # [ 0.016600] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations165machine # [ 0.016759] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations166machine # [ 0.016782] audit: initializing netlink subsys (disabled)167machine # [ 0.017324] thermal_sys: Registered thermal governor 'fair_share'168machine # [ 0.017325] thermal_sys: Registered thermal governor 'bang_bang'169machine # [ 0.017329] thermal_sys: Registered thermal governor 'step_wise'170machine # [ 0.017331] thermal_sys: Registered thermal governor 'user_space'171machine # [ 0.017336] thermal_sys: Registered thermal governor 'power_allocator'172machine # [ 0.017358] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1173machine # [ 0.017368] cpuidle: using governor ladder174machine # [ 0.017373] cpuidle: using governor menu175machine # [ 0.017587] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.176machine # [ 0.017602] ASID allocator initialised with 65536 entries177machine # [ 0.018718] Serial: AMBA PL011 UART driver178machine # [ 0.023759] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1179machine # [ 0.023898] printk: console [ttyAMA0] enabled180machine # [ 0.147625] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages181machine # [ 0.147646] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page182machine # [ 0.147651] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages183machine # [ 0.147655] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page184machine # [ 0.147660] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages185machine # [ 0.147664] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page186machine # [ 0.147668] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages187machine # [ 0.147672] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page188machine # [ 0.155401] fbcon: Taking over console189machine # [ 0.155418] ACPI: Interpreter disabled.190machine # [ 0.157314] iommu: Default domain type: Translated191machine # [ 0.157324] iommu: DMA domain TLB invalidation policy: strict mode192machine # [ 0.158990] SCSI subsystem initialized193machine # [ 0.164145] usbcore: registered new interface driver usbfs194machine # [ 0.164182] usbcore: registered new interface driver hub195machine # [ 0.164200] usbcore: registered new device driver usb196machine # [ 0.164452] pps_core: LinuxPPS API ver. 1 registered197machine # [ 0.164458] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>198machine # [ 0.164467] PTP clock support registered199machine # [ 0.164513] EDAC MC: Ver: 3.0.0200machine # [ 0.169179] scmi_core: SCMI protocol bus registered201machine # [ 0.170161] FPGA manager framework202machine # [ 0.171147] vgaarb: loaded203machine # [ 0.171783] clocksource: Switched to clocksource arch_sys_counter204machine # [ 0.176787] VFS: Disk quotas dquot_6.6.0205machine # [ 0.176824] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)206machine # [ 0.180559] netfs: FS-Cache loaded207machine # [ 0.180696] pnp: PnP ACPI: disabled208machine # [ 0.184555] NET: Registered PF_INET protocol family209machine # [ 0.184710] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)210machine # [ 0.215068] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)211machine # [ 0.215120] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)212machine # [ 0.215148] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)213machine # [ 0.215195] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)214machine # [ 0.215271] TCP: Hash tables configured (established 8192 bind 8192)215machine # [ 0.215359] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)216machine # [ 0.215417] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)217machine # [ 0.215444] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)218machine # [ 0.215523] NET: Registered PF_UNIX/PF_LOCAL protocol family219machine # [ 0.215544] NET: Registered PF_XDP protocol family220machine # [ 0.215566] PCI: CLS 0 bytes, default 64221machine # [ 0.215852] Trying to unpack rootfs image as initramfs...222machine # [ 0.233664] kvm [1]: HYP mode not available223machine # [ 0.329903] Initialise system trusted keyrings224machine # [ 0.330670] workingset: timestamp_bits=42 max_order=18 bucket_order=0225machine # [ 0.331970] squashfs: version 4.0 (2009/01/31) Phillip Lougher226machine # [ 0.332760] 9p: Installing v9fs 9p2000 file system support227machine # [ 0.356716] Key type asymmetric registered228machine # [ 0.356750] Asymmetric key parser 'x509' registered229machine # [ 0.356824] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)230machine # [ 0.359013] io scheduler mq-deadline registered231machine # [ 0.359024] io scheduler kyber registered232machine # [ 0.372085] pl061_gpio 9030000.pl061: PL061 GPIO chip registered233machine # [ 0.373547] ledtrig-cpu: registered to indicate activity on CPUs234machine # [ 0.373933] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:235machine # [ 0.373951] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000236machine # [ 0.373963] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000237machine # [ 0.373971] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000238machine # [ 0.373992] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits239machine # [ 0.374015] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]240machine # [ 0.374093] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00241machine # [ 0.374102] pci_bus 0000:00: root bus resource [bus 00-ff]242machine # [ 0.374109] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]243machine # [ 0.374114] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]244machine # [ 0.374120] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]245machine # [ 0.374177] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint246machine # [ 0.374627] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint247machine # [ 0.374816] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]248machine # [ 0.374832] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]249machine # [ 0.374861] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]250machine # [ 0.374879] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]251machine # [ 0.375333] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint252machine # [ 0.375513] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]253machine # [ 0.375529] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]254machine # [ 0.375558] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]255machine # [ 0.376053] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint256machine # [ 0.376237] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]257machine # [ 0.376253] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]258machine # [ 0.376282] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]259machine # [ 0.376732] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint260machine # [ 0.376924] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]261machine # [ 0.376941] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]262machine # [ 0.376970] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]263machine # [ 0.377423] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint264machine # [ 0.377604] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]265machine # [ 0.377619] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]266machine # [ 0.377648] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]267machine # [ 0.378108] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint268machine # [ 0.378288] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]269machine # [ 0.378304] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]270machine # [ 0.378334] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]271machine # [ 0.378776] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint272machine # [ 0.378957] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]273machine # [ 0.378972] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]274machine # [ 0.379001] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275machine # [ 0.379017] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]276machine # [ 0.379472] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint277machine # [ 0.379661] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]278machine # [ 0.379691] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279machine # [ 0.424258] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint280machine # [ 0.424470] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.424500] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.424890] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint283machine # [ 0.425071] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]284machine # [ 0.425313] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint285machine # [ 0.425603] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]286machine # [ 0.425620] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]287machine # [ 0.425649] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]288machine # [ 0.426092] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint289machine # [ 0.426270] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]290machine # [ 0.426286] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]291machine # [ 0.426314] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]292machine # [ 0.426901] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned293machine # [ 0.426913] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned294machine # [ 0.426919] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned295machine # [ 0.426967] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned296machine # [ 0.427014] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned297machine # [ 0.427062] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned298machine # [ 0.427108] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned299machine # [ 0.427154] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned300machine # [ 0.427202] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned301machine # [ 0.427250] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned302machine # [ 0.427296] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned303machine # [ 0.427341] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned304machine # [ 0.427416] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned305machine # [ 0.427461] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned306machine # [ 0.427482] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned307machine # [ 0.427503] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned308machine # [ 0.427524] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned309machine # [ 0.427546] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned310machine # [ 0.427570] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned311machine # [ 0.427592] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned312machine # [ 0.427613] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned313machine # [ 0.427634] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned314machine # [ 0.427656] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned315machine # [ 0.427678] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned316machine # [ 0.427700] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned317machine # [ 0.427720] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned318machine # [ 0.427741] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned319machine # [ 0.427762] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned320machine # [ 0.427782] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned321machine # [ 0.472028] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned322machine # [ 0.472054] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned323machine # [ 0.472077] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned324machine # [ 0.472099] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned325machine # [ 0.472122] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned326machine # [ 0.472156] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]327machine # [ 0.472166] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]328machine # [ 0.472171] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]329machine # [ 0.473330] pci 0000:00:0a.0: enabling device (0000 -> 0002)330machine # [ 0.500340] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)331machine # [ 0.502485] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)332machine # [ 0.512443] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)333machine # [ 0.514898] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)334machine # [ 0.525605] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)335machine # [ 0.528653] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)336machine # [ 0.530752] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)337machine # [ 0.541298] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)338machine # [ 0.543676] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)339machine # [ 0.553765] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)340machine # [ 0.556880] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)341machine # [ 0.569984] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled342machine # [ 0.572533] msm_serial: driver initialized343machine # [ 0.572672] SuperH (H)SCI(F) driver initialized344machine # [ 0.572726] STM32 USART driver initialized345machine # [ 0.606788] loop: module loaded346machine # [ 0.606990] virtio_blk virtio5: 1/0/0 default/read/poll queues347machine # [ 0.607771] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)348machine # [ 0.612459] megasas: 07.734.00.00-rc1349machine # [ 0.613166] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]350machine # [ 0.625020] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000351machine # [ 0.625067] Intel/Sharp Extended Query Table at 0x0031352machine # [ 0.626830] Using buffer write method353machine # [ 0.626933] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]354machine # [ 0.640508] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000355machine # [ 0.640550] Intel/Sharp Extended Query Table at 0x0031356machine # [ 0.642547] Using buffer write method357machine # [ 0.642584] Concatenating MTD devices:358machine # [ 0.642588] (0): "0.flash"359machine # [ 0.642592] (1): "0.flash"360machine # [ 0.642595] into device "0.flash"361machine # [ 0.909690] Freeing initrd memory: 25748K362machine # [ 0.915672] tun: Universal TUN/TAP device driver, 1.6363machine # [ 0.919637] thunder_xcv, ver 1.0364machine # [ 0.919690] thunder_bgx, ver 1.0365machine # [ 0.919710] nicpf, ver 1.0366machine # [ 0.921529] e1000: Intel(R) PRO/1000 Network Driver367machine # [ 0.921543] e1000: Copyright (c) 1999-2006 Intel Corporation.368machine # [ 0.921569] e1000e: Intel(R) PRO/1000 Network Driver369machine # [ 0.921581] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.370machine # [ 0.921605] igb: Intel(R) Gigabit Ethernet Network Driver371machine # [ 0.921611] igb: Copyright (c) 2007-2014 Intel Corporation.372machine # [ 0.921631] igbvf: Intel(R) Gigabit Virtual Function Network Driver373machine # [ 0.921637] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.374machine # [ 0.921767] sky2: driver version 1.30375machine # [ 0.923321] usbcore: registered new interface driver usb-storage376machine # [ 0.923418] usbcore: registered new interface driver usbserial_generic377machine # [ 0.923432] usbserial: USB Serial support registered for generic378machine # [ 0.924516] ehci-pci 0000:00:0a.0: EHCI Host Controller379machine # [ 0.924554] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1380machine # [ 0.924770] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000381machine # [ 0.935724] hv_vmbus: registering driver hyperv_keyboard382machine # [ 0.935843] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00383machine # [ 0.936190] hub 1-0:1.0: USB hub found384machine # [ 0.936212] hub 1-0:1.0: 6 ports detected385machine # [ 0.939685] rtc-pl031 9010000.pl031: registered as rtc0386machine # [ 0.939713] rtc-pl031 9010000.pl031: setting system clock to 2026-09-10T02:43:10 UTC (1789008190)387machine # [ 0.941762] i2c_dev: i2c /dev entries driver388machine # [ 0.944942] sdhci: Secure Digital Host Controller Interface driver389machine # [ 0.944957] sdhci: Copyright(c) Pierre Ossman390machine # [ 0.945218] Synopsys Designware Multimedia Card Interface Driver391machine # [ 0.945576] sdhci-pltfm: SDHCI platform and OF driver helper392machine # [ 0.947229] hid: raw HID events driver (C) Jiri Kosina393machine # [ 0.947464] usbcore: registered new interface driver usbhid394machine # [ 0.947471] usbhid: USB HID core driver395machine # [ 0.953118] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available396machine # [ 0.954672] drop_monitor: Initializing network drop monitor service397machine # [ 0.954838] NET: Registered PF_INET6 protocol family398machine # [ 0.957909] Segment Routing with IPv6399machine # [ 0.957933] In-situ OAM (IOAM) with IPv6400machine # [ 0.957962] NET: Registered PF_PACKET protocol family401machine # [ 0.959599] 9pnet: Installing 9P2000 support402machine # [ 0.962513] Key type dns_resolver registered403machine # [ 0.968686] registered taskstats version 1404machine # [ 0.968849] Loading compiled-in X.509 certificates405machine # [ 0.977416] Demotion targets for Node 0: null406machine # [ 0.977528] Key type .fscrypt registered407machine # [ 0.977535] Key type fscrypt-provisioning registered408machine # [ 0.977639] ima: No TPM chip found, activating TPM-bypass!409machine # [ 0.977660] ima: Allocated hash algorithm: sha1410machine # [ 0.977682] ima: No architecture policies found411machine # [ 0.981812] input: gpio-keys as /devices/platform/gpio-keys/input/input0412machine # [ 0.999984] clk: Disabling unused clocks413machine # [ 1.000017] PM: genpd: Disabling unused power domains414machine # [ 1.004376] Freeing unused kernel memory: 4736K415machine # [ 1.004592] Run /init as init process416machine # [ 1.022470] systemd[1]: Successfully made /usr/ read-only.417machine # [ 1.183875] usb 1-1: new high-speed USB device number 2 using ehci-pci418machine # [ 1.340491] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1419machine # [ 1.357081] systemd[1]: systemd 262-2ff84cf 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)420machine # [ 1.369470] systemd[1]: Detected virtualization qemu.421machine # [ 1.371480] systemd[1]: Detected architecture arm64.422machine # [ 1.373565] systemd[1]: Running in initrd.423machine # [ 1.376451] systemd[1]: Initializing machine ID from random generator.424machine # [ 1.379359] systemd[1]: Hostname set to <machine>.425machine # [ 1.424139] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0426machine # [ 1.532016] systemd[1]: bpf-restrict-fs: LSM BPF program attached427machine # [ 1.551906] usb 1-2: new high-speed USB device number 3 using ehci-pci428machine # [ 1.587874] systemd[1]: Queued start job for default target Initrd Default Target.429machine # [ 1.597850] systemd[1]: Created slice Slice /system/modprobe.430machine # [ 1.598848] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.431machine # [ 1.599936] systemd[1]: Expecting device /dev/disk/by-label/nixos...432machine # [ 1.600768] systemd[1]: Reached target Path Units.433machine # [ 1.601384] systemd[1]: Reached target Slice Units.434machine # [ 1.602022] systemd[1]: Reached target Swaps.435machine # [ 1.602594] systemd[1]: Reached target Timer Units.436machine # [ 1.603433] systemd[1]: Listening on D-Bus System Message Bus Socket.437machine # [ 1.604511] systemd[1]: Listening on Journal Socket (/dev/log).438machine # [ 1.605394] systemd[1]: Listening on Journal Sockets.439machine # [ 1.606120] systemd[1]: Listening on udev Kernel Socket.440machine # [ 1.606819] systemd[1]: Reached target Socket Units.441machine # [ 1.609003] systemd[1]: Starting Create List of Static Device Nodes...442machine # [ 1.616234] systemd[1]: Starting Load Kernel Module 9pnet_virtio...443machine # [ 1.617140] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs444machine # [ 1.624113] systemd[1]: Mounting Kernel Configuration File System...445machine # [ 1.648085] systemd[1]: Starting Journal Service...446machine # [ 1.659393] systemd[1]: Starting Load Kernel Modules...447machine # [ 1.660462] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os448machine # [ 1.676230] systemd[1]: Starting Coldplug All udev Devices...449machine # [ 1.692006] systemd[1]: Finished Create List of Static Device Nodes.450machine # [ 1.693841] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.451machine # [ 1.701151] systemd[1]: Finished Load Kernel Module 9pnet_virtio.452machine # [ 1.702506] systemd[1]: Mounted Kernel Configuration File System.453machine # [ 1.706083] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2454machine # [ 1.706217] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0455machine # [ 1.720173] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...456machine # [ 1.730185] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.457machine # [ 1.734506] systemd-journald[73]: Collecting audit messages is disabled.458machine # [ 1.748173] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev459machine # [ 1.752966] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0460machine # [ 1.753216] [drm] features: -virgl +edid -resource_blob -host_visible461machine # [ 1.753226] [drm] features: -context_init462machine # [ 1.753938] [drm] number of scanouts: 1463machine # [ 1.753961] [drm] number of cap sets: 0464machine # [ 1.772535] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.465machine # [ 1.775143] systemd[1]: Starting Create Static Device Nodes in /dev...466machine # [ 1.788145] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic467machine # [ 1.788172] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0468machine # [ 1.816273] Console: switching to colour frame buffer device 160x50469machine # [ 1.836409] systemd[1]: Finished Create Static Device Nodes in /dev.470machine # [ 1.836783] systemd[1]: Reached target Preparation for Local File Systems.471machine # [ 1.836810] systemd[1]: Reached target Local File Systems.472machine # [ 1.840879] systemd[1]: Starting Rule-based Manager for Device Events and Files...473machine # [ 1.852432] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device474machine # [ 1.876044] systemd[1]: Finished Load Kernel Modules.475machine # [ 1.880155] systemd[1]: Starting Apply Kernel Variables...476machine # [ 1.896452] systemd-modules-load[74]: Inserted module 'dm_mod'477machine # [ 1.917475] systemd[1]: Started Journal Service.478machine # [ 1.904394] systemd-modules-load[74]: Module 'virtio_balloon' is built in479machine # [ 1.912123] systemd-modules-load[74]: Module 'virtio_console' is built in480machine # [ 1.921855] systemd-modules-load[74]: Inserted module 'virtio_gpu'481machine # [ 1.922953] systemd-modules-load[74]: Module 'virtio_rng' is built in482machine # [ 1.928828] systemd[1]: Starting Create System Files and Directories...483machine # [ 1.944212] systemd[1]: Finished Apply Kernel Variables.484machine # [ 1.946631] systemd-udevd[80]: Using default interface naming scheme 'v261'.485machine # [ 1.961324] systemd[1]: Finished Create System Files and Directories.486machine # [ 1.985687] systemd[1]: Started Rule-based Manager for Device Events and Files.487machine # [ 2.069328] systemd[1]: Starting Virtual Console Setup...488machine # [ 2.137858] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.489machine # [ 2.145376] systemd[1]: Finished Virtual Console Setup.490machine # [ 2.769651] systemd[1]: Finished Coldplug All udev Devices.491machine # [ 2.770633] systemd[1]: Reached target System Initialization.492machine # [ 2.771483] systemd[1]: Reached target Basic System.493machine # [ 2.907473] (udev-worker)[96]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.494machine # [ 2.923246] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.495machine # [ 2.932339] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.496machine # [ 2.991322] systemd[1]: Found device /dev/disk/by-label/nixos.497machine # [ 2.994904] systemd[1]: Reached target Initrd Root Device.498machine # [ 2.998649] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...499machine # [ 3.056204] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks500machine # [ 3.063142] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.501machine # [ 3.070222] systemd[1]: Mounting /sysroot...502machine # [ 3.128174] EXT4-fs (vda): mounted filesystem 7b5cf406-d577-4923-86ce-8644149f7c36 r/w with ordered data mode. Quota mode: none.503machine # [ 3.114235] systemd[1]: Mounted /sysroot.504machine # [ 3.116939] systemd[1]: Reached target Initrd Root File System.505machine # [ 3.122566] systemd[1]: Starting Mountpoints Configured in the Real Root...506machine # [ 3.156395] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.507machine # [ 3.163888] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...508machine # [ 3.168263] systemd[1]: Reloading...509machine # [ 3.368135] systemd[1]: Reloading finished in 203 ms.510machine # [ 3.398207] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...511machine # [ 3.407409] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...512machine # [ 3.412407] systemd[1]: Starting Load Kernel Module 9pnet_virtio...513machine # [ 3.416644] systemd[1]: initrd-parse-etc.service: Deactivated successfully.514machine # [ 3.420065] systemd[1]: Finished Mountpoints Configured in the Real Root.515machine # [ 3.422946] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.516machine # [ 3.439921] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.517machine # [ 3.442986] systemd[1]: Finished Load Kernel Module 9pnet_virtio.518machine # [ 3.661752] systemd[1]: Mounting /sysroot/nix/.ro-store...519machine # [ 3.679061] systemd[1]: Mounting /sysroot/nix/.rw-store...520machine # [ 3.691290] systemd[1]: Mounting /sysroot/run...521machine # [ 3.702402] systemd[1]: Mounting /sysroot/tmp/shared...522machine # [ 3.746405] systemd[1]: Mounting /sysroot/tmp/xchg...523machine # [ 3.775676] systemd[1]: Mounted /sysroot/nix/.ro-store.524machine # [ 3.786064] systemd[1]: Mounted /sysroot/nix/.rw-store.525machine # [ 3.799732] systemd[1]: Mounted /sysroot/run.526machine # [ 3.815284] systemd[1]: Mounted /sysroot/tmp/shared.527machine # [ 3.825875] systemd[1]: Starting rw-sysroot-nix-store.service...528machine # [ 3.830945] systemd[1]: Mounted /sysroot/tmp/xchg.529machine # [ 3.858734] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.530machine # [ 3.860309] systemd[1]: Finished rw-sysroot-nix-store.service.531machine # [ 4.353718] (udev-worker)[96]: mtd0ro: Failed to find and pin callout binary "/nix/store/g748j53i4yimpg27kpkbav57qnhzxzm9-systemd-262-2ff84cf/lib/udev/mtd_probe": No such file or directory532machine # [ 4.358570] (udev-worker)[96]: mtd0ro: /nix/store/g748j53i4yimpg27kpkbav57qnhzxzm9-systemd-262-2ff84cf/lib/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 directory533machine # [ 4.384419] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.534machine # [ 4.387020] systemd[1]: Stopped Virtual Console Setup.535machine # [ 4.388471] systemd[1]: Stopping Virtual Console Setup...536machine # [ 4.392271] systemd[1]: Starting Virtual Console Setup...537machine # [ 4.406239] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.538machine # [ 4.407396] systemd[1]: Stopped Virtual Console Setup.539machine # [ 4.408719] systemd[1]: Starting Virtual Console Setup...540machine # [ 4.431389] systemd-vconsole-setup[155]: Configuration of first virtual console was skipped, ignoring remaining ones.541machine # [ 4.435182] systemd[1]: Finished Virtual Console Setup.542machine # [ 4.665208] systemd[1]: Mounting /sysroot/nix/store...543machine # [ 4.730956] systemd[1]: Mounted /sysroot/nix/store.544machine # [ 4.736419] systemd[1]: Reached target Initrd File Systems.545machine # [ 4.742339] systemd[1]: Starting Find NixOS closure...546machine # [ 4.752446] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...547machine # [ 4.796330] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.548machine # [ 4.799737] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.549machine # [ 4.817011] systemd[1]: Finished Find NixOS closure.550machine # [ 4.820270] systemd[1]: Reached target Initrd Default Target.551machine # [ 4.823629] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...552machine # [ 4.857993] systemd[1]: initrd-cleanup.service: Deactivated successfully.553machine # [ 4.862897] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.554machine # [ 4.864364] systemd[1]: Stopped target Initrd Default Target.555machine # [ 4.865402] systemd[1]: Stopped target Basic System.556machine # [ 4.866380] systemd[1]: Stopped target Initrd Root Device.557machine # [ 4.867385] systemd[1]: Stopped target Path Units.558machine # [ 4.871569] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.559machine # [ 4.874547] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.560machine # [ 4.880240] systemd[1]: Stopped target Slice Units.561machine # [ 4.881102] systemd[1]: Stopped target Socket Units.562machine # [ 4.881969] systemd[1]: Stopped target System Initialization.563machine # [ 4.882861] systemd[1]: Stopped target Swaps.564machine # [ 4.884125] systemd[1]: Stopped target Timer Units.565machine # [ 4.888185] systemd[1]: dbus.socket: Deactivated successfully.566machine # [ 4.889158] systemd[1]: Closed D-Bus System Message Bus Socket.567machine # [ 4.890061] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.568machine # [ 4.892117] systemd[1]: Stopped Find NixOS closure.569machine # [ 4.894496] systemd[1]: Starting Load Kernel Module 9pnet_virtio...570machine # [ 4.897323] systemd[1]: Starting rw-sysroot-nix-store.service...571machine # [ 4.899557] systemd[1]: systemd-sysctl.service: Deactivated successfully.572machine # [ 4.902411] systemd[1]: Stopped Apply Kernel Variables.573machine # [ 4.903680] systemd[1]: systemd-modules-load.service: Deactivated successfully.574machine # [ 4.912531] systemd[1]: Stopped Load Kernel Modules.575machine # [ 4.913353] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.576machine # [ 4.914621] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.577machine # [ 4.915805] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.578machine # [ 4.928868] systemd[1]: Stopped Create System Files and Directories.579machine # [ 4.932229] systemd[1]: Stopped target Local File Systems.580machine # [ 4.934223] systemd[1]: Stopped target Preparation for Local File Systems.581machine # [ 4.936180] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.582machine # [ 4.939316] systemd[1]: Stopped Coldplug All udev Devices.583machine # [ 4.940954] systemd[1]: Stopping Rule-based Manager for Device Events and Files...584machine # [ 4.944488] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.585machine # [ 4.945624] systemd[1]: Stopped Virtual Console Setup.586machine # [ 4.947157] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.587machine # [ 4.951046] systemd[1]: Finished Load Kernel Module 9pnet_virtio.588machine # [ 4.952166] systemd[1]: systemd-udevd.service: Deactivated successfully.589machine # [ 4.953128] systemd[1]: Stopped Rule-based Manager for Device Events and Files.590machine # [ 4.954126] systemd[1]: systemd-udevd.service: Consumed 1.493s CPU time over 3.112s wall clock time, 22.3M memory peak.591machine # [ 4.955547] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.592machine # [ 4.961006] systemd[1]: Finished rw-sysroot-nix-store.service.593machine # [ 4.964189] systemd[1]: Starting Cleanup udev Database...594machine # [ 4.965199] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.595machine # [ 4.968177] systemd[1]: Stopped Create Static Device Nodes in /dev.596machine # [ 4.969139] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.597machine # [ 4.972228] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.598machine # [ 4.976100] systemd[1]: kmod-static-nodes.service: Deactivated successfully.599machine # [ 4.977118] systemd[1]: Stopped Create List of Static Device Nodes.600machine # [ 5.004003] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.601machine # [ 5.007482] systemd[1]: Finished Cleanup udev Database.602machine # [ 5.008794] systemd[1]: Reached target Switch Root.603machine # [ 5.009981] systemd[1]: Starting NixOS Activation...604machine # [ 5.178758] initrd-nixos-activation-start[180]: booting system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test605machine # [ 5.248451] initrd-nixos-activation-start[180]: running activation script...606machine # [ 5.744623] initrd-nixos-activation-start[203]: setting up /etc...607machine # [ 6.061646] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.608machine # [ 6.064861] systemd[1]: Finished NixOS Activation.609machine # [ 6.066104] systemd[1]: Starting Switch Root...610machine # [ 6.090591] systemd[1]: Switching root.611machine # [ 6.294977] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).612machine # [ 6.920528] systemd[1]: systemd 262-2ff84cf 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)613machine # [ 6.933785] systemd[1]: Detected virtualization qemu.614machine # [ 6.938098] systemd[1]: Detected architecture arm64.615machine # [ 6.940467] systemd[1]: Detected first boot.616machine # [ 6.948474] systemd[1]: Initializing machine ID from random generator.617machine # [ 7.139764] systemd[1]: bpf-restrict-fs: LSM BPF program attached618machine # [ 7.309346] systemd[1]: Applying preset policy.619machine # [ 7.813392] systemd[1]: Populated /etc with preset unit settings.620machine # [ 8.346505] systemd[1]: initrd-switch-root.service: Deactivated successfully.621machine # [ 8.347991] systemd[1]: Stopped initrd-switch-root.service.622machine # [ 8.351111] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.623machine # [ 8.354783] systemd[1]: Created slice Slice /system/getty.624machine # [ 8.357586] systemd[1]: Created slice User and Session Slice.625machine # [ 8.359638] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.626machine # [ 8.361450] systemd[1]: Started Forward Password Requests to Wall Directory Watch.627machine # [ 8.363951] systemd[1]: Expecting device /dev/hvc0...628machine # [ 8.364993] systemd[1]: Expecting device /dev/ttyAMA0...629machine # [ 8.366477] systemd[1]: Reached target Local Encrypted Volumes.630machine # [ 8.368911] systemd[1]: Stopped target initrd-fs.target.631machine # [ 8.369964] systemd[1]: Stopped target initrd-root-fs.target.632machine # [ 8.371495] systemd[1]: Stopped target initrd-switch-root.target.633machine # [ 8.373787] systemd[1]: Reached target Virtual Machines and Containers.634machine # [ 8.375158] systemd[1]: Reached target Path Units.635machine # [ 8.376656] systemd[1]: Reached target Remote File Systems.636machine # [ 8.378873] systemd[1]: Reached target Slice Units.637machine # [ 8.379949] systemd[1]: Reached target Swaps.638machine # [ 8.384564] systemd[1]: Listening on Query the User Interactively for a Password.639machine # [ 8.389648] systemd[1]: Listening on Process Core Dump Socket.640machine # [ 8.393880] systemd[1]: Listening on Credential Encryption/Decryption.641machine # [ 8.398070] systemd[1]: Listening on Factory Reset Management.642machine # [ 8.399286] systemd[1]: Listening on Hostname Service Socket.643machine # [ 8.405498] systemd[1]: Starting Journal Log Access Socket...644machine # [ 8.407733] systemd[1]: Listening on Journal Audit Socket.645machine # [ 8.412997] systemd[1]: Listening on Console Output Muting Service Socket.646machine # [ 8.414567] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.647machine # [ 8.416239] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os648machine # [ 8.419034] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki649machine # [ 8.429823] systemd[1]: Listening on Disk Repartitioning Service Socket.650machine # [ 8.430383] systemd[1]: Listening on udev Varlink Socket.651machine # [ 8.435856] systemd[1]: Mounting Huge Pages File System...652machine # [ 8.439976] systemd[1]: Mounting POSIX Message Queue File System...653machine # [ 8.452355] systemd[1]: Mounting Kernel Debug File System...654machine # [ 8.461233] systemd[1]: Mounting Kernel Trace File System...655machine # [ 8.477732] systemd[1]: Starting Create List of Static Device Nodes...656machine # [ 8.500302] systemd[1]: Starting Load Kernel Module 9pnet_virtio...657machine # [ 8.502924] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs658machine # [ 8.517664] systemd[1]: Mounting Kernel Configuration File System...659machine # [ 8.524348] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm660machine # [ 8.527435] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore661machine # [ 8.559492] systemd[1]: Starting Load Kernel Module fuse...662machine # [ 8.567891] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67663machine # [ 8.621746] systemd[1]: Starting Journal Service...664machine # [ 8.656569] systemd[1]: Starting Load Kernel Modules...665machine # [ 8.677950] fuse: init (API version 7.45)666machine # [ 8.690713] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...667machine # [ 8.710854] systemd[1]: Starting Remount Root and Kernel File Systems...668machine # [ 8.716831] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os669machine # [ 8.751093] systemd[1]: Starting Coldplug All udev Devices...670machine # [ 8.781099] systemd-journald[275]: Collecting audit messages is enabled.671machine # [ 8.787762] systemd[1]: Listening on Journal Log Access Socket.672machine # [ 8.796140] systemd[1]: Mounted Huge Pages File System.673machine # [ 8.800740] systemd[1]: Mounted POSIX Message Queue File System.674machine # [ 8.787557] systemd[1]: Queued start job for default target Multi-User System.675machine # [ 8.806615] systemd[1]: Mounted Kernel Debug File System.676machine # [ 8.811184] systemd[1]: Started Journal Service.677machine # [ 8.793204] systemd[1]: systemd-journald.service: Deactivated successfully.678machine # [ 8.794643] systemd-modules-load[276]: Module 'atkbd' is built in679machine # [ 8.795646] systemd-modules-load[276]: Module 'loop' is built in680machine # [ 8.807923] systemd[1]: Mounted Kernel Trace File System.681machine # [ 8.830675] EXT4-fs (vda): re-mounted 7b5cf406-d577-4923-86ce-8644149f7c36.682machine # [ 8.818358] systemd[1]: Finished Create List of Static Device Nodes.683machine # [ 8.823511] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.684machine # [ 8.830885] systemd[1]: Finished Load Kernel Module 9pnet_virtio.685machine # [ 8.834364] systemd[1]: Mounted Kernel Configuration File System.686machine # [ 8.841779] systemd[1]: modprobe@fuse.service: Deactivated successfully.687machine # [ 8.842959] systemd[1]: Finished Load Kernel Module fuse.688machine # [ 8.843902] systemd[1]: Finished Load Kernel Modules.689machine # [ 8.856404] systemd[1]: Finished Remount Root and Kernel File Systems.690machine # [ 8.863299] systemd[1]: Listening on Disk Image Download Service Socket.691machine # [ 8.886373] systemd[1]: Mounting FUSE Control File System...692machine # [ 8.889711] systemd[1]: Starting Firewall...693machine # [ 8.929142] systemd-oomd[277]: No swap; memory pressure usage will be degraded694machine # [ 8.934522] systemd[1]: Starting Flush Journal to Persistent Storage...695machine # [ 8.939113] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore696machine # [ 8.953990] systemd[1]: Starting Load/Save OS Random Seed...697machine # [ 8.978458] systemd[1]: Starting Apply Kernel Variables...698machine # [ 9.029773] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...699machine # [ 9.033603] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os700machine # [ 9.040590] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.701machine # [ 9.127008] systemd-journald[275]: Received client request to flush runtime journal.702machine # [ 9.205295] systemd[1]: Mounted FUSE Control File System.703machine # [ 9.216607] systemd[1]: Finished Load/Save OS Random Seed.704machine # [ 9.217455] systemd[1]: Reached target First Boot Complete.705machine # [ 9.229552] systemd[1]: Finished Flush Journal to Persistent Storage.706machine # [ 9.231373] systemd[1]: Finished Apply Kernel Variables.707machine # [ 9.272002] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.708machine # [ 9.280481] systemd[1]: Starting Create Static Device Nodes in /dev...709machine # [ 9.393937] systemd[1]: Finished Create Static Device Nodes in /dev.710machine # [ 9.397747] systemd[1]: Reached target Preparation for Local File Systems.711machine # [ 9.407519] systemd[1]: Mounting /run/wrappers...712machine # [ 9.416557] systemd[1]: Starting Rule-based Manager for Device Events and Files...713machine # [ 9.501400] systemd[1]: Mounted /run/wrappers.714machine # [ 9.502612] systemd[1]: Reached target Local File Systems.715machine # [ 9.512342] systemd[1]: Listening on Boot Loader Control Service Socket.716machine # [ 9.516855] systemd[1]: Starting Create SUID/SGID Wrappers...717machine # [ 9.525204] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.718machine # [ 9.530865] systemd[1]: Starting Save Transient machine-id to Disk...719machine # [ 9.561953] systemd[1]: Starting Create System Files and Directories...720machine # [ 9.624871] systemd-udevd[324]: Using default interface naming scheme 'v261'.721machine # [ 9.655828] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.722machine # [ 9.669545] systemd[1]: Finished Save Transient machine-id to Disk.723machine # [ 9.737522] systemd[1]: Finished Create System Files and Directories.724machine # [ 9.748602] systemd[1]: Starting Rebuild Journal Catalog...725machine # [ 9.766334] systemd[1]: Starting Record System Boot/Shutdown in UTMP...726machine # [ 9.898399] systemd[1]: Finished Record System Boot/Shutdown in UTMP.727machine # [ 9.938245] systemd[1]: Started Rule-based Manager for Device Events and Files.728machine # [ 9.951348] systemd[1]: Finished Rebuild Journal Catalog.729machine # [ 9.975335] systemd[1]: Starting Update is Completed...730machine # [ 10.066204] systemd[1]: Finished Update is Completed.731machine # [ 10.420537] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.732machine # [ 10.425639] systemd[1]: Finished Create SUID/SGID Wrappers.733machine # [ 10.912175] systemd[1]: Finished Coldplug All udev Devices.734machine # [ 10.913190] systemd[1]: Reached target System Initialization.735machine # [ 10.914047] systemd[1]: Started Discard unused filesystem blocks once a week.736machine # [ 10.915016] systemd[1]: Started Daily Cleanup of Temporary Directories.737machine # [ 10.915954] systemd[1]: Reached target Timer Units.738machine # [ 10.924860] systemd[1]: Listening on D-Bus System Message Bus Socket.739machine # [ 10.926139] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.740machine # [ 10.933588] systemd[1]: Reached target Socket Units.741machine # [ 10.942371] systemd[1]: Reached target Basic System.742machine # [ 10.943142] systemd[1]: Starting Import lastlog data into lastlog2 database...743machine # [ 10.947824] systemd[1]: Starting Name Service Cache Daemon (nsncd)...744machine # [ 10.958096] systemd[1]: Starting Post-Boot Actions...745machine # [ 11.006728] systemd[1]: Started Reset console on configuration changes.746machine # [ 11.031904] systemd[1]: Starting resolvconf update...747machine # [ 11.126189] systemd[1]: Finished Post-Boot Actions.748machine # [ 11.156797] nsncd[461]: Sep 10 02:43:20.735 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"749machine # [ 11.170225] systemd[1]: Started Name Service Cache Daemon (nsncd).750machine # [ 11.177152] systemd[1]: Reached target Host and Network Name Lookups.751machine # [ 11.183119] systemd[1]: Reached target User and Group Name Lookups.752machine # [ 11.200943] systemd[1]: Starting D-Bus System Message Bus...753machine # [ 11.251477] systemd[1]: Starting User Login Management...754machine # [ 11.253674] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs755machine # [ 11.303149] systemd[1]: Finished Import lastlog data into lastlog2 database.756machine # [ 11.348671] systemd[1]: Finished Firewall.757machine # [ 11.481991] dbus-broker-launch[489]: Looking up NSS user entry for 'systemd-timesync'...758machine # [ 11.490153] dbus-broker-launch[489]: NSS returned no entry for 'systemd-timesync'759machine # [ 11.498115] dbus-broker-launch[489]: Invalid user-name in /nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"760machine # [ 11.507961] systemd[1]: Stopped target Host and Network Name Lookups.761machine # [ 11.514303] systemd[1]: Stopping Host and Network Name Lookups...762machine # [ 11.519990] systemd[1]: Stopped target User and Group Name Lookups.763machine # [ 11.525616] systemd[1]: Stopping User and Group Name Lookups...764machine # [ 11.530693] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...765machine # [ 11.542021] systemd-logind[493]: New seat seat0.766machine # [ 11.544343] systemd[1]: Started User Login Management.767machine # [ 11.549854] systemd[1]: nscd.service: Deactivated successfully.768machine # [ 11.553122] systemd[1]: Stopped Name Service Cache Daemon (nsncd).769machine # [ 11.558472] systemd[1]: Started D-Bus System Message Bus.770machine # [ 11.561349] systemd[1]: Starting linger-users.service...771machine # [ 11.567287] systemd[1]: Starting Name Service Cache Daemon (nsncd)...772machine # [ 11.610611] dbus-broker-launch[489]: Ready773machine # [ 11.682783] systemd[1]: Started Name Service Cache Daemon (nsncd).774machine # [ 11.688576] nsncd[550]: Sep 10 02:43:21.260 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"775machine # [ 11.698507] systemd[1]: Finished resolvconf update.776machine # [ 11.700392] systemd[1]: Reached target Preparation for Network.777machine # [ 11.708203] systemd[1]: Reached target Host and Network Name Lookups.778machine # [ 11.711414] systemd[1]: Reached target User and Group Name Lookups.779machine # [ 11.715554] systemd[1]: Starting DHCP Client...780machine # [ 11.720953] systemd[1]: Starting Extra networking commands....781machine # [ 11.812903] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.782machine # [ 11.820364] systemd[1]: linger-users.service: Deactivated successfully.783machine # [ 11.825090] systemd[1]: Finished linger-users.service.784machine # [ 11.856386] systemd[1]: Created slice Slice /user/1001.785machine # [ 11.878939] systemd[1]: Starting User Runtime Directory /run/user/1001...786machine # [ 11.922525] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.787machine # [ 11.950662] systemd[1]: Started backdoor.service.788machine # [ 12.052963] systemd[1]: Finished User Runtime Directory /run/user/1001.789machine # [ 12.076636] systemd[1]: Starting User Manager for UID 1001...790machine # [ 12.216751] (udev-worker)[370]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.791machine # [ 12.262163] (udev-worker)[370]: Network interface NamePolicy= disabled on kernel command line.792machine # [ 12.287503] (systemd)[584]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)793machine # [ 12.333304] (udev-worker)[383]: Network interface NamePolicy= disabled on kernel command line.794machine # [ 12.375503] systemd-logind[493]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.795machine # [ 12.424600] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy796machine # connecting to host...797machine # [ 12.625129] mousedev: PS/2 mouse device common for all mice798machine # [ 12.655046] systemd-logind[493]: Watching system buttons on /dev/input/event0 (gpio-keys)799machine # [ 12.668429] systemd[1]: Condition check resulted in Virtio network device being skipped.800machine # [ 12.669733] systemd[1]: Starting Address configuration of eth1...801machine # [ 12.670590] systemd[584]: Queued start job for default target Main User Target.802machine: Guest shell says: b'Spawning backdoor root shell...\n'803machine: connected to guest root shell804machine: (connecting took 13.00 seconds)805machine: (finished: waiting for the VM to finish booting, in 13.73 seconds)806machine # [ 12.707505] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy807machine # [ 12.724368] systemd[584]: Created slice User Application Slice.808machine # [ 12.725334] systemd[584]: Started Daily Cleanup of User's Temporary Directories.809machine # [ 12.726367] systemd[584]: Reached target Paths.810machine # [ 12.727032] systemd[584]: Reached target Timers.811machine # [ 12.727680] systemd[584]: Starting D-Bus User Message Bus Socket...812machine # [ 12.777216] systemd[584]: Starting Create User Files and Directories...813machine # [ 12.796954] systemd[584]: Finished Create User Files and Directories.814machine # [ 12.850132] systemd[584]: Listening on D-Bus User Message Bus Socket.815machine # [ 12.868670] systemd[584]: Reached target Sockets.816machine # [ 12.869456] systemd[584]: Reached target Basic System.817machine # [ 12.870174] systemd[1]: Started User Manager for UID 1001.818machine # [ 12.870942] systemd[584]: Starting Run user-specific NixOS activation...819machine # [ 12.898343] systemd-logind[493]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)820machine # [ 12.935594] systemd[584]: Finished Run user-specific NixOS activation.821machine # [ 12.939725] systemd[584]: Reached target Main User Target.822machine # [ 12.948323] systemd[584]: Startup finished in 619ms.823machine # [ 12.975987] dhcpcd[592]: dhcpcd-10.3.2 starting824machine # [ 12.996545] dhcpcd[659]: dev: loaded udev825machine # [ 13.010487] network-addresses-eth1-start[612]: adding address 192.168.1.1/24... done826machine # [ 13.027232] network-addresses-eth1-start[612]: adding address 2001:db8:1::1/64... done827machine # [ 13.064859] 8021q: 802.1Q VLAN Support v1.8828machine # [ 13.065252] 8021q: adding VLAN 0 to HW filter on device eth1829machine # [ 13.090744] systemd[1]: Finished Extra networking commands..830machine # [ 13.100548] systemd[1]: Finished Address configuration of eth1.831machine # [ 13.105650] systemd[1]: Reached target Network.832machine # [ 13.108512] systemd[1]: Starting Permit User Sessions...833machine # [ 13.157802] cfg80211: Loading compiled-in X.509 certificates for regulatory database834machine # [ 13.194021] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'835machine # [ 13.194594] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'836machine # [ 13.200109] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2837machine # [ 13.200466] cfg80211: failed to load regulatory.db838machine # [ 13.215329] systemd[1]: Finished Permit User Sessions.839machine # [ 13.221815] systemd[1]: Started Getty on tty1.840machine # [ 13.225222] systemd[1]: Reached target Login Prompts.841machine # [ 13.520376] 8021q: adding VLAN 0 to HW filter on device eth0842machine # [ 13.512988] dhcpcd[659]: eth0: waiting for carrier843machine # [ 13.522007] dhcpcd[659]: eth0: carrier acquired844machine # [ 13.599263] dhcpcd[659]: DUID 00:01:00:01:32:34:d5:cb:52:54:00:12:34:56845machine # [ 13.614167] dhcpcd[659]: eth0: IAID 00:12:34:56846machine # [ 13.614959] dhcpcd[659]: eth0: adding address fe80::5054:ff:fe12:3456847machine # [ 14.021069] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3848machine # [ 14.375572] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.849machine # [ 14.409509] systemd[1]: Starting Virtual Console Setup...850machine # [ 14.457473] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.851machine # [ 14.459324] systemd[1]: Stopped Virtual Console Setup.852machine # [ 14.465151] systemd-logind[493]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)853machine # [ 14.466363] systemd[1]: Starting Virtual Console Setup...854machine # [ 14.949009] systemd-vconsole-setup[713]: Configuration of first virtual console was skipped, ignoring remaining ones.855machine # [ 14.955645] systemd[1]: Finished Virtual Console Setup.856machine # [ 14.966424] dhcpcd[659]: eth0: soliciting a DHCP lease857machine # [ 14.972890] dhcpcd[659]: eth0: offered 10.0.2.15 from 10.0.2.2858machine # [ 14.980496] dhcpcd[659]: eth0: probing address 10.0.2.15/24859machine # [ 16.059360] dhcpcd[659]: eth0: soliciting an IPv6 router860machine # [ 16.063187] dhcpcd[659]: eth0: Router Advertisement from fe80::2861machine # [ 16.063383] dhcpcd[659]: eth0: adding address fec0::5054:ff:fe12:3456/64862machine # [ 16.063519] dhcpcd[659]: eth0: adding route to fec0::/64863machine # [ 16.063655] dhcpcd[659]: eth0: adding default route via fe80::2864machine # [ 19.539938] dhcpcd[659]: eth0: leased 10.0.2.15 for 86400 seconds865machine # [ 19.544141] dhcpcd[659]: eth0: adding route to 10.0.2.0/24866machine # [ 19.547145] dhcpcd[659]: eth0: adding default route via 10.0.2.2867machine # [ 19.746373] systemd[1]: Started DHCP Client.868machine # [ 19.749021] systemd[1]: Reached target Multi-User System.869machine # [ 19.750410] systemd[1]: Startup finished in 989ms (kernel) + 5.416s (initrd) + 13.342s (userspace) = 19.748s.870machine: (finished: waiting for unit multi-user.target, in 21.17 seconds)871machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test872machine # Checking switch inhibitors... done873machine # [ 20.756533] nixos[826]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test874machine # [ 20.759268] systemd[1]: Stopped target Local File Systems.875machine # activating the configuration...876machine # [ 20.761688] systemd[1]: Stopped target Remote File Systems.877machine # setting up /etc...878machine # [ 21.227189] systemd[1]: Reload requested from client PID 826 ('.switch-to-conf') (unit backdoor.service)...879machine # [ 21.229224] systemd[1]: Reloading...880machine # [ 21.817811] systemd[1]: Reloading finished in 585 ms.881machine # reloading user units for usertest...882machine # [ 21.866073] systemd[584]: Created slice User Core Session Slice.883machine # [ 21.869758] systemd[584]: Starting D-Bus User Message Bus...884machine # [ 21.932653] dbus-broker-launch[896]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/services/org.freedesktop.import1.service'885machine # [ 21.936459] dbus-broker-launch[896]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/services/org.freedesktop.machine1.service'886machine # [ 21.941896] dbus-broker-launch[896]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/services/org.freedesktop.portable1.service'887machine # [ 21.945050] dbus-broker-launch[896]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'888machine # [ 21.948733] dbus-broker-launch[896]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored889machine # [ 21.951763] dbus-broker-launch[896]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored890machine # [ 21.954210] systemd[584]: Started D-Bus User Message Bus.891machine # [ 21.962023] dbus-broker-launch[896]: Ready892machine # [ 22.072824] systemd[584]: Reexecution requested from client PID 894 ('switch-to-confi')...893machine # [ 22.076852] systemd[584]: Reexecuting.894machine # [ 22.126542] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy895machine # [ 22.322325] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy896machine # [ 22.329154] systemd[584]: Reload requested from client PID 894 ('switch-to-confi')...897machine # [ 22.331493] systemd[584]: Reloading...898machine # [ 22.523893] systemd[584]: Reloading finished in 191 ms.899machine # [ 22.536156] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy900machine # restarting the following user units: nixos-activation.service901machine # [ 22.549186] systemd[584]: Stopped Run user-specific NixOS activation.902machine # [ 22.550154] systemd[584]: Stopping Run user-specific NixOS activation...903machine # [ 22.552923] systemd[584]: Starting Run user-specific NixOS activation...904machine # [ 22.599716] systemd[584]: Finished Run user-specific NixOS activation.905machine # restarting sysinit-reactivation.target906machine # [ 22.617012] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....907machine # [ 22.722825] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..908machine # [ 22.725207] systemd[1]: Reached target Reactivate sysinit units.909machine # [ 22.726869] systemd[1]: Reached target Local File Systems.910machine # [ 22.734513] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os911machine # [ 22.737042] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki912machine # [ 22.739534] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore913machine # [ 22.747157] systemd[1]: Starting Create SUID/SGID Wrappers...914machine # [ 22.748446] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.915machine # [ 22.751978] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67916machine # [ 22.754661] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore917machine # [ 22.756325] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os918machine # [ 22.757906] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os919machine # [ 22.763478] systemd[1]: Reached target Remote File Systems.920machine # [ 23.097984] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.921machine # [ 23.102632] systemd[1]: Finished Create SUID/SGID Wrappers.922machine # [ 23.105483] systemd[1]: Starting linger-users.service...923machine # [ 23.166016] systemd[1]: linger-users.service: Deactivated successfully.924machine # [ 23.168659] systemd[1]: Finished linger-users.service.925machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service926machine # [ 23.432774] nixos[826]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test927machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test, in 3.30 seconds)928machine: must succeed: rm /etc/NIXOS929machine: (finished: must succeed: rm /etc/NIXOS, in 0.05 seconds)930machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test/bin/switch-to-configuration test931machine # this should succeed (config: /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test, action: test)932machine # Checking switch inhibitors... done933machine # [ 24.121919] nixos[974]: switching to system configuration /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test934machine # [ 24.124825] systemd[1]: Stopped target Local File Systems.935machine # activating the configuration...936machine # [ 24.127513] systemd[1]: Stopped target Remote File Systems.937machine # removing user ‘usertest’938machine # setting up /etc...939machine # [ 24.630861] systemd[1]: Reload requested from client PID 974 ('.switch-to-conf') (unit backdoor.service)...940machine # [ 24.633074] systemd[1]: Reloading...941machine # [ 25.309853] systemd[1]: Reloading finished in 674 ms.942machine # reloading user units for usertest...943machine # [ 25.484306] systemd[584]: Reexecution requested from client PID 1045 ('switch-to-confi')...944machine # [ 25.485784] systemd[584]: Reexecuting.945machine # [ 25.550382] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy946machine # [ 25.759738] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy947machine # [ 25.766574] systemd[584]: Reload requested from client PID 1045 ('switch-to-confi')...948machine # [ 25.769249] systemd[584]: Reloading...949machine # [ 25.970623] systemd[584]: Reloading finished in 200 ms.950machine # [ 25.984200] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy951machine # restarting the following user units: nixos-activation.service952machine # [ 25.995888] systemd[584]: Stopped Run user-specific NixOS activation.953machine # [ 25.998709] systemd[584]: Stopping Run user-specific NixOS activation...954machine # [ 26.002004] systemd[584]: Starting Run user-specific NixOS activation...955machine # [ 26.054730] systemd[584]: Finished Run user-specific NixOS activation.956machine # restarting sysinit-reactivation.target957machine # [ 26.059611] systemd[1]: Stopped target Reactivate sysinit units.958machine # [ 26.061282] systemd[1]: Stopping Reactivate sysinit units...959machine # [ 26.062135] systemd[1]: Reached target Reactivate sysinit units.960machine # reloading the following units: dbus-broker.service961machine # [ 26.065558] systemd[1]: Reloading D-Bus System Message Bus...962machine # [ 26.066786] dbus-broker-launch[489]: Caught SIGHUP, trigger reload.963machine # [ 26.091849] dbus-broker-launch[489]: Looking up NSS user entry for 'systemd-timesync'...964machine # [ 26.094344] dbus-broker-launch[489]: NSS returned no entry for 'systemd-timesync'965machine # [ 26.095676] dbus-broker-launch[489]: Invalid user-name in /nix/store/p8lzwq9djsvv4pad8njyi3hknk69n4y5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"966machine # [ 26.125488] systemd[1]: Reloaded D-Bus System Message Bus.967machine # restarting the following units: network-addresses-eth1.service968machine # [ 26.133737] systemd[1]: Stopping Address configuration of eth1...969machine # [ 26.187589] network-addresses-eth1-pre-stop[1057]: deleting address 192.168.1.1/24... done970machine # [ 26.199992] network-addresses-eth1-pre-stop[1057]: deleting address 2001:db8:1::1/64... done971machine # [ 26.210676] systemd[1]: network-addresses-eth1.service: Deactivated successfully.972machine # [ 26.215138] systemd[1]: Stopped Address configuration of eth1.973machine # [ 26.218141] systemd[1]: Starting Address configuration of eth1...974machine # [ 26.292437] network-addresses-eth1-start[1062]: adding address 192.168.1.2/24... done975machine # [ 26.305243] network-addresses-eth1-start[1062]: adding address 2001:db8:1::2/64... done976machine # [ 26.321897] systemd[1]: Finished Address configuration of eth1.977machine # [ 26.325865] systemd[1]: Reached target Local File Systems.978machine # [ 26.329105] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore979machine # [ 26.334415] systemd[1]: Starting Create SUID/SGID Wrappers...980machine # [ 26.335334] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.981machine # [ 26.346131] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67982machine # [ 26.358813] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore983machine # [ 26.364448] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os984machine # [ 26.365847] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os985machine # [ 26.367272] systemd[1]: Reached target Remote File Systems.986machine # [ 26.368246] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os987machine # [ 26.369784] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki988machine # [ 26.798575] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.989machine # [ 26.804404] systemd[1]: Finished Create SUID/SGID Wrappers.990machine # [ 26.807401] systemd[1]: Starting linger-users.service...991machine # [ 26.860343] linger-users-start[1135]: id: ‘usertest’: no such user992machine # [ 26.863213] linger-users-start[1133]: Removing linger for missing user usertest993machine # [ 26.874257] systemd[1]: linger-users.service: Deactivated successfully.994machine # [ 26.877491] systemd[1]: Finished linger-users.service.995machine # [ 27.135750] nixos[974]: finished switching to system configuration /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test996machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test/bin/switch-to-configuration test, in 3.65 seconds)997machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1998machine # [ 27.723638] nixos[1141]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test999machine # [ 27.727918] systemd[1]: Stopped target Remote File Systems.1000machine # [ 27.729083] systemd[1]: Stopped target Local File Systems.1001machine # [ 28.180513] systemd[1]: Reload requested from client PID 1141 ('.switch-to-conf') (unit backdoor.service)...1002machine # [ 28.182510] systemd[1]: Reloading...1003machine # [ 28.768714] systemd[1]: Reloading finished in 583 ms.1004machine # [ 28.918193] systemd[584]: Reexecution requested from client PID 1210 ('switch-to-confi')...1005machine # [ 28.921774] systemd[584]: Reexecuting.1006machine # [ 28.983805] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1007machine # [ 29.186301] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1008machine # [ 29.193009] systemd[584]: Reload requested from client PID 1210 ('switch-to-confi')...1009machine # [ 29.195588] systemd[584]: Reloading...1010machine # [ 29.399330] systemd[584]: Reloading finished in 202 ms.1011machine # [ 29.416231] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1012machine # [ 29.427455] systemd[584]: Stopped Run user-specific NixOS activation.1013machine # [ 29.430135] systemd[584]: Stopping Run user-specific NixOS activation...1014machine # [ 29.433474] systemd[584]: Starting Run user-specific NixOS activation...1015machine # [ 29.485211] systemd[584]: Finished Run user-specific NixOS activation.1016machine # [ 29.489763] systemd[1]: Stopped target Reactivate sysinit units.1017machine # [ 29.490822] systemd[1]: Stopping Reactivate sysinit units...1018machine # [ 29.494792] systemd[1]: Reached target Reactivate sysinit units.1019machine # [ 29.495814] systemd[1]: Reloading D-Bus System Message Bus...1020machine # [ 29.497679] dbus-broker-launch[489]: Caught SIGHUP, trigger reload.1021machine # [ 29.512411] dbus-broker-launch[489]: Looking up NSS user entry for 'systemd-timesync'...1022machine # [ 29.514809] dbus-broker-launch[489]: NSS returned no entry for 'systemd-timesync'1023machine # [ 29.516190] dbus-broker-launch[489]: Invalid user-name in /nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1024machine # [ 29.542386] systemd[1]: Reloaded D-Bus System Message Bus.1025machine # [ 29.549605] systemd[1]: Stopping Address configuration of eth1...1026machine # [ 29.603275] network-addresses-eth1-pre-stop[1222]: deleting address 192.168.1.2/24... done1027machine # [ 29.616197] network-addresses-eth1-pre-stop[1222]: deleting address 2001:db8:1::2/64... done1028machine # [ 29.626682] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1029machine # [ 29.629053] systemd[1]: Stopped Address configuration of eth1.1030machine # [ 29.634241] systemd[1]: Starting Address configuration of eth1...1031machine # [ 29.729023] network-addresses-eth1-start[1227]: adding address 192.168.1.1/24... done1032machine # [ 29.743933] network-addresses-eth1-start[1227]: adding address 2001:db8:1::1/64... done1033machine # [ 29.764560] systemd[1]: Finished Address configuration of eth1.1034machine # [ 29.769098] systemd[1]: Reached target Remote File Systems.1035machine # [ 29.773130] systemd[1]: Reached target Local File Systems.1036machine # [ 29.777522] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1037machine # [ 29.781530] systemd[1]: Starting Create SUID/SGID Wrappers...1038machine # [ 29.782414] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1039machine # [ 29.783659] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671040machine # [ 29.803516] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1041machine # [ 29.810908] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1042machine # [ 29.813545] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1043machine # [ 29.815829] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1044machine # [ 29.817533] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1045machine # [ 30.150361] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1046machine # [ 30.154140] systemd[1]: Finished Create SUID/SGID Wrappers.1047machine # [ 30.158885] systemd[1]: Starting linger-users.service...1048machine # [ 30.209307] systemd[1]: linger-users.service: Deactivated successfully.1049machine # [ 30.212340] systemd[1]: Finished linger-users.service.1050machine # [ 30.471177] nixos[1141]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test1051machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.33 seconds)1052subtest: user services1053machine: waiting for unit user@1001.service1054machine: (finished: waiting for unit user@1001.service, in 0.21 seconds)1055machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11056machine # [ 31.255967] nixos[1301]: switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test1057machine # [ 31.258741] systemd[1]: Stopped target Local File Systems.1058machine # [ 31.261316] systemd[1]: Stopped target Remote File Systems.1059machine # [ 31.788745] systemd[1]: Reload requested from client PID 1301 ('.switch-to-conf') (unit backdoor.service)...1060machine # [ 31.790801] systemd[1]: Reloading...1061machine # [ 32.357920] systemd[1]: Reloading finished in 564 ms.1062machine # [ 32.530205] systemd[584]: Reexecution requested from client PID 1370 ('switch-to-confi')...1063machine # [ 32.531601] systemd[584]: Reexecuting.1064machine # [ 32.594171] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1065machine # [ 32.812973] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1066machine # [ 32.819951] systemd[584]: Reload requested from client PID 1370 ('switch-to-confi')...1067machine # [ 32.822426] systemd[584]: Reloading...1068machine # [ 33.033541] systemd[584]: Reloading finished in 209 ms.1069machine # [ 33.057964] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1070machine # [ 33.068324] systemd[584]: Starting usertest.service...1071machine # [ 33.115593] systemd[584]: Finished usertest.service.1072machine # [ 33.118019] systemd[584]: Stopped Run user-specific NixOS activation.1073machine # [ 33.120263] systemd[584]: Stopping Run user-specific NixOS activation...1074machine # [ 33.123509] systemd[584]: Starting Run user-specific NixOS activation...1075machine # [ 33.169674] systemd[584]: Finished Run user-specific NixOS activation.1076machine # [ 33.173923] systemd[1]: Stopped target Reactivate sysinit units.1077machine # [ 33.176834] systemd[1]: Stopping Reactivate sysinit units...1078machine # [ 33.177695] systemd[1]: Reached target Reactivate sysinit units.1079machine # [ 33.178509] systemd[1]: Reached target Remote File Systems.1080machine # [ 33.179985] systemd[1]: Reached target Local File Systems.1081machine # [ 33.188721] systemd[1]: Starting linger-users.service...1082machine # [ 33.193358] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1083machine # [ 33.202320] systemd[1]: Starting Create SUID/SGID Wrappers...1084machine # [ 33.203412] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1085machine # [ 33.206335] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671086machine # [ 33.217109] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1087machine # [ 33.218757] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1088machine # [ 33.224505] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1089machine # [ 33.226440] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1090machine # [ 33.227971] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1091machine # [ 33.287476] systemd[1]: linger-users.service: Deactivated successfully.1092machine # [ 33.290262] systemd[1]: Finished linger-users.service.1093machine # [ 33.571268] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1094machine # [ 33.574889] systemd[1]: Finished Create SUID/SGID Wrappers.1095machine # [ 33.831646] nixos[1301]: finished switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test1096machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.16 seconds)1097machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1098machine # [ 33.945790] sudo[1441]: 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.service1099machine # [ 33.968643] sudo[1441]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1100machine # [ 33.986891] sudo[1441]: pam_unix(sudo:session): session closed for user usertest1101machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.15 seconds)1102machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11103machine # [ 34.541019] nixos[1450]: switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test1104machine # [ 34.543454] systemd[1]: Stopped target Local File Systems.1105machine # [ 34.545734] systemd[1]: Stopped target Remote File Systems.1106machine # [ 34.998758] systemd[1]: Reload requested from client PID 1450 ('.switch-to-conf') (unit backdoor.service)...1107machine # [ 35.000876] systemd[1]: Reloading...1108machine # [ 35.613253] systemd[1]: Reloading finished in 610 ms.1109machine # [ 35.774560] systemd[584]: Reexecution requested from client PID 1519 ('switch-to-confi')...1110machine # [ 35.779076] systemd[584]: Reexecuting.1111machine # [ 35.826917] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1112machine # [ 36.033368] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1113machine # [ 36.040063] systemd[584]: Reload requested from client PID 1519 ('switch-to-confi')...1114machine # [ 36.042445] systemd[584]: Reloading...1115machine # [ 36.247496] systemd[584]: Reloading finished in 203 ms.1116machine # [ 36.260167] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1117machine # [ 36.269971] systemd[584]: Stopped Run user-specific NixOS activation.1118machine # [ 36.272806] systemd[584]: Stopping Run user-specific NixOS activation...1119machine # [ 36.276978] systemd[584]: Starting Run user-specific NixOS activation...1120machine # [ 36.332543] systemd[584]: Finished Run user-specific NixOS activation.1121machine # [ 36.340156] systemd[1]: Stopped target Reactivate sysinit units.1122machine # [ 36.341160] systemd[1]: Stopping Reactivate sysinit units...1123machine # [ 36.341966] systemd[1]: Reached target Reactivate sysinit units.1124machine # [ 36.349148] systemd[1]: Reached target Remote File Systems.1125machine # [ 36.354358] systemd[1]: Starting linger-users.service...1126machine # [ 36.358224] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1127machine # [ 36.361886] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1128machine # [ 36.369267] systemd[1]: Reached target Local File Systems.1129machine # [ 36.374568] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1130machine # [ 36.380359] systemd[1]: Starting Create SUID/SGID Wrappers...1131machine # [ 36.392154] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1132machine # [ 36.393660] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671133machine # [ 36.399873] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1134machine # [ 36.403175] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1135machine # [ 36.406600] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1136machine # [ 36.489269] systemd[1]: linger-users.service: Deactivated successfully.1137machine # [ 36.492893] systemd[1]: Finished linger-users.service.1138machine # [ 36.784727] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1139machine # [ 36.787613] systemd[1]: Finished Create SUID/SGID Wrappers.1140machine # [ 37.046943] nixos[1450]: finished switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test1141machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.07 seconds)1142machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&11143machine # [ 37.661147] nixos[1591]: switching to system configuration /nix/store/c42prpkib9w329y9261yvpwpsfdlmkr9-nixos-system-machine-test1144machine # [ 37.663923] systemd[1]: Stopped target Local File Systems.1145machine # [ 37.666398] systemd[1]: Stopped target Remote File Systems.1146machine # [ 38.185444] systemd[1]: Reload requested from client PID 1591 ('.switch-to-conf') (unit backdoor.service)...1147machine # [ 38.187381] systemd[1]: Reloading...1148machine # [ 38.786084] systemd[1]: Reloading finished in 595 ms.1149machine # [ 38.965239] systemd[584]: Stopped usertest.service.1150machine # [ 38.968857] systemd[584]: Reexecution requested from client PID 1660 ('switch-to-confi')...1151machine # [ 38.971510] systemd[584]: Reexecuting.1152machine # [ 39.035074] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1153machine # [ 39.249406] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1154machine # [ 39.257282] systemd[584]: Reload requested from client PID 1660 ('switch-to-confi')...1155machine # [ 39.260106] systemd[584]: Reloading...1156machine # [ 39.462809] systemd[584]: Reloading finished in 203 ms.1157machine # [ 39.476191] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1158machine # [ 39.488407] systemd[584]: Starting usertest.service...1159machine # [ 39.528951] systemd[584]: Finished usertest.service.1160machine # [ 39.532103] systemd[584]: Stopped Run user-specific NixOS activation.1161machine # [ 39.533083] systemd[584]: Stopping Run user-specific NixOS activation...1162machine # [ 39.538223] systemd[584]: Starting Run user-specific NixOS activation...1163machine # [ 39.588542] systemd[584]: Finished Run user-specific NixOS activation.1164machine # [ 39.593064] systemd[1]: Stopped target Reactivate sysinit units.1165machine # [ 39.595909] systemd[1]: Stopping Reactivate sysinit units...1166machine # [ 39.597791] systemd[1]: Reached target Reactivate sysinit units.1167machine # [ 39.598765] systemd[1]: Reached target Local File Systems.1168machine # [ 39.602650] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1169machine # [ 39.608515] systemd[1]: Starting Create SUID/SGID Wrappers...1170machine # [ 39.610982] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1171machine # [ 39.616603] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671172machine # [ 39.618840] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1173machine # [ 39.627421] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1174machine # [ 39.630961] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1175machine # [ 39.635106] systemd[1]: Reached target Remote File Systems.1176machine # [ 39.636187] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1177machine # [ 39.638320] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1178machine # [ 40.033293] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1179machine # [ 40.034532] systemd[1]: Finished Create SUID/SGID Wrappers.1180machine # [ 40.040790] systemd[1]: Starting linger-users.service...1181machine # [ 40.111327] systemd[1]: linger-users.service: Deactivated successfully.1182machine # [ 40.114350] systemd[1]: Finished linger-users.service.1183machine # [ 40.372908] nixos[1591]: finished switching to system configuration /nix/store/c42prpkib9w329y9261yvpwpsfdlmkr9-nixos-system-machine-test1184machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 3.32 seconds)1185machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1186machine # [ 40.462621] sudo[1731]: 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.service1187machine # [ 40.481631] sudo[1731]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1188machine # [ 40.496684] sudo[1731]: pam_unix(sudo:session): session closed for user usertest1189machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds)1190machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&11191machine # [ 41.032409] nixos[1740]: switching to system configuration /nix/store/b5xsfk33ln102fr8jl2wvr3p0xxww71d-nixos-system-machine-test1192machine # [ 41.036719] systemd[1]: Stopped target Local File Systems.1193machine # [ 41.038056] systemd[1]: Stopped target Remote File Systems.1194machine # [ 41.557577] systemd[1]: Reload requested from client PID 1740 ('.switch-to-conf') (unit backdoor.service)...1195machine # [ 41.559616] systemd[1]: Reloading...1196machine # [ 42.153241] systemd[1]: Reloading finished in 589 ms.1197machine # [ 42.330622] systemd[584]: Reexecution requested from client PID 1809 ('switch-to-confi')...1198machine # [ 42.333011] systemd[584]: Reexecuting.1199machine # [ 42.395609] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1200machine # [ 42.611301] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1201machine # [ 42.619414] systemd[584]: Reload requested from client PID 1809 ('switch-to-confi')...1202machine # [ 42.622910] systemd[584]: Reloading...1203machine # [ 42.820223] systemd[584]: Reloading finished in 198 ms.1204machine # [ 42.836204] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1205machine # [ 42.842626] systemd[584]: Stopped usertest.service.1206machine # [ 42.844852] systemd[584]: Stopping usertest.service...1207machine # [ 42.847588] systemd[584]: Starting usertest.service...1208machine # [ 42.884238] systemd[584]: Finished usertest.service.1209machine # [ 42.889777] systemd[584]: Stopped Run user-specific NixOS activation.1210machine # [ 42.892399] systemd[584]: Stopping Run user-specific NixOS activation...1211machine # [ 42.895569] systemd[584]: Starting Run user-specific NixOS activation...1212machine # [ 42.943271] systemd[584]: Finished Run user-specific NixOS activation.1213machine # [ 42.947761] systemd[1]: Stopped target Reactivate sysinit units.1214machine # [ 42.949600] systemd[1]: Stopping Reactivate sysinit units...1215machine # [ 42.950441] systemd[1]: Reached target Reactivate sysinit units.1216machine # [ 42.953479] systemd[1]: Reached target Remote File Systems.1217machine # [ 42.959366] systemd[1]: Starting linger-users.service...1218machine # [ 42.970876] systemd[1]: Reached target Local File Systems.1219machine # [ 42.973207] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1220machine # [ 42.983673] systemd[1]: Starting Create SUID/SGID Wrappers...1221machine # [ 42.989529] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1222machine # [ 42.990825] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671223machine # [ 43.003873] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1224machine # [ 43.008216] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1225machine # [ 43.009902] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1226machine # [ 43.015182] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1227machine # [ 43.017989] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1228machine # [ 43.104150] systemd[1]: linger-users.service: Deactivated successfully.1229machine # [ 43.109214] systemd[1]: Finished linger-users.service.1230machine # [ 43.394939] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1231machine # [ 43.398614] systemd[1]: Finished Create SUID/SGID Wrappers.1232machine # [ 43.655249] nixos[1740]: finished switching to system configuration /nix/store/b5xsfk33ln102fr8jl2wvr3p0xxww71d-nixos-system-machine-test1233machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 3.17 seconds)1234machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1235machine # [ 43.740269] sudo[1880]: 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.service1236machine # [ 43.757812] sudo[1880]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1237machine # [ 43.772643] sudo[1880]: pam_unix(sudo:session): session closed for user usertest1238machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds)1239machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&11240machine # [ 44.327463] nixos[1889]: switching to system configuration /nix/store/b4dc468blcb1jxanc87pr5fwwy1ff403-nixos-system-machine-test1241machine # [ 44.330722] systemd[1]: Stopped target Remote File Systems.1242machine # [ 44.333251] systemd[1]: Stopped target Local File Systems.1243machine # [ 44.877788] systemd[1]: Reload requested from client PID 1889 ('.switch-to-conf') (unit backdoor.service)...1244machine # [ 44.879943] systemd[1]: Reloading...1245machine # [ 45.516943] systemd[1]: Reloading finished in 634 ms.1246machine # [ 45.710161] systemd[584]: Reexecution requested from client PID 1958 ('switch-to-confi')...1247machine # [ 45.711685] systemd[584]: Reexecuting.1248machine # [ 45.774755] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1249machine # [ 45.988718] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1250machine # [ 45.996419] systemd[584]: Reload requested from client PID 1958 ('switch-to-confi')...1251machine # [ 45.998868] systemd[584]: Reloading...1252machine # [ 46.209661] systemd[584]: Reloading finished in 209 ms.1253machine # [ 46.224205] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1254machine # [ 46.235445] systemd[584]: Reloading usertest.service...1255machine # [ 46.277039] systemd[584]: Reloaded usertest.service.1256machine # [ 46.282855] systemd[584]: Stopped Run user-specific NixOS activation.1257machine # [ 46.285540] systemd[584]: Stopping Run user-specific NixOS activation...1258machine # [ 46.288724] systemd[584]: Starting Run user-specific NixOS activation...1259machine # [ 46.353895] systemd[584]: Finished Run user-specific NixOS activation.1260machine # [ 46.360937] systemd[1]: Stopped target Reactivate sysinit units.1261machine # [ 46.361965] systemd[1]: Stopping Reactivate sysinit units...1262machine # [ 46.362758] systemd[1]: Reached target Reactivate sysinit units.1263machine # [ 46.382202] systemd[1]: Reached target Remote File Systems.1264machine # [ 46.386152] systemd[1]: Starting linger-users.service...1265machine # [ 46.401729] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1266machine # [ 46.403186] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1267machine # [ 46.410643] systemd[1]: Reached target Local File Systems.1268machine # [ 46.411657] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1269machine # [ 46.421008] systemd[1]: Starting Create SUID/SGID Wrappers...1270machine # [ 46.421995] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1271machine # [ 46.423229] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671272machine # [ 46.427885] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1273machine # [ 46.441404] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1274machine # [ 46.442772] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1275machine # [ 46.522288] systemd[1]: linger-users.service: Deactivated successfully.1276machine # [ 46.524338] systemd[1]: Finished linger-users.service.1277machine # [ 46.823690] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1278machine # [ 46.826735] systemd[1]: Finished Create SUID/SGID Wrappers.1279machine # [ 47.087894] nixos[1889]: finished switching to system configuration /nix/store/b4dc468blcb1jxanc87pr5fwwy1ff403-nixos-system-machine-test1280machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 3.32 seconds)1281machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1282machine # [ 47.183125] sudo[2029]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1283machine # [ 47.206831] sudo[2029]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1284machine # [ 47.224317] sudo[2029]: pam_unix(sudo:session): session closed for user usertest1285machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.13 seconds)1286machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11287machine # [ 47.816647] nixos[2038]: switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test1288machine # [ 47.819838] systemd[1]: Stopped target Local File Systems.1289machine # [ 47.822884] systemd[1]: Stopped target Remote File Systems.1290machine # [ 48.324760] systemd[1]: Reload requested from client PID 2038 ('.switch-to-conf') (unit backdoor.service)...1291machine # [ 48.326918] systemd[1]: Reloading...1292machine # [ 48.980402] systemd[1]: Reloading finished in 650 ms.1293machine # [ 49.146271] systemd[584]: Stopped usertest.service.1294machine # [ 49.150179] systemd[584]: Reexecution requested from client PID 2107 ('switch-to-confi')...1295machine # [ 49.151817] systemd[584]: Reexecuting.1296machine # [ 49.214781] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1297machine # [ 49.422959] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1298machine # [ 49.430606] systemd[584]: Reload requested from client PID 2107 ('switch-to-confi')...1299machine # [ 49.433377] systemd[584]: Reloading...1300machine # [ 49.639390] systemd[584]: Reloading finished in 204 ms.1301machine # [ 49.652198] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1302machine # [ 49.663922] systemd[584]: Starting usertest.service...1303machine # [ 49.705953] systemd[584]: Finished usertest.service.1304machine # [ 49.709172] systemd[584]: Stopped Run user-specific NixOS activation.1305machine # [ 49.711769] systemd[584]: Stopping Run user-specific NixOS activation...1306machine # [ 49.714863] systemd[584]: Starting Run user-specific NixOS activation...1307machine # [ 49.768250] systemd[584]: Finished Run user-specific NixOS activation.1308machine # [ 49.773244] systemd[1]: Stopped target Reactivate sysinit units.1309machine # [ 49.774268] systemd[1]: Stopping Reactivate sysinit units...1310machine # [ 49.777092] systemd[1]: Reached target Reactivate sysinit units.1311machine # [ 49.780885] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1312machine # [ 49.782275] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1313machine # [ 49.785966] systemd[1]: Reached target Remote File Systems.1314machine # [ 49.792701] systemd[1]: Starting linger-users.service...1315machine # [ 49.793637] systemd[1]: Reached target Local File Systems.1316machine # [ 49.806329] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1317machine # [ 49.811291] systemd[1]: Starting Create SUID/SGID Wrappers...1318machine # [ 49.815000] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1319machine # [ 49.824296] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671320machine # [ 49.826639] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1321machine # [ 49.830768] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1322machine # [ 49.833410] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1323machine # [ 49.900568] systemd[1]: linger-users.service: Deactivated successfully.1324machine # [ 49.904302] systemd[1]: Finished linger-users.service.1325machine # [ 50.198647] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1326machine # [ 50.201837] systemd[1]: Finished Create SUID/SGID Wrappers.1327machine # [ 50.460416] nixos[2038]: finished switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test1328machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.25 seconds)1329machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1330machine # [ 50.549416] sudo[2177]: 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.service1331machine # [ 50.567082] sudo[2177]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1332machine # [ 50.581985] sudo[2177]: pam_unix(sudo:session): session closed for user usertest1333machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds)1334machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&11335machine # [ 51.125192] nixos[2186]: switching to system configuration /nix/store/zcrywg5wyj4g3ilqa3j99jrvi5vbjzzk-nixos-system-machine-test1336machine # [ 51.128315] systemd[1]: Stopped target Local File Systems.1337machine # [ 51.129711] systemd[1]: Stopped target Remote File Systems.1338machine # [ 51.633072] systemd[1]: Reload requested from client PID 2186 ('.switch-to-conf') (unit backdoor.service)...1339machine # [ 51.635044] systemd[1]: Reloading...1340machine # [ 52.206102] systemd[1]: Reloading finished in 568 ms.1341machine # [ 52.378476] systemd[584]: Reexecution requested from client PID 2255 ('switch-to-confi')...1342machine # [ 52.382590] systemd[584]: Reexecuting.1343machine # [ 52.445988] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1344machine # [ 52.650992] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1345machine # [ 52.658982] systemd[584]: Reload requested from client PID 2255 ('switch-to-confi')...1346machine # [ 52.660780] systemd[584]: Reloading...1347machine # [ 52.849816] systemd[584]: Reloading finished in 187 ms.1348machine # [ 52.865652] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1349machine # [ 52.874399] systemd[584]: Reloading usertest.service...1350machine # [ 52.911958] systemd[584]: Reloaded usertest.service.1351machine # [ 52.917630] systemd[584]: Stopped Run user-specific NixOS activation.1352machine # [ 52.920291] systemd[584]: Stopping Run user-specific NixOS activation...1353machine # [ 52.923057] systemd[584]: Starting Run user-specific NixOS activation...1354machine # [ 52.970705] systemd[584]: Finished Run user-specific NixOS activation.1355machine # [ 52.975270] systemd[1]: Stopped target Reactivate sysinit units.1356machine # [ 52.977277] systemd[1]: Stopping Reactivate sysinit units...1357machine # [ 52.978138] systemd[1]: Reached target Reactivate sysinit units.1358machine # [ 52.979555] systemd[1]: Reached target Remote File Systems.1359machine # [ 52.982254] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1360machine # [ 52.984686] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1361machine # [ 52.989002] systemd[1]: Reached target Local File Systems.1362machine # [ 52.989847] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1363machine # [ 52.995966] systemd[1]: Starting Create SUID/SGID Wrappers...1364machine # [ 52.997482] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1365machine # [ 52.998706] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671366machine # [ 53.001976] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1367machine # [ 53.004969] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1368machine # [ 53.014450] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1369machine # [ 53.339881] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1370machine # [ 53.344949] systemd[1]: Finished Create SUID/SGID Wrappers.1371machine # [ 53.347255] systemd[1]: Starting linger-users.service...1372machine # [ 53.410206] systemd[1]: linger-users.service: Deactivated successfully.1373machine # [ 53.412134] systemd[1]: Finished linger-users.service.1374machine # [ 53.669935] nixos[2186]: finished switching to system configuration /nix/store/zcrywg5wyj4g3ilqa3j99jrvi5vbjzzk-nixos-system-machine-test1375machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 3.09 seconds)1376machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1377machine # [ 53.741281] sudo[2325]: 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.service1378machine # [ 53.760895] sudo[2325]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1379machine # [ 53.775392] sudo[2325]: pam_unix(sudo:session): session closed for user usertest1380machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds)1381machine: must fail: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&11382machine # [ 54.333840] nixos[2334]: switching to system configuration /nix/store/rf8f0cn1k0z7ax1wdy9nmfln17hafc3w-nixos-system-machine-test1383machine # [ 54.336918] systemd[1]: Stopped target Remote File Systems.1384machine # [ 54.339623] systemd[1]: Stopped target Local File Systems.1385machine # [ 54.847517] systemd[1]: Reload requested from client PID 2334 ('.switch-to-conf') (unit backdoor.service)...1386machine # [ 54.849994] systemd[1]: Reloading...1387machine # [ 55.462223] systemd[1]: Reloading finished in 608 ms.1388machine # [ 55.638923] systemd[584]: Stopped usertest.service.1389machine # [ 55.642926] systemd[584]: Reexecution requested from client PID 2403 ('switch-to-confi')...1390machine # [ 55.645610] systemd[584]: Reexecuting.1391machine # [ 55.715138] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1392machine # [ 55.935567] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1393machine # [ 55.943371] systemd[584]: Reload requested from client PID 2403 ('switch-to-confi')...1394machine # [ 55.947646] systemd[584]: Reloading...1395machine # [ 56.147758] systemd[584]: Reloading finished in 201 ms.1396machine # [ 56.160189] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1397machine # [ 56.171923] systemd[584]: Starting usertest.service...1398machine # [ 56.214921] systemd[584]: usertest.service: Main process exited, code=exited, status=1/FAILURE1399machine # [ 56.217889] systemd[584]: usertest.service: Failed with result 'exit-code'.1400machine # [ 56.219283] systemd[584]: Failed to start usertest.service.1401machine # [ 56.223822] systemd[584]: Stopped Run user-specific NixOS activation.1402machine # [ 56.225228] systemd[584]: Stopping Run user-specific NixOS activation...1403machine # [ 56.227846] systemd[584]: Starting Run user-specific NixOS activation...1404machine # [ 56.277188] systemd[584]: Finished Run user-specific NixOS activation.1405machine # [ 56.282221] systemd[1]: Stopped target Reactivate sysinit units.1406machine # [ 56.283742] systemd[1]: Stopping Reactivate sysinit units...1407machine # [ 56.286393] systemd[1]: Reached target Reactivate sysinit units.1408machine # [ 56.291977] systemd[1]: Reached target Remote File Systems.1409machine # [ 56.295848] systemd[1]: Reached target Local File Systems.1410machine # [ 56.299873] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1411machine # [ 56.304341] systemd[1]: Starting Create SUID/SGID Wrappers...1412machine # [ 56.305347] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1413machine # [ 56.306586] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671414machine # [ 56.315029] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1415machine # [ 56.316977] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1416machine # [ 56.318686] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1417machine # [ 56.320103] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1418machine # [ 56.324277] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1419machine # [ 56.709217] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1420machine # [ 56.712652] systemd[1]: Finished Create SUID/SGID Wrappers.1421machine # [ 56.718402] systemd[1]: Starting linger-users.service...1422machine # [ 56.789172] systemd[1]: linger-users.service: Deactivated successfully.1423machine # [ 56.792256] systemd[1]: Finished linger-users.service.1424machine # [ 57.051240] nixos[2334]: switching to system configuration /nix/store/rf8f0cn1k0z7ax1wdy9nmfln17hafc3w-nixos-system-machine-test failed (status 4)1425machine: (finished: must fail: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 3.29 seconds)1426machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11427machine # [ 57.691470] nixos[2476]: switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test1428machine # [ 57.694994] systemd[1]: Stopped target Local File Systems.1429machine # [ 57.696757] systemd[1]: Stopped target Remote File Systems.1430machine # [ 58.159310] systemd[1]: Reload requested from client PID 2476 ('.switch-to-conf') (unit backdoor.service)...1431machine # [ 58.161589] systemd[1]: Reloading...1432machine # [ 58.759067] systemd[1]: Reloading finished in 595 ms.1433machine # [ 58.909813] systemd[584]: Reexecution requested from client PID 2544 ('switch-to-confi')...1434machine # [ 58.911305] systemd[584]: Reexecuting.1435machine # [ 58.983149] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1436machine # [ 59.186284] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1437machine # [ 59.193398] systemd[584]: Reload requested from client PID 2544 ('switch-to-confi')...1438machine # [ 59.195325] systemd[584]: Reloading...1439machine # [ 59.393838] systemd[584]: Reloading finished in 198 ms.1440machine # [ 59.409733] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1441machine # [ 59.419952] systemd[584]: Starting usertest.service...1442machine # [ 59.456637] systemd[584]: Finished usertest.service.1443machine # [ 59.460126] systemd[584]: Stopped Run user-specific NixOS activation.1444machine # [ 59.461341] systemd[584]: Stopping Run user-specific NixOS activation...1445machine # [ 59.465440] systemd[584]: Starting Run user-specific NixOS activation...1446machine # [ 59.514599] systemd[584]: Finished Run user-specific NixOS activation.1447machine # [ 59.518982] systemd[1]: Stopped target Reactivate sysinit units.1448machine # [ 59.521992] systemd[1]: Stopping Reactivate sysinit units...1449machine # [ 59.522872] systemd[1]: Reached target Reactivate sysinit units.1450machine # [ 59.523720] systemd[1]: Reached target Remote File Systems.1451machine # [ 59.529743] systemd[1]: Reached target Local File Systems.1452machine # [ 59.530935] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1453machine # [ 59.534759] systemd[1]: Starting Create SUID/SGID Wrappers...1454machine # [ 59.535618] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1455machine # [ 59.539526] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671456machine # [ 59.542547] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1457machine # [ 59.549343] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1458machine # [ 59.550619] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1459machine # [ 59.558560] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1460machine # [ 59.561767] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1461machine # [ 59.899294] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1462machine # [ 59.902181] systemd[1]: Finished Create SUID/SGID Wrappers.1463machine # [ 59.907899] systemd[1]: Starting linger-users.service...1464machine # [ 59.979408] systemd[1]: linger-users.service: Deactivated successfully.1465machine # [ 59.982443] systemd[1]: Finished linger-users.service.1466machine # [ 60.240930] nixos[2476]: finished switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test1467machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.19 seconds)1468machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1469machine # [ 60.328756] sudo[2615]: 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.service1470machine # [ 60.349067] sudo[2615]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1471machine # [ 60.364730] sudo[2615]: pam_unix(sudo:session): session closed for user usertest1472machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds)1473machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&11474machine # [ 60.930641] nixos[2624]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test1475machine # [ 60.933417] systemd[1]: Stopped target Remote File Systems.1476machine # [ 60.935741] systemd[1]: Stopped target Local File Systems.1477machine # [ 61.404430] systemd[1]: Reload requested from client PID 2624 ('.switch-to-conf') (unit backdoor.service)...1478machine # [ 61.406622] systemd[1]: Reloading...1479machine # [ 62.021866] systemd[1]: Reloading finished in 612 ms.1480machine # [ 62.180365] systemd[584]: Stopped usertest.service.1481machine # [ 62.184237] systemd[584]: Reexecution requested from client PID 2693 ('switch-to-confi')...1482machine # [ 62.186453] systemd[584]: Reexecuting.1483machine # [ 62.246222] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1484machine # [ 62.442542] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1485machine # [ 62.449792] systemd[584]: Reload requested from client PID 2693 ('switch-to-confi')...1486machine # [ 62.452262] systemd[584]: Reloading...1487machine # [ 62.647691] systemd[584]: Reloading finished in 194 ms.1488machine # [ 62.660163] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1489machine # [ 62.670380] systemd[584]: Stopped Run user-specific NixOS activation.1490machine # [ 62.671514] systemd[584]: Stopping Run user-specific NixOS activation...1491machine # [ 62.674511] systemd[584]: Starting Run user-specific NixOS activation...1492machine # [ 62.722517] systemd[584]: Finished Run user-specific NixOS activation.1493machine # [ 62.726776] systemd[1]: Stopped target Reactivate sysinit units.1494machine # [ 62.727777] systemd[1]: Stopping Reactivate sysinit units...1495machine # [ 62.730829] systemd[1]: Reached target Reactivate sysinit units.1496machine # [ 62.736078] systemd[1]: Reached target Local File Systems.1497machine # [ 62.739683] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1498machine # [ 62.744096] systemd[1]: Starting Create SUID/SGID Wrappers...1499machine # [ 62.744987] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1500machine # [ 62.746169] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671501machine # [ 62.758268] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1502machine # [ 62.762735] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1503machine # [ 62.764241] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1504machine # [ 62.765443] systemd[1]: Reached target Remote File Systems.1505machine # [ 62.766241] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1506machine # [ 62.767512] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1507machine # [ 63.101466] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1508machine # [ 63.104152] systemd[1]: Finished Create SUID/SGID Wrappers.1509machine # [ 63.109842] systemd[1]: Starting linger-users.service...1510machine # [ 63.179007] systemd[1]: linger-users.service: Deactivated successfully.1511machine # [ 63.181907] systemd[1]: Finished linger-users.service.1512machine # [ 63.441879] nixos[2624]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test1513machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.09 seconds)1514machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1515machine # [ 63.533972] sudo[2761]: 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.service1516machine # [ 63.554862] sudo[2761]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1517machine # [ 63.573992] sudo[2761]: pam_unix(sudo:session): session closed for user usertest1518machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.12 seconds)1519machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1520machine # [ 63.630884] sudo[2767]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1521machine # [ 63.646372] sudo[2767]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1522machine # [ 63.660668] sudo[2767]: pam_unix(sudo:session): session closed for user usertest1523machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.09 seconds)1524machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service1525machine # [ 63.711033] sudo[2773]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service /home/usertest/.config/systemd/user/migrated.service1526machine # [ 63.725776] sudo[2773]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1527machine # [ 63.741666] sudo[2773]: pam_unix(sudo:session): session closed for user usertest1528machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.08 seconds)1529machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1530machine # [ 63.791991] sudo[2779]: 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.service1531machine # [ 63.806913] sudo[2779]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1532machine # [ 63.821710] sudo[2779]: pam_unix(sudo:session): session closed for user usertest1533machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.08 seconds)1534machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1535machine # [ 63.871362] sudo[2785]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1536machine # [ 63.885245] sudo[2785]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1537machine # [ 63.896245] systemd[584]: Reload requested from client PID 2790 ('systemctl')...1538machine # [ 63.898607] systemd[584]: Reloading...1539machine # [ 64.103207] systemd[584]: Reloading finished in 202 ms.1540machine # [ 64.116232] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1541machine # [ 64.131249] sudo[2785]: pam_unix(sudo:session): session closed for user usertest1542machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.31 seconds)1543machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1544machine # [ 64.183862] sudo[2795]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1545machine # [ 64.198590] sudo[2795]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1546machine # [ 64.217501] systemd[584]: Starting migrated.service...1547machine # [ 64.262099] systemd[584]: Finished migrated.service.1548machine # [ 64.267103] sudo[2795]: pam_unix(sudo:session): session closed for user usertest1549machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.14 seconds)1550machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1551machine # [ 64.318869] sudo[2803]: 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.service1552machine # [ 64.334049] sudo[2803]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1553machine # [ 64.348119] sudo[2803]: pam_unix(sudo:session): session closed for user usertest1554machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)1555machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1556machine # [ 64.400993] sudo[2809]: 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-owner1557machine # [ 64.415295] sudo[2809]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1558machine # [ 64.430585] sudo[2809]: pam_unix(sudo:session): session closed for user usertest1559machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)1560machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1561machine # [ 64.479902] sudo[2815]: 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.service1562machine # [ 64.494615] sudo[2815]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1563machine # [ 64.519930] sudo[2815]: pam_unix(sudo:session): session closed for user usertest1564machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.09 seconds)1565machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&11566machine # [ 65.083411] nixos[2824]: switching to system configuration /nix/store/mnl5fks2p8jdjjd37njx5fj01z5i2yjg-nixos-system-machine-test1567machine # [ 65.086908] systemd[1]: Stopped target Local File Systems.1568machine # [ 65.088353] systemd[1]: Stopped target Remote File Systems.1569machine # [ 65.578500] systemd[1]: Reload requested from client PID 2824 ('.switch-to-conf') (unit backdoor.service)...1570machine # [ 65.580657] systemd[1]: Reloading...1571machine # [ 66.162053] systemd[1]: Reloading finished in 579 ms.1572machine # [ 66.339058] systemd[584]: Reexecution requested from client PID 2893 ('switch-to-confi')...1573machine # [ 66.343220] systemd[584]: Reexecuting.1574machine # [ 66.399208] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1575machine # [ 66.612290] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1576machine # [ 66.619505] systemd[584]: Reload requested from client PID 2893 ('switch-to-confi')...1577machine # [ 66.624167] systemd[584]: Reloading...1578machine # [ 66.828142] systemd[584]: Reloading finished in 204 ms.1579machine # [ 66.844487] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1580machine # [ 66.855192] systemd[584]: Stopped Run user-specific NixOS activation.1581machine # [ 66.858003] systemd[584]: Stopping Run user-specific NixOS activation...1582machine # [ 66.861218] systemd[584]: Starting Run user-specific NixOS activation...1583machine # [ 66.963859] systemd[584]: Reload requested from client PID 2905 ('systemctl') (unit nixos-activation.service)...1584machine # [ 66.969274] systemd[584]: Reloading...1585machine # [ 67.182359] systemd[584]: Reloading finished in 214 ms.1586machine # [ 67.196202] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1587machine # [ 67.225035] systemd[584]: Stopped migrated.service.1588machine # [ 67.239960] systemd[584]: Finished Run user-specific NixOS activation.1589machine # [ 67.243225] systemd[584]: Reload requested from client PID 2893 ('switch-to-confi')...1590machine # [ 67.248325] systemd[584]: Reloading...1591machine # [ 67.452579] systemd[584]: Reloading finished in 205 ms.1592machine # [ 67.468190] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1593machine # [ 67.482551] systemd[584]: Starting migrated.service...1594machine # [ 67.523889] systemd[584]: Finished migrated.service.1595machine # [ 67.529054] systemd[1]: Stopped target Reactivate sysinit units.1596machine # [ 67.533067] systemd[1]: Stopping Reactivate sysinit units...1597machine # [ 67.533979] systemd[1]: Reached target Reactivate sysinit units.1598machine # [ 67.534861] systemd[1]: Reached target Local File Systems.1599machine # [ 67.537887] systemd[1]: Reached target Remote File Systems.1600machine # [ 67.542335] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1601machine # [ 67.550230] systemd[1]: Starting Create SUID/SGID Wrappers...1602machine # [ 67.554048] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1603machine # [ 67.555373] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671604machine # [ 67.565664] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1605machine # [ 67.567325] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1606machine # [ 67.569203] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1607machine # [ 67.570436] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1608machine # [ 67.571738] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1609machine # [ 67.913046] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1610machine # [ 67.918115] systemd[1]: Finished Create SUID/SGID Wrappers.1611machine # [ 67.921164] systemd[1]: Starting linger-users.service...1612machine # [ 67.988810] systemd[1]: linger-users.service: Deactivated successfully.1613machine # [ 67.991581] systemd[1]: Finished linger-users.service.1614machine # [ 68.251265] nixos[2824]: finished switching to system configuration /nix/store/mnl5fks2p8jdjjd37njx5fj01z5i2yjg-nixos-system-machine-test1615machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 3.74 seconds)1616machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1617machine # [ 68.340868] sudo[2975]: 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.service1618machine # [ 68.361371] sudo[2975]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1619machine # [ 68.376824] sudo[2975]: pam_unix(sudo:session): session closed for user usertest1620machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.12 seconds)1621machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1622machine # [ 68.431459] sudo[2981]: 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.service1623machine # [ 68.446381] sudo[2981]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1624machine # [ 68.477119] sudo[2981]: pam_unix(sudo:session): session closed for user usertest1625machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds)1626machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1627machine # [ 68.528295] sudo[2987]: 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-owner1628machine # [ 68.541911] sudo[2987]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1629machine # [ 68.554514] sudo[2987]: pam_unix(sudo:session): session closed for user usertest1630machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)1631machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&11632machine # [ 69.083697] nixos[2996]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test1633machine # [ 69.086437] systemd[1]: Stopped target Remote File Systems.1634machine # [ 69.088836] systemd[1]: Stopped target Local File Systems.1635machine # [ 69.543908] systemd[1]: Reload requested from client PID 2996 ('.switch-to-conf') (unit backdoor.service)...1636machine # [ 69.546205] systemd[1]: Reloading...1637machine # [ 70.150971] systemd[1]: Reloading finished in 602 ms.1638machine # [ 70.303968] systemd[584]: Stopped migrated.service.1639machine # [ 70.307225] systemd[584]: Reexecution requested from client PID 3065 ('switch-to-confi')...1640machine # [ 70.309954] systemd[584]: Reexecuting.1641machine # [ 70.378746] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1642machine # [ 70.573075] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1643machine # [ 70.579585] systemd[584]: Reload requested from client PID 3065 ('switch-to-confi')...1644machine # [ 70.583637] systemd[584]: Reloading...1645machine # [ 70.777424] systemd[584]: Reloading finished in 194 ms.1646machine # [ 70.792190] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1647machine # [ 70.801747] systemd[584]: Stopped Run user-specific NixOS activation.1648machine # [ 70.804138] systemd[584]: Stopping Run user-specific NixOS activation...1649machine # [ 70.807221] systemd[584]: Starting Run user-specific NixOS activation...1650machine # [ 70.853943] systemd[584]: Finished Run user-specific NixOS activation.1651machine # [ 70.858030] systemd[1]: Stopped target Reactivate sysinit units.1652machine # [ 70.859992] systemd[1]: Stopping Reactivate sysinit units...1653machine # [ 70.861484] systemd[1]: Reached target Reactivate sysinit units.1654machine # [ 70.863499] systemd[1]: Reached target Local File Systems.1655machine # [ 70.868593] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1656machine # [ 70.872279] systemd[1]: Starting Create SUID/SGID Wrappers...1657machine # [ 70.873211] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1658machine # [ 70.874419] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671659machine # [ 70.888380] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1660machine # [ 70.892817] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1661machine # [ 70.894168] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1662machine # [ 70.895379] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1663machine # [ 70.896967] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1664machine # [ 70.898253] systemd[1]: Reached target Remote File Systems.1665machine # [ 71.238318] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1666machine # [ 71.242886] systemd[1]: Finished Create SUID/SGID Wrappers.1667machine # [ 71.245585] systemd[1]: Starting linger-users.service...1668machine # [ 71.310909] systemd[1]: linger-users.service: Deactivated successfully.1669machine # [ 71.313936] systemd[1]: Finished linger-users.service.1670machine # [ 71.570885] nixos[2996]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test1671machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.03 seconds)1672machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1673machine # [ 71.657695] sudo[3133]: 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.service1674machine # [ 71.676862] sudo[3133]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1675machine # [ 71.692969] sudo[3133]: pam_unix(sudo:session): session closed for user usertest1676machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds)1677machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1678machine # [ 71.744713] sudo[3139]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1679machine # [ 71.759216] sudo[3139]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1680machine # [ 71.773946] sudo[3139]: pam_unix(sudo:session): session closed for user usertest1681machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.08 seconds)1682machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service1683machine # [ 71.823115] sudo[3145]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service /home/usertest/.config/systemd/user/migrated.service1684machine # [ 71.838482] sudo[3145]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1685machine # [ 71.851841] sudo[3145]: pam_unix(sudo:session): session closed for user usertest1686machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.08 seconds)1687machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1688machine # [ 71.899256] sudo[3151]: 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.service1689machine # [ 71.914099] sudo[3151]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1690machine # [ 71.927236] sudo[3151]: pam_unix(sudo:session): session closed for user usertest1691machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.08 seconds)1692machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1693machine # [ 71.975923] sudo[3157]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1694machine # [ 71.989643] sudo[3157]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1695machine # [ 72.000514] systemd[584]: Reload requested from client PID 3162 ('systemctl')...1696machine # [ 72.003160] systemd[584]: Reloading...1697machine # [ 72.198882] systemd[584]: Reloading finished in 194 ms.1698machine # [ 72.213625] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1699machine # [ 72.225742] sudo[3157]: pam_unix(sudo:session): session closed for user usertest1700machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.30 seconds)1701machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1702machine # [ 72.273371] sudo[3167]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1703machine # [ 72.286827] sudo[3167]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1704machine # [ 72.303753] systemd[584]: Starting migrated.service...1705machine # [ 72.338254] systemd[584]: Finished migrated.service.1706machine # [ 72.342664] sudo[3167]: pam_unix(sudo:session): session closed for user usertest1707machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.12 seconds)1708machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1709machine # [ 72.391867] sudo[3175]: 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.service1710machine # [ 72.405759] sudo[3175]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1711machine # [ 72.418288] sudo[3175]: pam_unix(sudo:session): session closed for user usertest1712machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)1713machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1714machine # [ 72.466750] sudo[3181]: 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-owner1715machine # [ 72.480769] sudo[3181]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1716machine # [ 72.493723] sudo[3181]: pam_unix(sudo:session): session closed for user usertest1717machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)1718machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1719machine # [ 72.540763] sudo[3187]: 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.service1720machine # [ 72.554348] sudo[3187]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1721machine # [ 72.577387] sudo[3187]: pam_unix(sudo:session): session closed for user usertest1722machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds)1723machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&11724machine # [ 73.110703] nixos[3196]: switching to system configuration /nix/store/nwp1vqhdqmv4dkl7jq71c76m9skyrmc2-nixos-system-machine-test1725machine # [ 73.114856] systemd[1]: Stopped target Local File Systems.1726machine # [ 73.116109] systemd[1]: Stopped target Remote File Systems.1727machine # [ 73.618336] systemd[1]: Reload requested from client PID 3196 ('.switch-to-conf') (unit backdoor.service)...1728machine # [ 73.620430] systemd[1]: Reloading...1729machine # [ 74.213541] systemd[1]: Reloading finished in 589 ms.1730machine # [ 74.378512] systemd[584]: Reexecution requested from client PID 3264 ('switch-to-confi')...1731machine # [ 74.382170] systemd[584]: Reexecuting.1732machine # [ 74.445331] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1733machine # [ 74.649590] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1734machine # [ 74.657219] systemd[584]: Reload requested from client PID 3264 ('switch-to-confi')...1735machine # [ 74.660006] systemd[584]: Reloading...1736machine # [ 74.855398] systemd[584]: Reloading finished in 194 ms.1737machine # [ 74.872174] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1738machine # [ 74.881285] systemd[584]: Stopped Run user-specific NixOS activation.1739machine # [ 74.883704] systemd[584]: Stopping Run user-specific NixOS activation...1740machine # [ 74.886738] systemd[584]: Starting Run user-specific NixOS activation...1741machine # [ 74.947976] systemd[584]: Reload requested from client PID 3277 ('systemctl') (unit nixos-activation.service)...1742machine # [ 74.950738] systemd[584]: Reloading...1743machine # [ 75.155564] systemd[584]: Reloading finished in 203 ms.1744machine # [ 75.168180] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1745machine # [ 75.187613] systemd[584]: Finished Run user-specific NixOS activation.1746machine # [ 75.190659] systemd[584]: Reload requested from client PID 3264 ('switch-to-confi')...1747machine # [ 75.193367] systemd[584]: Reloading...1748machine # [ 75.393400] systemd[584]: Reloading finished in 198 ms.1749machine # [ 75.408191] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1750machine # [ 75.418822] systemd[584]: Stopped migrated.service.1751machine # [ 75.420977] systemd[584]: Stopping migrated.service...1752machine # [ 75.423836] systemd[584]: Starting migrated.service...1753machine # [ 75.464322] systemd[584]: Finished migrated.service.1754machine # [ 75.471090] systemd[1]: Stopped target Reactivate sysinit units.1755machine # [ 75.474098] systemd[1]: Stopping Reactivate sysinit units...1756machine # [ 75.475007] systemd[1]: Reached target Reactivate sysinit units.1757machine # [ 75.475863] systemd[1]: Reached target Remote File Systems.1758machine # [ 75.478899] systemd[1]: Reached target Local File Systems.1759machine # [ 75.482563] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1760machine # [ 75.486985] systemd[1]: Starting Create SUID/SGID Wrappers...1761machine # [ 75.487949] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1762machine # [ 75.491495] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671763machine # [ 75.498118] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1764machine # [ 75.504852] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1765machine # [ 75.506218] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1766machine # [ 75.507456] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1767machine # [ 75.511847] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1768machine # [ 75.843644] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1769machine # [ 75.848702] systemd[1]: Finished Create SUID/SGID Wrappers.1770machine # [ 75.853150] systemd[1]: Starting linger-users.service...1771machine # [ 75.918865] systemd[1]: linger-users.service: Deactivated successfully.1772machine # [ 75.921871] systemd[1]: Finished linger-users.service.1773machine # [ 76.178878] nixos[3196]: finished switching to system configuration /nix/store/nwp1vqhdqmv4dkl7jq71c76m9skyrmc2-nixos-system-machine-test1774machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 3.61 seconds)1775machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1776machine # [ 76.269923] sudo[3346]: 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.service1777machine # [ 76.293204] sudo[3346]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1778machine # [ 76.310639] sudo[3346]: pam_unix(sudo:session): session closed for user usertest1779machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.12 seconds)1780machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1781machine # [ 76.362911] sudo[3352]: 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.service1782machine # [ 76.378266] sudo[3352]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1783machine # [ 76.406309] sudo[3352]: pam_unix(sudo:session): session closed for user usertest1784machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds)1785machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1786machine # [ 76.455296] sudo[3358]: 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-owner1787machine # [ 76.470417] sudo[3358]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1788machine # [ 76.483826] sudo[3358]: pam_unix(sudo:session): session closed for user usertest1789machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)1790machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&11791machine # [ 77.033058] nixos[3367]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test1792machine # [ 77.035860] systemd[1]: Stopped target Remote File Systems.1793machine # [ 77.038266] systemd[1]: Stopped target Local File Systems.1794machine # [ 77.501734] systemd[1]: Reload requested from client PID 3367 ('.switch-to-conf') (unit backdoor.service)...1795machine # [ 77.503745] systemd[1]: Reloading...1796machine # [ 78.074602] systemd[1]: Reloading finished in 568 ms.1797machine # [ 78.218842] systemd[584]: Stopped migrated.service.1798machine # [ 78.222763] systemd[584]: Reexecution requested from client PID 3436 ('switch-to-confi')...1799machine # [ 78.226401] systemd[584]: Reexecuting.1800machine # [ 78.281803] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1801machine # [ 78.462621] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1802machine # [ 78.469939] systemd[584]: Reload requested from client PID 3436 ('switch-to-confi')...1803machine # [ 78.472444] systemd[584]: Reloading...1804machine # [ 78.655571] systemd[584]: Reloading finished in 182 ms.1805machine # [ 78.672524] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1806machine # [ 78.683675] systemd[584]: Stopped Run user-specific NixOS activation.1807machine # [ 78.686356] systemd[584]: Stopping Run user-specific NixOS activation...1808machine # [ 78.689354] systemd[584]: Starting Run user-specific NixOS activation...1809machine # [ 78.737972] systemd[584]: Finished Run user-specific NixOS activation.1810machine # [ 78.742641] systemd[1]: Stopped target Reactivate sysinit units.1811machine # [ 78.744163] systemd[1]: Stopping Reactivate sysinit units...1812machine # [ 78.745007] systemd[1]: Reached target Reactivate sysinit units.1813machine # [ 78.747973] systemd[1]: Reached target Remote File Systems.1814machine # [ 78.753314] systemd[1]: Reached target Local File Systems.1815machine # [ 78.756846] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1816machine # [ 78.758228] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1817machine # [ 78.759480] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1818machine # [ 78.765133] systemd[1]: Starting Create SUID/SGID Wrappers...1819machine # [ 78.766111] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1820machine # [ 78.767312] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671821machine # [ 78.772936] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1822machine # [ 78.782289] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1823machine # [ 78.783709] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1824machine # [ 79.113432] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1825machine # [ 79.118193] systemd[1]: Finished Create SUID/SGID Wrappers.1826machine # [ 79.121030] systemd[1]: Starting linger-users.service...1827machine # [ 79.186435] systemd[1]: linger-users.service: Deactivated successfully.1828machine # [ 79.188524] systemd[1]: Finished linger-users.service.1829machine # [ 79.450651] nixos[3367]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test1830machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.98 seconds)1831machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1832machine # [ 79.549142] sudo[3504]: 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.service1833machine # [ 79.569557] sudo[3504]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1834machine # [ 79.587067] sudo[3504]: pam_unix(sudo:session): session closed for user usertest1835machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.12 seconds)1836machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1837machine # [ 79.637663] sudo[3510]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1838machine # [ 79.651251] sudo[3510]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1839machine # [ 79.663448] sudo[3510]: pam_unix(sudo:session): session closed for user usertest1840machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.08 seconds)1841machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service1842machine # [ 79.711072] sudo[3516]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service /home/usertest/.config/systemd/user/migrated.service1843machine # [ 79.726157] sudo[3516]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1844machine # [ 79.739727] sudo[3516]: pam_unix(sudo:session): session closed for user usertest1845machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.08 seconds)1846machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1847machine # [ 79.789668] sudo[3522]: 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.service1848machine # [ 79.805049] sudo[3522]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1849machine # [ 79.818524] sudo[3522]: pam_unix(sudo:session): session closed for user usertest1850machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.08 seconds)1851machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1852machine # [ 79.871145] sudo[3528]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1853machine # [ 79.885707] sudo[3528]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1854machine # [ 79.898155] systemd[584]: Reload requested from client PID 3533 ('systemctl')...1855machine # [ 79.901317] systemd[584]: Reloading...1856machine # [ 80.102972] systemd[584]: Reloading finished in 200 ms.1857machine # [ 80.116221] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1858machine # [ 80.128058] sudo[3528]: pam_unix(sudo:session): session closed for user usertest1859machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.31 seconds)1860machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1861machine # [ 80.179399] sudo[3538]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1862machine # [ 80.194077] sudo[3538]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1863machine # [ 80.211206] systemd[584]: Starting migrated.service...1864machine # [ 80.247471] systemd[584]: Finished migrated.service.1865machine # [ 80.252506] sudo[3538]: pam_unix(sudo:session): session closed for user usertest1866machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.12 seconds)1867machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1868machine # [ 80.301034] sudo[3546]: 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.service1869machine # [ 80.314849] sudo[3546]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1870machine # [ 80.328089] sudo[3546]: pam_unix(sudo:session): session closed for user usertest1871machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)1872machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1873machine # [ 80.379287] sudo[3552]: 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-owner1874machine # [ 80.393168] sudo[3552]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1875machine # [ 80.406461] sudo[3552]: pam_unix(sudo:session): session closed for user usertest1876machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)1877machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1878machine # [ 80.455386] sudo[3558]: 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.service1879machine # [ 80.469934] sudo[3558]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1880machine # [ 80.493398] sudo[3558]: pam_unix(sudo:session): session closed for user usertest1881machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.09 seconds)1882machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&11883machine # [ 81.043673] nixos[3567]: switching to system configuration /nix/store/i1m50dqv1p2ykm1057rmb6ssfin360f2-nixos-system-machine-test1884machine # [ 81.047571] systemd[1]: Stopped target Local File Systems.1885machine # [ 81.049068] systemd[1]: Stopped target Remote File Systems.1886machine # [ 81.586711] systemd[1]: Reload requested from client PID 3567 ('.switch-to-conf') (unit backdoor.service)...1887machine # [ 81.588941] systemd[1]: Reloading...1888machine # [ 82.177154] systemd[1]: Reloading finished in 585 ms.1889machine # [ 82.350823] systemd[584]: Reexecution requested from client PID 3636 ('switch-to-confi')...1890machine # [ 82.353603] systemd[584]: Reexecuting.1891machine # [ 82.418269] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1892machine # [ 82.626716] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1893machine # [ 82.633910] systemd[584]: Reload requested from client PID 3636 ('switch-to-confi')...1894machine # [ 82.636796] systemd[584]: Reloading...1895machine # [ 82.834872] systemd[584]: Reloading finished in 196 ms.1896machine # [ 82.848212] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1897machine # [ 82.858478] systemd[584]: Stopped Run user-specific NixOS activation.1898machine # [ 82.859582] systemd[584]: Stopping Run user-specific NixOS activation...1899machine # [ 82.862709] systemd[584]: Starting Run user-specific NixOS activation...1900machine # [ 82.924624] systemd[584]: Reload requested from client PID 3648 ('systemctl') (unit nixos-activation.service)...1901machine # [ 82.927645] systemd[584]: Reloading...1902machine # [ 83.133930] systemd[584]: Reloading finished in 204 ms.1903machine # [ 83.148227] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1904machine # [ 83.170455] systemd[584]: Finished Run user-specific NixOS activation.1905machine # [ 83.173670] systemd[584]: Reload requested from client PID 3636 ('switch-to-confi')...1906machine # [ 83.177120] systemd[584]: Reloading...1907machine # [ 83.387549] systemd[584]: Reloading finished in 209 ms.1908machine # [ 83.400274] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1909machine # [ 83.414759] systemd[584]: Reloading migrated.service...1910machine # [ 83.455557] systemd[584]: Reloaded migrated.service.1911machine # [ 83.465865] systemd[1]: Stopped target Reactivate sysinit units.1912machine # [ 83.466913] systemd[1]: Stopping Reactivate sysinit units...1913machine # [ 83.467796] systemd[1]: Reached target Reactivate sysinit units.1914machine # [ 83.470841] systemd[1]: Reached target Local File Systems.1915machine # [ 83.474819] systemd[1]: Reached target Remote File Systems.1916machine # [ 83.483711] systemd[1]: Starting linger-users.service...1917machine # [ 83.487307] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1918machine # [ 83.498451] systemd[1]: Starting Create SUID/SGID Wrappers...1919machine # [ 83.499476] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1920machine # [ 83.503669] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671921machine # [ 83.516454] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1922machine # [ 83.518087] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1923machine # [ 83.519363] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1924machine # [ 83.523451] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1925machine # [ 83.525517] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1926machine # [ 83.598176] systemd[1]: linger-users.service: Deactivated successfully.1927machine # [ 83.601897] systemd[1]: Finished linger-users.service.1928machine # [ 83.900470] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1929machine # [ 83.904543] systemd[1]: Finished Create SUID/SGID Wrappers.1930machine # [ 84.163004] nixos[3567]: finished switching to system configuration /nix/store/i1m50dqv1p2ykm1057rmb6ssfin360f2-nixos-system-machine-test1931machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 3.68 seconds)1932machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1933machine # [ 84.258717] sudo[3717]: 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.service1934machine # [ 84.278338] sudo[3717]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1935machine # [ 84.293807] sudo[3717]: pam_unix(sudo:session): session closed for user usertest1936machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.12 seconds)1937machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1938machine # [ 84.347655] sudo[3723]: 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-owner1939machine # [ 84.362284] sudo[3723]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1940machine # [ 84.375586] sudo[3723]: pam_unix(sudo:session): session closed for user usertest1941machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)1942machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&11943machine # [ 84.947201] nixos[3732]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test1944machine # [ 84.951493] systemd[1]: Stopped target Local File Systems.1945machine # [ 84.952802] systemd[1]: Stopped target Remote File Systems.1946machine # [ 85.423664] systemd[1]: Reload requested from client PID 3732 ('.switch-to-conf') (unit backdoor.service)...1947machine # [ 85.425992] systemd[1]: Reloading...1948machine # [ 86.057640] systemd[1]: Reloading finished in 629 ms.1949machine # [ 86.221237] systemd[584]: Stopped migrated.service.1950machine # [ 86.224609] systemd[584]: Reexecution requested from client PID 3801 ('switch-to-confi')...1951machine # [ 86.228141] systemd[584]: Reexecuting.1952machine # [ 86.295000] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1953machine # [ 86.506598] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1954machine # [ 86.514036] systemd[584]: Reload requested from client PID 3801 ('switch-to-confi')...1955machine # [ 86.517137] systemd[584]: Reloading...1956machine # [ 86.717762] systemd[584]: Reloading finished in 200 ms.1957machine # [ 86.732207] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1958machine # [ 86.743317] systemd[584]: Stopped Run user-specific NixOS activation.1959machine # [ 86.746022] systemd[584]: Stopping Run user-specific NixOS activation...1960machine # [ 86.749084] systemd[584]: Starting Run user-specific NixOS activation...1961machine # [ 86.802073] systemd[584]: Finished Run user-specific NixOS activation.1962machine # [ 86.806949] systemd[1]: Stopped target Reactivate sysinit units.1963machine # [ 86.808487] systemd[1]: Stopping Reactivate sysinit units...1964machine # [ 86.809443] systemd[1]: Reached target Reactivate sysinit units.1965machine # [ 86.813406] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1966machine # [ 86.816223] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1967machine # [ 86.819175] systemd[1]: Reached target Local File Systems.1968machine # [ 86.823197] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1969machine # [ 86.830933] systemd[1]: Starting Create SUID/SGID Wrappers...1970machine # [ 86.831964] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1971machine # [ 86.836447] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671972machine # [ 86.844248] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1973machine # [ 86.846013] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1974machine # [ 86.847293] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1975machine # [ 86.852680] systemd[1]: Reached target Remote File Systems.1976machine # [ 87.212851] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1977machine # [ 87.216529] systemd[1]: Finished Create SUID/SGID Wrappers.1978machine # [ 87.221220] systemd[1]: Starting linger-users.service...1979machine # [ 87.291686] systemd[1]: linger-users.service: Deactivated successfully.1980machine # [ 87.293717] systemd[1]: Finished linger-users.service.1981machine # [ 87.552860] nixos[3732]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test1982machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.19 seconds)1983machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1984machine # [ 87.645845] sudo[3869]: 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.service1985machine # [ 87.670078] sudo[3869]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1986machine # [ 87.691142] sudo[3869]: pam_unix(sudo:session): session closed for user usertest1987machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.13 seconds)1988machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1989machine # [ 87.747153] sudo[3875]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1990machine # [ 87.762302] sudo[3875]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1991machine # [ 87.776145] sudo[3875]: pam_unix(sudo:session): session closed for user usertest1992machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.08 seconds)1993machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service1994machine # [ 87.827580] sudo[3881]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service /home/usertest/.config/systemd/user/migrated.service1995machine # [ 87.842464] sudo[3881]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1996machine # [ 87.856628] sudo[3881]: pam_unix(sudo:session): session closed for user usertest1997machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.08 seconds)1998machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1999machine # [ 87.907006] sudo[3887]: 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.service2000machine # [ 87.922089] sudo[3887]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2001machine # [ 87.935486] sudo[3887]: pam_unix(sudo:session): session closed for user usertest2002machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.08 seconds)2003machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2004machine # [ 87.985124] sudo[3893]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2005machine # [ 87.999366] sudo[3893]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2006machine # [ 88.010511] systemd[584]: Reload requested from client PID 3898 ('systemctl')...2007machine # [ 88.013511] systemd[584]: Reloading...2008machine # [ 88.230147] systemd[584]: Reloading finished in 215 ms.2009machine # [ 88.248454] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2010machine # [ 88.264198] sudo[3893]: pam_unix(sudo:session): session closed for user usertest2011machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.33 seconds)2012machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2013machine # [ 88.322964] sudo[3903]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2014machine # [ 88.337493] sudo[3903]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2015machine # [ 88.352510] systemd[584]: Starting migrated.service...2016machine # [ 88.396438] systemd[584]: Finished migrated.service.2017machine # [ 88.401638] sudo[3903]: pam_unix(sudo:session): session closed for user usertest2018machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.14 seconds)2019machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2020machine # [ 88.451702] sudo[3911]: 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.service2021machine # [ 88.466565] sudo[3911]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2022machine # [ 88.479862] sudo[3911]: pam_unix(sudo:session): session closed for user usertest2023machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)2024machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2025machine # [ 88.543664] sudo[3917]: 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-owner2026machine # [ 88.559719] sudo[3917]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2027machine # [ 88.575134] sudo[3917]: pam_unix(sudo:session): session closed for user usertest2028machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.10 seconds)2029machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2030machine # [ 88.633122] sudo[3923]: 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.service2031machine # [ 88.647632] sudo[3923]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2032machine # [ 88.673201] sudo[3923]: pam_unix(sudo:session): session closed for user usertest2033machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds)2034machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&12035machine # [ 89.251925] nixos[3932]: switching to system configuration /nix/store/h1gyn8j5j0cfh53kbpjdrqhjnlgnzk90-nixos-system-machine-test2036machine # [ 89.256343] systemd[1]: Stopped target Remote File Systems.2037machine # [ 89.257513] systemd[1]: Stopped target Local File Systems.2038machine # [ 89.814062] systemd[1]: Reload requested from client PID 3932 ('.switch-to-conf') (unit backdoor.service)...2039machine # [ 89.816159] systemd[1]: Reloading...2040machine # [ 90.464209] systemd[1]: Reloading finished in 645 ms.2041machine # [ 90.671265] systemd[584]: Reexecution requested from client PID 4001 ('switch-to-confi')...2042machine # [ 90.674502] systemd[584]: Reexecuting.2043machine # [ 90.748663] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2044machine # [ 90.984238] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2045machine # [ 90.991892] systemd[584]: Reload requested from client PID 4001 ('switch-to-confi')...2046machine # [ 90.994576] systemd[584]: Reloading...2047machine # [ 91.214919] systemd[584]: Reloading finished in 219 ms.2048machine # [ 91.232300] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2049machine # [ 91.243969] systemd[584]: Stopped Run user-specific NixOS activation.2050machine # [ 91.245168] systemd[584]: Stopping Run user-specific NixOS activation...2051machine # [ 91.248686] systemd[584]: Starting Run user-specific NixOS activation...2052machine # [ 91.320547] systemd[584]: Reload requested from client PID 4013 ('systemctl') (unit nixos-activation.service)...2053machine # [ 91.323505] systemd[584]: Reloading...2054machine # [ 91.562926] systemd[584]: Reloading finished in 237 ms.2055machine # [ 91.576549] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2056machine # [ 91.599442] systemd[584]: Finished Run user-specific NixOS activation.2057machine # [ 91.602729] systemd[584]: Reload requested from client PID 4001 ('switch-to-confi')...2058machine # [ 91.607241] systemd[584]: Reloading...2059machine # [ 91.823682] systemd[584]: Reloading finished in 217 ms.2060machine # [ 91.836601] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2061machine # [ 91.855982] systemd[1]: Stopped target Reactivate sysinit units.2062machine # [ 91.859741] systemd[1]: Stopping Reactivate sysinit units...2063machine # [ 91.861614] systemd[1]: Reached target Reactivate sysinit units.2064machine # [ 91.862524] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2065machine # [ 91.863836] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2066machine # [ 91.866390] systemd[1]: Reached target Local File Systems.2067machine # [ 91.868771] systemd[1]: Reached target Remote File Systems.2068machine # [ 91.874490] systemd[1]: Starting linger-users.service...2069machine # [ 91.892197] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2070machine # [ 91.898517] systemd[1]: Starting Create SUID/SGID Wrappers...2071machine # [ 91.899573] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2072machine # [ 91.903697] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672073machine # [ 91.907095] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2074machine # [ 91.913397] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2075machine # [ 91.916751] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2076machine # [ 91.988754] systemd[1]: linger-users.service: Deactivated successfully.2077machine # [ 91.993299] systemd[1]: Finished linger-users.service.2078machine # [ 92.317651] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2079machine # [ 92.321165] systemd[1]: Finished Create SUID/SGID Wrappers.2080machine # [ 92.580991] nixos[3932]: finished switching to system configuration /nix/store/h1gyn8j5j0cfh53kbpjdrqhjnlgnzk90-nixos-system-machine-test2081machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 3.92 seconds)2082machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2083machine # [ 92.678930] sudo[4080]: 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.service2084machine # [ 92.701300] sudo[4080]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2085machine # [ 92.719570] sudo[4080]: pam_unix(sudo:session): session closed for user usertest2086machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.13 seconds)2087machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2088machine # [ 92.782235] sudo[4086]: 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-owner2089machine # [ 92.798036] sudo[4086]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2090machine # [ 92.812560] sudo[4086]: pam_unix(sudo:session): session closed for user usertest2091machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.09 seconds)2092machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&12093machine # [ 93.431070] nixos[4095]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test2094machine # [ 93.435604] systemd[1]: Stopped target Remote File Systems.2095machine # [ 93.436879] systemd[1]: Stopped target Local File Systems.2096machine # [ 93.933407] systemd[1]: Reload requested from client PID 4095 ('.switch-to-conf') (unit backdoor.service)...2097machine # [ 93.935461] systemd[1]: Reloading...2098machine # [ 94.551004] systemd[1]: Reloading finished in 612 ms.2099machine # [ 94.714501] systemd[584]: Stopped migrated.service.2100machine # [ 94.718568] systemd[584]: Reexecution requested from client PID 4164 ('switch-to-confi')...2101machine # [ 94.721371] systemd[584]: Reexecuting.2102machine # [ 94.795329] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2103machine # [ 95.003868] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2104machine # [ 95.011799] systemd[584]: Reload requested from client PID 4164 ('switch-to-confi')...2105machine # [ 95.015348] systemd[584]: Reloading...2106machine # [ 95.215693] systemd[584]: Reloading finished in 201 ms.2107machine # [ 95.228208] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2108machine # [ 95.239441] systemd[584]: Stopped Run user-specific NixOS activation.2109machine # [ 95.242096] systemd[584]: Stopping Run user-specific NixOS activation...2110machine # [ 95.245436] systemd[584]: Starting Run user-specific NixOS activation...2111machine # [ 95.298388] systemd[584]: Finished Run user-specific NixOS activation.2112machine # [ 95.303357] systemd[1]: Stopped target Reactivate sysinit units.2113machine # [ 95.305814] systemd[1]: Stopping Reactivate sysinit units...2114machine # [ 95.306673] systemd[1]: Reached target Reactivate sysinit units.2115machine # [ 95.311470] systemd[1]: Reached target Local File Systems.2116machine # [ 95.313500] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2117machine # [ 95.317373] systemd[1]: Starting Create SUID/SGID Wrappers...2118machine # [ 95.318311] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2119machine # [ 95.319561] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672120machine # [ 95.330740] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2121machine # [ 95.334463] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2122machine # [ 95.335745] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2123machine # [ 95.343005] systemd[1]: Reached target Remote File Systems.2124machine # [ 95.345743] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2125machine # [ 95.347926] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2126machine # [ 95.655905] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2127machine # [ 95.658315] systemd[1]: Finished Create SUID/SGID Wrappers.2128machine # [ 95.663968] systemd[1]: Starting linger-users.service...2129machine # [ 95.729201] systemd[1]: linger-users.service: Deactivated successfully.2130machine # [ 95.731765] systemd[1]: Finished linger-users.service.2131machine # [ 95.987251] nixos[4095]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test2132machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.18 seconds)2133machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2134machine # [ 96.056823] sudo[4232]: 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.service2135machine # [ 96.073835] sudo[4232]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2136machine # [ 96.090530] sudo[4232]: pam_unix(sudo:session): session closed for user usertest2137machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)2138machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user2139machine # [ 96.141231] sudo[4238]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user2140machine # [ 96.155501] sudo[4238]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2141machine # [ 96.168466] sudo[4238]: pam_unix(sudo:session): session closed for user usertest2142machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.08 seconds)2143machine: must succeed: sudo -u usertest cp /nix/store/sjwrvqsv40pz3pq93zpd2s2k0jw0h8qf-migrated.service ~usertest/.local/share/systemd/user/migrated.service2144machine # [ 96.220997] sudo[4244]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/sjwrvqsv40pz3pq93zpd2s2k0jw0h8qf-migrated.service /home/usertest/.local/share/systemd/user/migrated.service2145machine # [ 96.233923] sudo[4244]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2146machine # [ 96.248962] sudo[4244]: pam_unix(sudo:session): session closed for user usertest2147machine: (finished: must succeed: sudo -u usertest cp /nix/store/sjwrvqsv40pz3pq93zpd2s2k0jw0h8qf-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.08 seconds)2148machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2149machine # [ 96.300954] sudo[4250]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2150machine # [ 96.315915] sudo[4250]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2151machine # [ 96.328275] systemd[584]: Reload requested from client PID 4255 ('systemctl')...2152machine # [ 96.330897] systemd[584]: Reloading...2153machine # [ 96.539622] systemd[584]: Reloading finished in 206 ms.2154machine # [ 96.556197] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2155machine # [ 96.567025] sudo[4250]: pam_unix(sudo:session): session closed for user usertest2156machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.32 seconds)2157machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2158machine # [ 96.621404] sudo[4260]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2159machine # [ 96.636732] sudo[4260]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2160machine # [ 96.657874] systemd[584]: Starting migrated.service...2161machine # [ 96.697716] systemd[584]: Finished migrated.service.2162machine # [ 96.702535] sudo[4260]: pam_unix(sudo:session): session closed for user usertest2163machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.13 seconds)2164machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2165machine # [ 96.760321] sudo[4268]: 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.service2166machine # [ 96.776792] sudo[4268]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2167machine # [ 96.791902] sudo[4268]: pam_unix(sudo:session): session closed for user usertest2168machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.09 seconds)2169machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2170machine # [ 96.861154] sudo[4274]: 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-owner2171machine # [ 96.878611] sudo[4274]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2172machine # [ 96.894538] sudo[4274]: pam_unix(sudo:session): session closed for user usertest2173machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.10 seconds)2174machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2175machine # [ 96.956916] sudo[4280]: 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.service2176machine # [ 96.973157] sudo[4280]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2177machine # [ 97.021736] sudo[4280]: pam_unix(sudo:session): session closed for user usertest2178machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.13 seconds)2179machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12180machine # [ 97.613288] nixos[4289]: switching to system configuration /nix/store/jnq1jwn05wdixs0h4xzdxbzq6gxg7syi-nixos-system-machine-test2181machine # [ 97.616128] systemd[1]: Stopped target Remote File Systems.2182machine # [ 97.618435] systemd[1]: Stopped target Local File Systems.2183machine # [ 98.175484] systemd[1]: Reload requested from client PID 4289 ('.switch-to-conf') (unit backdoor.service)...2184machine # [ 98.177676] systemd[1]: Reloading...2185machine # [ 98.810348] systemd[1]: Reloading finished in 630 ms.2186machine # [ 98.986537] systemd[584]: Reexecution requested from client PID 4358 ('switch-to-confi')...2187machine # [ 98.990255] systemd[584]: Reexecuting.2188machine # [ 99.030371] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2189machine # [ 99.249670] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2190machine # [ 99.256796] systemd[584]: Reload requested from client PID 4358 ('switch-to-confi')...2191machine # [ 99.259374] systemd[584]: Reloading...2192machine # [ 99.468879] systemd[584]: Reloading finished in 208 ms.2193machine # [ 99.484176] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2194machine # [ 99.494080] systemd[584]: Stopped Run user-specific NixOS activation.2195machine # [ 99.496480] systemd[584]: Stopping Run user-specific NixOS activation...2196machine # [ 99.499644] systemd[584]: Starting Run user-specific NixOS activation...2197machine # [ 99.549656] systemd[584]: Finished Run user-specific NixOS activation.2198machine # [ 99.551762] systemd[584]: Reload requested from client PID 4358 ('switch-to-confi')...2199machine # [ 99.554664] systemd[584]: Reloading...2200machine # [ 99.759854] systemd[584]: Reloading finished in 204 ms.2201machine # [ 99.773618] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2202machine # [ 99.784080] systemd[584]: Stopped migrated.service.2203machine # [ 99.784912] systemd[584]: Stopping migrated.service...2204machine # [ 99.787922] systemd[584]: Starting migrated.service...2205machine # [ 99.827938] systemd[584]: Finished migrated.service.2206machine # [ 99.834874] systemd[1]: Stopped target Reactivate sysinit units.2207machine # [ 99.837434] systemd[1]: Stopping Reactivate sysinit units...2208machine # [ 99.838309] systemd[1]: Reached target Reactivate sysinit units.2209machine # [ 99.844774] systemd[1]: Reached target Local File Systems.2210machine # [ 99.847286] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2211machine # [ 99.854681] systemd[1]: Starting Create SUID/SGID Wrappers...2212machine # [ 99.855916] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2213machine # [ 99.861496] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672214machine # [ 99.863743] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2215machine # [ 99.865948] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2216machine # [ 99.867547] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2217machine # [ 99.868982] systemd[1]: Reached target Remote File Systems.2218machine # [ 99.874158] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2219machine # [ 99.875586] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2220machine # [ 100.208892] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2221machine # [ 100.211083] systemd[1]: Finished Create SUID/SGID Wrappers.2222machine # [ 100.216386] systemd[1]: Starting linger-users.service...2223machine # [ 100.277098] systemd[1]: linger-users.service: Deactivated successfully.2224machine # [ 100.279888] systemd[1]: Finished linger-users.service.2225machine # [ 100.539245] nixos[4289]: finished switching to system configuration /nix/store/jnq1jwn05wdixs0h4xzdxbzq6gxg7syi-nixos-system-machine-test2226machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.53 seconds)2227machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2228machine # [ 100.627089] sudo[4432]: 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.service2229machine # [ 100.647035] sudo[4432]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2230machine # [ 100.662837] sudo[4432]: pam_unix(sudo:session): session closed for user usertest2231machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds)2232machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2233machine # [ 100.713652] sudo[4438]: 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.service2234machine # [ 100.728917] sudo[4438]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2235machine # [ 100.759573] sudo[4438]: pam_unix(sudo:session): session closed for user usertest2236machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds)2237machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2238machine # [ 100.808666] sudo[4444]: 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-owner2239machine # [ 100.821604] sudo[4444]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2240machine # [ 100.834869] sudo[4444]: pam_unix(sudo:session): session closed for user usertest2241machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)2242machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12243machine # [ 101.360543] nixos[4453]: switching to system configuration /nix/store/jnq1jwn05wdixs0h4xzdxbzq6gxg7syi-nixos-system-machine-test2244machine # [ 101.363319] systemd[1]: Stopped target Remote File Systems.2245machine # [ 101.365375] systemd[1]: Stopped target Local File Systems.2246machine # [ 101.823990] systemd[1]: Reload requested from client PID 4453 ('.switch-to-conf') (unit backdoor.service)...2247machine # [ 101.826088] systemd[1]: Reloading...2248machine # [ 102.473131] systemd[1]: Reloading finished in 644 ms.2249machine # [ 102.640142] systemd[584]: Reexecution requested from client PID 4522 ('switch-to-confi')...2250machine # [ 102.644446] systemd[584]: Reexecuting.2251machine # [ 102.712451] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2252machine # [ 102.941453] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2253machine # [ 102.948364] systemd[584]: Reload requested from client PID 4522 ('switch-to-confi')...2254machine # [ 102.950690] systemd[584]: Reloading...2255machine # [ 103.158461] systemd[584]: Reloading finished in 206 ms.2256machine # [ 103.172221] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2257machine # [ 103.183561] systemd[584]: Stopped Run user-specific NixOS activation.2258machine # [ 103.185894] systemd[584]: Stopping Run user-specific NixOS activation...2259machine # [ 103.188813] systemd[584]: Starting Run user-specific NixOS activation...2260machine # [ 103.240996] systemd[584]: Finished Run user-specific NixOS activation.2261machine # [ 103.245458] systemd[1]: Stopped target Reactivate sysinit units.2262machine # [ 103.248970] systemd[1]: Stopping Reactivate sysinit units...2263machine # [ 103.250785] systemd[1]: Reached target Reactivate sysinit units.2264machine # [ 103.251721] systemd[1]: Reached target Remote File Systems.2265machine # [ 103.254079] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2266machine # [ 103.257625] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2267machine # [ 103.259413] systemd[1]: Reached target Local File Systems.2268machine # [ 103.265464] systemd[1]: Starting linger-users.service...2269machine # [ 103.270406] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2270machine # [ 103.275752] systemd[1]: Starting Create SUID/SGID Wrappers...2271machine # [ 103.277196] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2272machine # [ 103.281500] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672273machine # [ 103.283756] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2274machine # [ 103.296111] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2275machine # [ 103.297505] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2276machine # [ 103.372424] systemd[1]: linger-users.service: Deactivated successfully.2277machine # [ 103.375231] systemd[1]: Finished linger-users.service.2278machine # [ 103.691506] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2279machine # [ 103.695464] systemd[1]: Finished Create SUID/SGID Wrappers.2280machine # [ 103.951959] nixos[4453]: finished switching to system configuration /nix/store/jnq1jwn05wdixs0h4xzdxbzq6gxg7syi-nixos-system-machine-test2281machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.13 seconds)2282machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd2283machine # [ 104.046025] sudo[4590]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd2284machine # [ 104.065696] sudo[4590]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2285machine # [ 104.082148] sudo[4590]: pam_unix(sudo:session): session closed for user usertest2286machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.12 seconds)2287machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2288machine # [ 104.138866] sudo[4596]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2289machine # [ 104.153778] sudo[4596]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2290machine # [ 104.165326] systemd[584]: Reload requested from client PID 4601 ('systemctl')...2291machine # [ 104.169625] systemd[584]: Reloading...2292machine # [ 104.374723] systemd[584]: Reloading finished in 206 ms.2293machine # [ 104.388187] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2294machine # [ 104.399705] sudo[4596]: pam_unix(sudo:session): session closed for user usertest2295machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.32 seconds)2296machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&12297machine # [ 104.951778] nixos[4609]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test2298machine # [ 104.956050] systemd[1]: Stopped target Remote File Systems.2299machine # [ 104.957024] systemd[1]: Stopped target Local File Systems.2300machine # [ 105.473536] systemd[1]: Reload requested from client PID 4609 ('.switch-to-conf') (unit backdoor.service)...2301machine # [ 105.475667] systemd[1]: Reloading...2302machine # [ 106.108113] systemd[1]: Reloading finished in 629 ms.2303machine # [ 106.269927] systemd[584]: Stopped migrated.service.2304machine # [ 106.273811] systemd[584]: Reexecution requested from client PID 4678 ('switch-to-confi')...2305machine # [ 106.275196] systemd[584]: Reexecuting.2306machine # [ 106.338738] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2307machine # [ 106.545494] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2308machine # [ 106.552584] systemd[584]: Reload requested from client PID 4678 ('switch-to-confi')...2309machine # [ 106.555167] systemd[584]: Reloading...2310machine # [ 106.760962] systemd[584]: Reloading finished in 204 ms.2311machine # [ 106.776237] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2312machine # [ 106.785990] systemd[584]: Stopped Run user-specific NixOS activation.2313machine # [ 106.788469] systemd[584]: Stopping Run user-specific NixOS activation...2314machine # [ 106.791761] systemd[584]: Starting Run user-specific NixOS activation...2315machine # [ 106.841457] systemd[584]: Finished Run user-specific NixOS activation.2316machine # [ 106.845546] systemd[1]: Stopped target Reactivate sysinit units.2317machine # [ 106.848177] systemd[1]: Stopping Reactivate sysinit units...2318machine # [ 106.849129] systemd[1]: Reached target Reactivate sysinit units.2319machine # [ 106.853770] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2320machine # [ 106.857315] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2321machine # [ 106.862066] systemd[1]: Reached target Local File Systems.2322machine # [ 106.862892] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2323machine # [ 106.867663] systemd[1]: Starting Create SUID/SGID Wrappers...2324machine # [ 106.870070] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2325machine # [ 106.872951] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672326machine # [ 106.876526] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2327machine # [ 106.878905] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2328machine # [ 106.884395] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2329machine # [ 106.885658] systemd[1]: Reached target Remote File Systems.2330machine # [ 107.270739] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2331machine # [ 107.275310] systemd[1]: Finished Create SUID/SGID Wrappers.2332machine # [ 107.278072] systemd[1]: Starting linger-users.service...2333machine # [ 107.344841] systemd[1]: linger-users.service: Deactivated successfully.2334machine # [ 107.347852] systemd[1]: Finished linger-users.service.2335machine # [ 107.604806] nixos[4609]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test2336machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.22 seconds)2337machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2338machine # [ 107.701002] sudo[4746]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2339machine # [ 107.722912] sudo[4746]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2340machine # [ 107.739122] sudo[4746]: pam_unix(sudo:session): session closed for user usertest2341machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.12 seconds)2342machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service2343machine # [ 107.794559] sudo[4752]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service /home/usertest/.config/systemd/user/migrated.service2344machine # [ 107.809883] sudo[4752]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2345machine # [ 107.824229] sudo[4752]: pam_unix(sudo:session): session closed for user usertest2346machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.08 seconds)2347machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2348machine # [ 107.873045] sudo[4758]: 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.service2349machine # [ 107.888787] sudo[4758]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2350machine # [ 107.901827] sudo[4758]: pam_unix(sudo:session): session closed for user usertest2351machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.08 seconds)2352machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2353machine # [ 107.951980] sudo[4764]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2354machine # [ 107.966744] sudo[4764]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2355machine # [ 107.977557] systemd[584]: Reload requested from client PID 4769 ('systemctl')...2356machine # [ 107.980285] systemd[584]: Reloading...2357machine # [ 108.197421] systemd[584]: Reloading finished in 216 ms.2358machine # [ 108.212221] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2359machine # [ 108.226799] sudo[4764]: pam_unix(sudo:session): session closed for user usertest2360machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.33 seconds)2361machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2362machine # [ 108.283698] sudo[4774]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2363machine # [ 108.300282] sudo[4774]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2364machine # [ 108.317839] systemd[584]: Starting migrated.service...2365machine # [ 108.359920] systemd[584]: Finished migrated.service.2366machine # [ 108.365664] sudo[4774]: pam_unix(sudo:session): session closed for user usertest2367machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.14 seconds)2368machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2369machine # [ 108.420142] sudo[4782]: 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.service2370machine # [ 108.434536] sudo[4782]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2371machine # [ 108.448119] sudo[4782]: pam_unix(sudo:session): session closed for user usertest2372machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)2373machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2374machine # [ 108.507628] sudo[4788]: 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-owner2375machine # [ 108.523631] sudo[4788]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2376machine # [ 108.538113] sudo[4788]: pam_unix(sudo:session): session closed for user usertest2377machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.09 seconds)2378machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2379machine # [ 108.591365] sudo[4794]: 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.service2380machine # [ 108.606330] sudo[4794]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2381machine # [ 108.630859] sudo[4794]: pam_unix(sudo:session): session closed for user usertest2382machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.09 seconds)2383machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12384machine # [ 109.256687] nixos[4803]: switching to system configuration /nix/store/jnq1jwn05wdixs0h4xzdxbzq6gxg7syi-nixos-system-machine-test2385machine # [ 109.259425] systemd[1]: Stopped target Remote File Systems.2386machine # [ 109.261714] systemd[1]: Stopped target Local File Systems.2387machine # [ 109.744982] systemd[1]: Reload requested from client PID 4803 ('.switch-to-conf') (unit backdoor.service)...2388machine # [ 109.747080] systemd[1]: Reloading...2389machine # [ 110.382881] systemd[1]: Reloading finished in 633 ms.2390machine # [ 110.534773] systemd[584]: Reexecution requested from client PID 4871 ('switch-to-confi')...2391machine # [ 110.538569] systemd[584]: Reexecuting.2392machine # [ 110.602847] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2393machine # [ 110.802944] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2394machine # [ 110.809469] systemd[584]: Reload requested from client PID 4871 ('switch-to-confi')...2395machine # [ 110.811743] systemd[584]: Reloading...2396machine # [ 111.005914] systemd[584]: Reloading finished in 192 ms.2397machine # [ 111.020258] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2398machine # [ 111.031368] systemd[584]: Stopped Run user-specific NixOS activation.2399machine # [ 111.033951] systemd[584]: Stopping Run user-specific NixOS activation...2400machine # [ 111.036948] systemd[584]: Starting Run user-specific NixOS activation...2401machine # [ 111.087466] systemd[584]: Finished Run user-specific NixOS activation.2402machine # [ 111.089980] systemd[584]: Reload requested from client PID 4871 ('switch-to-confi')...2403machine # [ 111.092617] systemd[584]: Reloading...2404machine # [ 111.289472] systemd[584]: Reloading finished in 195 ms.2405machine # [ 111.304195] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2406machine # [ 111.318737] systemd[1]: Stopped target Reactivate sysinit units.2407machine # [ 111.321553] systemd[1]: Stopping Reactivate sysinit units...2408machine # [ 111.323309] systemd[1]: Reached target Reactivate sysinit units.2409machine # [ 111.327957] systemd[1]: Reached target Local File Systems.2410machine # [ 111.329307] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2411machine # [ 111.332177] systemd[1]: Starting Create SUID/SGID Wrappers...2412machine # [ 111.333075] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2413machine # [ 111.334307] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672414machine # [ 111.349212] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2415machine # [ 111.355724] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2416machine # [ 111.358034] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2417machine # [ 111.359300] systemd[1]: Reached target Remote File Systems.2418machine # [ 111.361860] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2419machine # [ 111.363399] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2420machine # [ 111.691471] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2421machine # [ 111.696527] systemd[1]: Finished Create SUID/SGID Wrappers.2422machine # [ 111.698991] systemd[1]: Starting linger-users.service...2423machine # [ 111.762092] systemd[1]: linger-users.service: Deactivated successfully.2424machine # [ 111.764266] systemd[1]: Finished linger-users.service.2425machine # [ 112.022673] nixos[4803]: finished switching to system configuration /nix/store/jnq1jwn05wdixs0h4xzdxbzq6gxg7syi-nixos-system-machine-test2426machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.40 seconds)2427machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2428machine # [ 112.113665] sudo[4944]: 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.service2429machine # [ 112.135501] sudo[4944]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2430machine # [ 112.166243] sudo[4944]: pam_unix(sudo:session): session closed for user usertest2431machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.13 seconds)2432machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2433machine # [ 112.221379] sudo[4950]: 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-owner2434machine # [ 112.236919] sudo[4950]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2435machine # [ 112.249580] sudo[4950]: pam_unix(sudo:session): session closed for user usertest2436machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)2437machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd2438machine # [ 112.300650] sudo[4956]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd2439machine # [ 112.314316] sudo[4956]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2440machine # [ 112.327841] sudo[4956]: pam_unix(sudo:session): session closed for user usertest2441machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.08 seconds)2442machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2443machine # [ 112.378684] sudo[4962]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2444machine # [ 112.392707] sudo[4962]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2445machine # [ 112.403564] systemd[584]: Reload requested from client PID 4967 ('systemctl')...2446machine # [ 112.405502] systemd[584]: Reloading...2447machine # [ 112.624161] systemd[584]: Reloading finished in 216 ms.2448machine # [ 112.640196] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2449machine # [ 112.654612] sudo[4962]: pam_unix(sudo:session): session closed for user usertest2450machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.33 seconds)2451machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service2452machine # [ 112.707761] sudo[4972]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user stop migrated.service2453machine # [ 112.723003] sudo[4972]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2454machine # [ 112.739374] systemd[584]: Stopped migrated.service.2455machine # [ 112.745188] sudo[4972]: pam_unix(sudo:session): session closed for user usertest2456machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.09 seconds)2457machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&12458machine # [ 113.309404] nixos[4981]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test2459machine # [ 113.312325] systemd[1]: Stopped target Remote File Systems.2460machine # [ 113.314930] systemd[1]: Stopped target Local File Systems.2461machine # [ 113.754925] systemd[1]: Reload requested from client PID 4981 ('.switch-to-conf') (unit backdoor.service)...2462machine # [ 113.757258] systemd[1]: Reloading...2463machine # [ 114.333963] systemd[1]: Reloading finished in 574 ms.2464machine # [ 114.476634] systemd[584]: Reexecution requested from client PID 5049 ('switch-to-confi')...2465machine # [ 114.480311] systemd[584]: Reexecuting.2466machine # [ 114.533933] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2467machine # [ 114.725844] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2468machine # [ 114.733396] systemd[584]: Reload requested from client PID 5049 ('switch-to-confi')...2469machine # [ 114.735924] systemd[584]: Reloading...2470machine # [ 114.922950] systemd[584]: Reloading finished in 186 ms.2471machine # [ 114.936163] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2472machine # [ 114.945119] systemd[584]: Stopped Run user-specific NixOS activation.2473machine # [ 114.947475] systemd[584]: Stopping Run user-specific NixOS activation...2474machine # [ 114.950197] systemd[584]: Starting Run user-specific NixOS activation...2475machine # [ 114.996993] systemd[584]: Finished Run user-specific NixOS activation.2476machine # [ 115.001291] systemd[1]: Stopped target Reactivate sysinit units.2477machine # [ 115.004130] systemd[1]: Stopping Reactivate sysinit units...2478machine # [ 115.004999] systemd[1]: Reached target Reactivate sysinit units.2479machine # [ 115.006946] systemd[1]: Reached target Remote File Systems.2480machine # [ 115.009938] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2481machine # [ 115.012981] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2482machine # [ 115.018502] systemd[1]: Reached target Local File Systems.2483machine # [ 115.023907] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2484machine # [ 115.027732] systemd[1]: Starting Create SUID/SGID Wrappers...2485machine # [ 115.030511] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2486machine # [ 115.032752] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672487machine # [ 115.035221] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2488machine # [ 115.040275] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2489machine # [ 115.041500] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2490machine # [ 115.391192] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2491machine # [ 115.396556] systemd[1]: Finished Create SUID/SGID Wrappers.2492machine # [ 115.399309] systemd[1]: Starting linger-users.service...2493machine # [ 115.465379] systemd[1]: linger-users.service: Deactivated successfully.2494machine # [ 115.467640] systemd[1]: Finished linger-users.service.2495machine # [ 115.726802] nixos[4981]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test2496machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.99 seconds)2497(finished: subtest: user services, in 85.26 seconds)2498(finished: run the VM test script, in 116.77 seconds)2499test script finished in 116.85s2500cleanup2501kill QemuMachine (pid 45)2502machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14)2503(finished: cleanup, in 0.17 seconds)2504additionally exposed symbols:2505 machine, other,2506 vlan1,2507 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