vm-test-run-switch-test-user
checks.aarch64-linux.switchTest-user
· build #23
· 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.N9pcdDyFiu', 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: 2ba3810e-5a4f-4528-a727-7313517e3af417machine # 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.49 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Wed Sep 2 12:31:51 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/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/init regInfo=/nix/store/ckcx2090wph8lagrmdycwhafqpkwny0r-closure-info/registration console=ttyAMA0,115200n8 console=tty061machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/ckcx2090wph8lagrmdycwhafqpkwny0r-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 74886 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 @44ce0000 (indirect, esz 8, psz 64K, shr 1)96machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)97machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d0000098machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d1000099machine # [ 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.000029] arm-pv: using stolen time PV105machine # [ 0.000437] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)106machine # [ 0.000623] Console: colour dummy device 80x25107machine # [ 0.000630] printk: legacy console [tty0] enabled108machine # [ 0.000824] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)109machine # [ 0.000831] pid_max: default: 32768 minimum: 301110machine # [ 0.000910] LSM: initializing lsm=capability,landlock,yama,bpf,ima111machine # [ 0.001034] landlock: Up and running.112machine # [ 0.001036] Yama: becoming mindful.113machine # [ 0.001493] LSM support for eBPF active114machine # [ 0.001619] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)115machine # [ 0.001641] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)116machine # [ 0.002776] cacheinfo: Unable to detect cache hierarchy for CPU 0117machine # [ 0.003506] rcu: Hierarchical SRCU implementation.118machine # [ 0.003510] rcu: Max phase no-delay instances is 1000.119machine # [ 0.004714] fsl-mc MSI: its@8080000 domain created120machine # [ 0.004805] EFI services will not be available.121machine # [ 0.004878] smp: Bringing up secondary CPUs ...122machine # [ 0.004887] smp: Brought up 1 node, 1 CPU123machine # [ 0.004890] SMP: Total of 1 processors activated.124machine # [ 0.004893] CPU: All CPU(s) started at EL1125machine # [ 0.004906] CPU features: detected: Branch Target Identification126machine # [ 0.004911] CPU features: detected: ARMv8.4 Translation Table Level127machine # [ 0.004914] CPU features: detected: Instruction cache invalidation not required for I/D coherence128machine # [ 0.004918] CPU features: detected: Data cache clean to the PoU not required for I/D coherence129machine # [ 0.004921] CPU features: detected: Common not Private translations130machine # [ 0.004924] CPU features: detected: CRC32 instructions131machine # [ 0.004927] CPU features: detected: Data cache clean to Point of Deep Persistence132machine # [ 0.004931] CPU features: detected: Data cache clean to Point of Persistence133machine # [ 0.004934] CPU features: detected: Data independent timing control (DIT)134machine # [ 0.004937] CPU features: detected: E0PD135machine # [ 0.004939] CPU features: detected: Enhanced Counter Virtualization136machine # [ 0.004942] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)137machine # [ 0.004946] CPU features: detected: Enhanced Virtualization Traps138machine # [ 0.004949] CPU features: detected: Fine Grained Traps139machine # [ 0.004952] CPU features: detected: Generic authentication (architected QARMA5 algorithm)140machine # [ 0.004957] CPU features: detected: RCpc load-acquire (LDAPR)141machine # [ 0.004960] CPU features: detected: LSE atomic instructions142machine # [ 0.004963] CPU features: detected: Privileged Access Never143machine # [ 0.004965] CPU features: detected: PMUv3144machine # [ 0.004968] CPU features: detected: RAS Extension Support145machine # [ 0.004970] CPU features: detected: RASv1p1 Extension Support146machine # [ 0.004973] CPU features: detected: Random Number Generator147machine # [ 0.004976] CPU features: detected: Speculation barrier (SB)148machine # [ 0.004978] CPU features: detected: Stage-2 Force Write-Back149machine # [ 0.004981] CPU features: detected: TLB range maintenance instructions150machine # [ 0.004986] CPU features: detected: Speculative Store Bypassing Safe (SSBS)151machine # [ 0.005024] alternatives: applying system-wide alternatives152machine # [ 0.007945] CPU features: detected: BBM Level 2 without TLB conflict abort153machine # [ 0.008167] Memory: 894344K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 112964K reserved, 32768K cma-reserved)154machine # [ 0.008510] devtmpfs: initialized155machine # [ 0.010247] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)156machine # [ 0.010270] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).157machine # [ 0.010447] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL158machine # [ 0.010452] 0 pages in range for non-PLT usage159machine # [ 0.010453] 508288 pages in range for PLT usage160machine # [ 0.010565] pinctrl core: initialized pinctrl subsystem161machine # [ 0.011324] DMI not present or invalid.162machine # [ 0.014386] NET: Registered PF_NETLINK/PF_ROUTE protocol family163machine # [ 0.016653] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations164machine # [ 0.016812] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations165machine # [ 0.016973] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations166machine # [ 0.016994] audit: initializing netlink subsys (disabled)167machine # [ 0.017499] thermal_sys: Registered thermal governor 'fair_share'168machine # [ 0.017500] thermal_sys: Registered thermal governor 'bang_bang'169machine # [ 0.017504] thermal_sys: Registered thermal governor 'step_wise'170machine # [ 0.017506] thermal_sys: Registered thermal governor 'user_space'171machine # [ 0.017511] thermal_sys: Registered thermal governor 'power_allocator'172machine # [ 0.017535] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1173machine # [ 0.017543] cpuidle: using governor ladder174machine # [ 0.017549] cpuidle: using governor menu175machine # [ 0.017749] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.176machine # [ 0.017765] ASID allocator initialised with 65536 entries177machine # [ 0.018897] Serial: AMBA PL011 UART driver178machine # [ 0.024215] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1179machine # [ 0.024377] printk: console [ttyAMA0] enabled180machine # [ 0.147815] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages181machine # [ 0.147835] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page182machine # [ 0.147841] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages183machine # [ 0.147845] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page184machine # [ 0.147850] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages185machine # [ 0.147854] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page186machine # [ 0.147858] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages187machine # [ 0.147862] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page188machine # [ 0.155371] fbcon: Taking over console189machine # [ 0.155388] ACPI: Interpreter disabled.190machine # [ 0.157287] iommu: Default domain type: Translated191machine # [ 0.157297] iommu: DMA domain TLB invalidation policy: strict mode192machine # [ 0.159005] SCSI subsystem initialized193machine # [ 0.164282] usbcore: registered new interface driver usbfs194machine # [ 0.164320] usbcore: registered new interface driver hub195machine # [ 0.164336] usbcore: registered new device driver usb196machine # [ 0.164602] pps_core: LinuxPPS API ver. 1 registered197machine # [ 0.164609] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>198machine # [ 0.164618] PTP clock support registered199machine # [ 0.164665] EDAC MC: Ver: 3.0.0200machine # [ 0.169336] scmi_core: SCMI protocol bus registered201machine # [ 0.170334] FPGA manager framework202machine # [ 0.171261] vgaarb: loaded203machine # [ 0.171900] clocksource: Switched to clocksource arch_sys_counter204machine # [ 0.175710] VFS: Disk quotas dquot_6.6.0205machine # [ 0.175740] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)206machine # [ 0.179536] netfs: FS-Cache loaded207machine # [ 0.179666] pnp: PnP ACPI: disabled208machine # [ 0.183716] NET: Registered PF_INET protocol family209machine # [ 0.183879] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)210machine # [ 0.213706] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)211machine # [ 0.213754] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)212machine # [ 0.213780] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)213machine # [ 0.213827] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)214machine # [ 0.213903] TCP: Hash tables configured (established 8192 bind 8192)215machine # [ 0.214014] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)216machine # [ 0.214080] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)217machine # [ 0.214129] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)218machine # [ 0.214216] NET: Registered PF_UNIX/PF_LOCAL protocol family219machine # [ 0.214268] NET: Registered PF_XDP protocol family220machine # [ 0.214291] PCI: CLS 0 bytes, default 64221machine # [ 0.214551] Trying to unpack rootfs image as initramfs...222machine # [ 0.229650] kvm [1]: HYP mode not available223machine # [ 0.336488] Initialise system trusted keyrings224machine # [ 0.337264] workingset: timestamp_bits=42 max_order=18 bucket_order=0225machine # [ 0.338558] squashfs: version 4.0 (2009/01/31) Phillip Lougher226machine # [ 0.339333] 9p: Installing v9fs 9p2000 file system support227machine # [ 0.368036] Key type asymmetric registered228machine # [ 0.368068] Asymmetric key parser 'x509' registered229machine # [ 0.368160] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)230machine # [ 0.370320] io scheduler mq-deadline registered231machine # [ 0.370331] io scheduler kyber registered232machine # [ 0.380074] pl061_gpio 9030000.pl061: PL061 GPIO chip registered233machine # [ 0.381518] ledtrig-cpu: registered to indicate activity on CPUs234machine # [ 0.381958] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:235machine # [ 0.381975] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000236machine # [ 0.381992] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000237machine # [ 0.382000] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000238machine # [ 0.382020] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits239machine # [ 0.382047] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]240machine # [ 0.382135] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00241machine # [ 0.382144] pci_bus 0000:00: root bus resource [bus 00-ff]242machine # [ 0.382151] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]243machine # [ 0.382157] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]244machine # [ 0.382162] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]245machine # [ 0.382226] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint246machine # [ 0.382683] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint247machine # [ 0.382875] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]248machine # [ 0.382896] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]249machine # [ 0.382927] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]250machine # [ 0.382944] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]251machine # [ 0.383397] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint252machine # [ 0.383584] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]253machine # [ 0.383603] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]254machine # [ 0.383632] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]255machine # [ 0.403409] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint256machine # [ 0.403597] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]257machine # [ 0.403614] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]258machine # [ 0.403644] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]259machine # [ 0.407335] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint260machine # [ 0.407524] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]261machine # [ 0.407540] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]262machine # [ 0.407571] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]263machine # [ 0.420187] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint264machine # [ 0.420403] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]265machine # [ 0.420421] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]266machine # [ 0.420451] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]267machine # [ 0.420942] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint268machine # [ 0.421127] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]269machine # [ 0.421143] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]270machine # [ 0.421174] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]271machine # [ 0.421668] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint272machine # [ 0.421852] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]273machine # [ 0.421869] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]274machine # [ 0.421902] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275machine # [ 0.421920] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]276machine # [ 0.422379] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint277machine # [ 0.422570] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]278machine # [ 0.422603] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279machine # [ 0.423060] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint280machine # [ 0.423251] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.423283] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.423674] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint283machine # [ 0.423861] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]284machine # [ 0.441553] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint285machine # [ 0.441858] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]286machine # [ 0.441876] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]287machine # [ 0.441906] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]288machine # [ 0.442375] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint289machine # [ 0.442563] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]290machine # [ 0.442579] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]291machine # [ 0.442609] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]292machine # [ 0.443225] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned293machine # [ 0.443238] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned294machine # [ 0.443243] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned295machine # [ 0.443290] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned296machine # [ 0.443338] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned297machine # [ 0.443387] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned298machine # [ 0.443437] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned299machine # [ 0.443485] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned300machine # [ 0.443532] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned301machine # [ 0.443580] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned302machine # [ 0.443627] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned303machine # [ 0.443674] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned304machine # [ 0.443745] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned305machine # [ 0.443791] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned306machine # [ 0.443813] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned307machine # [ 0.443835] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned308machine # [ 0.443857] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned309machine # [ 0.443879] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned310machine # [ 0.443915] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned311machine # [ 0.443935] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned312machine # [ 0.443957] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned313machine # [ 0.443979] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned314machine # [ 0.444001] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned315machine # [ 0.444024] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned316machine # [ 0.444047] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned317machine # [ 0.444069] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned318machine # [ 0.444091] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned319machine # [ 0.444112] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned320machine # [ 0.444143] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned321machine # [ 0.444164] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned322machine # [ 0.444186] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned323machine # [ 0.444208] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned324machine # [ 0.444230] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned325machine # [ 0.444253] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned326machine # [ 0.444284] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]327machine # [ 0.444294] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]328machine # [ 0.444299] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]329machine # [ 0.445488] pci 0000:00:0a.0: enabling device (0000 -> 0002)330machine # [ 0.510469] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)331machine # [ 0.513516] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)332machine # [ 0.515585] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)333machine # [ 0.525515] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)334machine # [ 0.527872] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)335machine # [ 0.538911] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)336machine # [ 0.545340] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)337machine # [ 0.547441] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)338machine # [ 0.557889] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)339machine # [ 0.559778] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)340machine # [ 0.570001] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)341machine # [ 0.584655] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled342machine # [ 0.587402] msm_serial: driver initialized343machine # [ 0.587555] SuperH (H)SCI(F) driver initialized344machine # [ 0.587609] STM32 USART driver initialized345machine # [ 0.622072] loop: module loaded346machine # [ 0.622294] virtio_blk virtio5: 1/0/0 default/read/poll queues347machine # [ 0.623131] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)348machine # [ 0.628814] megasas: 07.734.00.00-rc1349machine # [ 0.629565] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]350machine # [ 0.639985] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000351machine # [ 0.640051] Intel/Sharp Extended Query Table at 0x0031352machine # [ 0.642316] Using buffer write method353machine # [ 0.642434] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]354machine # [ 0.651942] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000355machine # [ 0.651993] Intel/Sharp Extended Query Table at 0x0031356machine # [ 0.654920] Using buffer write method357machine # [ 0.654954] Concatenating MTD devices:358machine # [ 0.654959] (0): "0.flash"359machine # [ 0.654962] (1): "0.flash"360machine # [ 0.654966] into device "0.flash"361machine # [ 0.944058] Freeing initrd memory: 26104K362machine # [ 0.950036] tun: Universal TUN/TAP device driver, 1.6363machine # [ 0.954021] thunder_xcv, ver 1.0364machine # [ 0.954065] thunder_bgx, ver 1.0365machine # [ 0.954086] nicpf, ver 1.0366machine # [ 0.954609] e1000: Intel(R) PRO/1000 Network Driver367machine # [ 0.954617] e1000: Copyright (c) 1999-2006 Intel Corporation.368machine # [ 0.954644] e1000e: Intel(R) PRO/1000 Network Driver369machine # [ 0.954653] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.370machine # [ 0.954678] igb: Intel(R) Gigabit Ethernet Network Driver371machine # [ 0.954683] igb: Copyright (c) 2007-2014 Intel Corporation.372machine # [ 0.954704] igbvf: Intel(R) Gigabit Virtual Function Network Driver373machine # [ 0.954709] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.374machine # [ 0.954841] sky2: driver version 1.30375machine # [ 0.963516] usbcore: registered new interface driver usb-storage376machine # [ 0.963616] usbcore: registered new interface driver usbserial_generic377machine # [ 0.963629] usbserial: USB Serial support registered for generic378machine # [ 0.964704] ehci-pci 0000:00:0a.0: EHCI Host Controller379machine # [ 0.964758] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1380machine # [ 0.964967] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000381machine # [ 0.970063] hv_vmbus: registering driver hyperv_keyboard382machine # [ 0.971668] rtc-pl031 9010000.pl031: registered as rtc0383machine # [ 0.971705] rtc-pl031 9010000.pl031: setting system clock to 2026-09-06T02:30:19 UTC (1788661819)384machine # [ 0.973846] i2c_dev: i2c /dev entries driver385machine # [ 0.977076] sdhci: Secure Digital Host Controller Interface driver386machine # [ 0.977092] sdhci: Copyright(c) Pierre Ossman387machine # [ 0.977350] Synopsys Designware Multimedia Card Interface Driver388machine # [ 0.977708] sdhci-pltfm: SDHCI platform and OF driver helper389machine # [ 0.979421] hid: raw HID events driver (C) Jiri Kosina390machine # [ 0.979638] usbcore: registered new interface driver usbhid391machine # [ 0.979645] usbhid: USB HID core driver392machine # [ 0.984467] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00393machine # [ 0.985684] hub 1-0:1.0: USB hub found394machine # [ 0.986246] hub 1-0:1.0: 6 ports detected395machine # [ 0.988039] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available396machine # [ 0.989546] drop_monitor: Initializing network drop monitor service397machine # [ 0.989702] NET: Registered PF_INET6 protocol family398machine # [ 0.992768] Segment Routing with IPv6399machine # [ 0.992798] In-situ OAM (IOAM) with IPv6400machine # [ 0.992825] NET: Registered PF_PACKET protocol family401machine # [ 0.994554] 9pnet: Installing 9P2000 support402machine # [ 0.997405] Key type dns_resolver registered403machine # [ 1.003815] registered taskstats version 1404machine # [ 1.004664] Loading compiled-in X.509 certificates405machine # [ 1.013341] Demotion targets for Node 0: null406machine # [ 1.013478] Key type .fscrypt registered407machine # [ 1.013485] Key type fscrypt-provisioning registered408machine # [ 1.013592] ima: No TPM chip found, activating TPM-bypass!409machine # [ 1.013613] ima: Allocated hash algorithm: sha1410machine # [ 1.013636] ima: No architecture policies found411machine # [ 1.017975] input: gpio-keys as /devices/platform/gpio-keys/input/input0412machine # [ 1.037104] clk: Disabling unused clocks413machine # [ 1.037144] PM: genpd: Disabling unused power domains414machine # [ 1.041539] Freeing unused kernel memory: 4736K415machine # [ 1.041768] Run /init as init process416machine # [ 1.058645] systemd[1]: Successfully made /usr/ read-only.417machine # [ 1.231990] usb 1-1: new high-speed USB device number 2 using ehci-pci418machine # [ 1.384531] 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.393789] systemd[1]: systemd 262-9457f81 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.393858] systemd[1]: Detected virtualization qemu.421machine # [ 1.393946] systemd[1]: Detected architecture arm64.422machine # [ 1.393972] systemd[1]: Running in initrd.423machine # [ 1.394992] systemd[1]: Initializing machine ID from random generator.424machine # [ 1.395295] systemd[1]: Hostname set to <machine>.425machine # [ 1.472166] 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.532827] systemd[1]: bpf-restrict-fs: LSM BPF program attached427machine # [ 1.586633] systemd[1]: Queued start job for default target Initrd Default Target.428machine # [ 1.592003] usb 1-2: new high-speed USB device number 3 using ehci-pci429machine # [ 1.597969] systemd[1]: Created slice Slice /system/modprobe.430machine # [ 1.598928] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.431machine # [ 1.600081] systemd[1]: Expecting device /dev/disk/by-label/nixos...432machine # [ 1.600901] systemd[1]: Reached target Path Units.433machine # [ 1.601630] systemd[1]: Reached target Slice Units.434machine # [ 1.602268] systemd[1]: Reached target Swaps.435machine # [ 1.602852] systemd[1]: Reached target Timer Units.436machine # [ 1.603694] systemd[1]: Listening on D-Bus System Message Bus Socket.437machine # [ 1.604781] systemd[1]: Listening on Journal Socket (/dev/log).438machine # [ 1.605656] systemd[1]: Listening on Journal Sockets.439machine # [ 1.606453] systemd[1]: Listening on udev Kernel Socket.440machine # [ 1.607163] systemd[1]: Reached target Socket Units.441machine # [ 1.609262] systemd[1]: Starting Create List of Static Device Nodes...442machine # [ 1.618988] systemd[1]: Starting Load Kernel Module 9pnet_virtio...443machine # [ 1.620142] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs444machine # [ 1.633285] systemd[1]: Mounting Kernel Configuration File System...445machine # [ 1.652807] systemd[1]: Starting Journal Service...446machine # [ 1.655207] systemd[1]: Starting Load Kernel Modules...447machine # [ 1.656368] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os448machine # [ 1.669337] systemd[1]: Starting Coldplug All udev Devices...449machine # [ 1.688090] systemd[1]: Finished Create List of Static Device Nodes.450machine # [ 1.689323] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.451machine # [ 1.689665] systemd[1]: Finished Load Kernel Module 9pnet_virtio.452machine # [ 1.689909] systemd[1]: Mounted Kernel Configuration File System.453machine # [ 1.708463] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...454machine # [ 1.731523] systemd-journald[73]: Collecting audit messages is disabled.455machine # [ 1.736269] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.456machine # [ 1.745004] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev457machine # [ 1.748270] 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/input2458machine # [ 1.750531] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0459machine # [ 1.773735] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0460machine # [ 1.774040] [drm] features: -virgl +edid -resource_blob -host_visible461machine # [ 1.774050] [drm] features: -context_init462machine # [ 1.774827] [drm] number of scanouts: 1463machine # [ 1.774846] [drm] number of cap sets: 0464machine # [ 1.778889] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.465machine # [ 1.784282] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic466machine # [ 1.784303] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0467machine # [ 1.790233] systemd[1]: Starting Create Static Device Nodes in /dev...468machine # [ 1.820553] Console: switching to colour frame buffer device 160x50469machine # [ 1.844624] systemd[1]: Finished Create Static Device Nodes in /dev.470machine # [ 1.844828] systemd[1]: Reached target Preparation for Local File Systems.471machine # [ 1.844854] systemd[1]: Reached target Local File Systems.472machine # [ 1.852597] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device473machine # [ 1.855781] systemd[1]: Starting Rule-based Manager for Device Events and Files...474machine # [ 1.864538] systemd[1]: Finished Load Kernel Modules.475machine # [ 1.896055] systemd[1]: Starting Apply Kernel Variables...476machine # [ 1.936635] systemd[1]: Finished Apply Kernel Variables.477machine # [ 1.928566] systemd-modules-load[74]: Inserted module 'dm_mod'478machine # [ 1.948509] systemd[1]: Started Journal Service.479machine # [ 1.935839] systemd-modules-load[74]: Module 'virtio_balloon' is built in480machine # [ 1.944522] systemd-modules-load[74]: Module 'virtio_console' is built in481machine # [ 1.945679] systemd-modules-load[74]: Inserted module 'virtio_gpu'482machine # [ 1.946675] systemd-modules-load[74]: Module 'virtio_rng' is built in483machine # [ 1.947722] systemd[1]: Starting Create System Files and Directories...484machine # [ 1.964786] systemd-udevd[80]: Using default interface naming scheme 'v261'.485machine # [ 1.993655] systemd[1]: Finished Create System Files and Directories.486machine # [ 2.012462] systemd[1]: Started Rule-based Manager for Device Events and Files.487machine # [ 2.076748] systemd[1]: Starting Virtual Console Setup...488machine # [ 2.156544] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.489machine # [ 2.160075] systemd[1]: Finished Virtual Console Setup.490machine # [ 2.818404] systemd[1]: Finished Coldplug All udev Devices.491machine # [ 2.820146] systemd[1]: Reached target System Initialization.492machine # [ 2.821270] systemd[1]: Reached target Basic System.493machine # [ 2.951918] (udev-worker)[95]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.494machine # [ 2.990986] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.495machine # [ 2.996925] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.496machine # [ 3.069212] systemd[1]: Found device /dev/disk/by-label/nixos.497machine # [ 3.072804] systemd[1]: Reached target Initrd Root Device.498machine # [ 3.077366] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...499machine # [ 3.137033] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks500machine # [ 3.152265] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.501machine # [ 3.155155] systemd[1]: Mounting /sysroot...502machine # [ 3.221153] EXT4-fs (vda): mounted filesystem 2ba3810e-5a4f-4528-a727-7313517e3af4 r/w with ordered data mode. Quota mode: none.503machine # [ 3.212950] systemd[1]: Mounted /sysroot.504machine # [ 3.213985] systemd[1]: Reached target Initrd Root File System.505machine # [ 3.217514] systemd[1]: Starting Mountpoints Configured in the Real Root...506machine # [ 3.250964] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.507machine # [ 3.257553] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...508machine # [ 3.261885] systemd[1]: Reloading...509machine # [ 3.476127] systemd[1]: Reloading finished in 220 ms.510machine # [ 3.508649] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...511machine # [ 3.515259] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...512machine # [ 3.520875] systemd[1]: Starting Load Kernel Module 9pnet_virtio...513machine # [ 3.527010] systemd[1]: initrd-parse-etc.service: Deactivated successfully.514machine # [ 3.530328] systemd[1]: Finished Mountpoints Configured in the Real Root.515machine # [ 3.534056] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.516machine # [ 3.559284] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.517machine # [ 3.562896] systemd[1]: Finished Load Kernel Module 9pnet_virtio.518machine # [ 3.673094] systemd[1]: Mounting /sysroot/nix/.ro-store...519machine # [ 3.691614] systemd[1]: Mounting /sysroot/nix/.rw-store...520machine # [ 3.696232] systemd[1]: Mounting /sysroot/run...521machine # [ 3.715608] systemd[1]: Mounting /sysroot/tmp/shared...522machine # [ 3.737371] systemd[1]: Mounting /sysroot/tmp/xchg...523machine # [ 3.789194] systemd[1]: Mounted /sysroot/nix/.ro-store.524machine # [ 3.807713] systemd[1]: Mounted /sysroot/nix/.rw-store.525machine # [ 3.814408] systemd[1]: Mounted /sysroot/run.526machine # [ 3.830880] systemd[1]: Mounted /sysroot/tmp/shared.527machine # [ 3.847626] systemd[1]: Starting rw-sysroot-nix-store.service...528machine # [ 3.852448] systemd[1]: Mounted /sysroot/tmp/xchg.529machine # [ 3.880255] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.530machine # [ 3.881983] systemd[1]: Finished rw-sysroot-nix-store.service.531machine # [ 4.485065] (udev-worker)[101]: mtd0ro: Failed to find and pin callout binary "/nix/store/x4i8f5p0japrw9j9zrdx7z9lf5387qn3-systemd-262-9457f81/lib/udev/mtd_probe": No such file or directory532machine # [ 4.489805] (udev-worker)[101]: mtd0ro: /nix/store/x4i8f5p0japrw9j9zrdx7z9lf5387qn3-systemd-262-9457f81/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.518032] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.534machine # [ 4.520335] systemd[1]: Stopped Virtual Console Setup.535machine # [ 4.524231] systemd[1]: Stopping Virtual Console Setup...536machine # [ 4.527496] systemd[1]: Starting Virtual Console Setup...537machine # [ 4.541581] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.538machine # [ 4.544181] systemd[1]: Stopped Virtual Console Setup.539machine # [ 4.548265] systemd[1]: Starting Virtual Console Setup...540machine # [ 4.569681] systemd-vconsole-setup[155]: Configuration of first virtual console was skipped, ignoring remaining ones.541machine # [ 4.573621] systemd[1]: Finished Virtual Console Setup.542machine # [ 4.674490] systemd[1]: Mounting /sysroot/nix/store...543machine # [ 4.725309] systemd[1]: Mounted /sysroot/nix/store.544machine # [ 4.728472] systemd[1]: Reached target Initrd File Systems.545machine # [ 4.732510] systemd[1]: Starting Find NixOS closure...546machine # [ 4.744484] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...547machine # [ 4.786461] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.548machine # [ 4.790013] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.549machine # [ 4.805016] systemd[1]: Finished Find NixOS closure.550machine # [ 4.807765] systemd[1]: Reached target Initrd Default Target.551machine # [ 4.809729] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...552machine # [ 4.841447] systemd[1]: Stopped target Initrd Default Target.553machine # [ 4.843284] systemd[1]: Stopped target Basic System.554machine # [ 4.848535] systemd[1]: Stopped target Initrd Root Device.555machine # [ 4.849525] systemd[1]: Stopped target Path Units.556machine # [ 4.850363] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.557machine # [ 4.851618] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.558machine # [ 4.855149] systemd[1]: Stopped target Slice Units.559machine # [ 4.860171] systemd[1]: Stopped target Socket Units.560machine # [ 4.861066] systemd[1]: Stopped target System Initialization.561machine # [ 4.861973] systemd[1]: Stopped target Swaps.562machine # [ 4.862691] systemd[1]: Stopped target Timer Units.563machine # [ 4.863718] systemd[1]: dbus.socket: Deactivated successfully.564machine # [ 4.866198] systemd[1]: Closed D-Bus System Message Bus Socket.565machine # [ 4.872201] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.566machine # [ 4.874948] systemd[1]: Stopped Find NixOS closure.567machine # [ 4.876528] systemd[1]: Starting Load Kernel Module 9pnet_virtio...568machine # [ 4.878139] systemd[1]: Starting rw-sysroot-nix-store.service...569machine # [ 4.880909] systemd[1]: systemd-sysctl.service: Deactivated successfully.570machine # [ 4.887296] systemd[1]: Stopped Apply Kernel Variables.571machine # [ 4.888391] systemd[1]: systemd-modules-load.service: Deactivated successfully.572machine # [ 4.891145] systemd[1]: Stopped Load Kernel Modules.573machine # [ 4.892893] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.574machine # [ 4.894019] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.575machine # [ 4.895091] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.576machine # [ 4.905554] systemd[1]: Stopped Create System Files and Directories.577machine # [ 4.911538] systemd[1]: Stopped target Local File Systems.578machine # [ 4.916666] systemd[1]: Stopped target Preparation for Local File Systems.579machine # [ 4.917774] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.580machine # [ 4.918808] systemd[1]: Stopped Coldplug All udev Devices.581machine # [ 4.919677] systemd[1]: Stopping Rule-based Manager for Device Events and Files...582machine # [ 4.924427] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.583machine # [ 4.928249] systemd[1]: Stopped Virtual Console Setup.584machine # [ 4.929066] systemd[1]: initrd-cleanup.service: Deactivated successfully.585machine # [ 4.930927] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.586machine # [ 4.933067] systemd[1]: systemd-udevd.service: Deactivated successfully.587machine # [ 4.934479] systemd[1]: Stopped Rule-based Manager for Device Events and Files.588machine # [ 4.937059] systemd[1]: systemd-udevd.service: Consumed 1.575s CPU time over 3.068s wall clock time, 22M memory peak.589machine # [ 4.938775] systemd[1]: Starting Cleanup udev Database...590machine # [ 4.939572] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.591machine # [ 4.941008] systemd[1]: Stopped Create Static Device Nodes in /dev.592machine # [ 4.941892] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.593machine # [ 4.943009] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.594machine # [ 4.943998] systemd[1]: kmod-static-nodes.service: Deactivated successfully.595machine # [ 4.949252] systemd[1]: Stopped Create List of Static Device Nodes.596machine # [ 4.950209] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.597machine # [ 4.952867] systemd[1]: Finished Load Kernel Module 9pnet_virtio.598machine # [ 4.956404] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.599machine # [ 4.957508] systemd[1]: Finished rw-sysroot-nix-store.service.600machine # [ 4.989813] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.601machine # [ 4.992703] systemd[1]: Finished Cleanup udev Database.602machine # [ 4.994449] systemd[1]: Reached target Switch Root.603machine # [ 4.995714] systemd[1]: Starting NixOS Activation...604machine # [ 5.179615] initrd-nixos-activation-start[182]: booting system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test605machine # [ 5.249496] initrd-nixos-activation-start[182]: running activation script...606machine # [ 5.801530] initrd-nixos-activation-start[205]: setting up /etc...607machine # [ 6.103753] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.608machine # [ 6.106861] systemd[1]: Finished NixOS Activation.609machine # [ 6.108220] systemd[1]: Starting Switch Root...610machine # [ 6.135634] systemd[1]: Switching root.611machine # [ 6.333377] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).612machine # [ 6.951611] systemd[1]: systemd 262-9457f81 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.964973] systemd[1]: Detected virtualization qemu.614machine # [ 6.968470] systemd[1]: Detected architecture arm64.615machine # [ 6.972550] systemd[1]: Detected first boot.616machine # [ 6.981167] systemd[1]: Initializing machine ID from random generator.617machine # [ 7.166821] systemd[1]: bpf-restrict-fs: LSM BPF program attached618machine # [ 7.354381] systemd[1]: Applying preset policy.619machine # [ 7.875586] systemd[1]: Populated /etc with preset unit settings.620machine # [ 8.487835] systemd[1]: initrd-switch-root.service: Deactivated successfully.621machine # [ 8.489654] systemd[1]: Stopped initrd-switch-root.service.622machine # [ 8.492947] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.623machine # [ 8.496322] systemd[1]: Created slice Slice /system/getty.624machine # [ 8.499384] systemd[1]: Created slice User and Session Slice.625machine # [ 8.501651] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.626machine # [ 8.504106] systemd[1]: Started Forward Password Requests to Wall Directory Watch.627machine # [ 8.506221] systemd[1]: Expecting device /dev/hvc0...628machine # [ 8.508528] systemd[1]: Expecting device /dev/ttyAMA0...629machine # [ 8.510555] systemd[1]: Reached target Local Encrypted Volumes.630machine # [ 8.512764] systemd[1]: Stopped target initrd-fs.target.631machine # [ 8.514690] systemd[1]: Stopped target initrd-root-fs.target.632machine # [ 8.516566] systemd[1]: Stopped target initrd-switch-root.target.633machine # [ 8.518399] systemd[1]: Reached target Virtual Machines and Containers.634machine # [ 8.520799] systemd[1]: Reached target Path Units.635machine # [ 8.522638] systemd[1]: Reached target Remote File Systems.636machine # [ 8.525349] systemd[1]: Reached target Slice Units.637machine # [ 8.527480] systemd[1]: Reached target Swaps.638machine # [ 8.532231] systemd[1]: Listening on Query the User Interactively for a Password.639machine # [ 8.541358] systemd[1]: Listening on Process Core Dump Socket.640machine # [ 8.552752] systemd[1]: Listening on Credential Encryption/Decryption.641machine # [ 8.558630] systemd[1]: Listening on Factory Reset Management.642machine # [ 8.561279] systemd[1]: Listening on Hostname Service Socket.643machine # [ 8.578214] systemd[1]: Starting Journal Log Access Socket...644machine # [ 8.581233] systemd[1]: Listening on Journal Audit Socket.645machine # [ 8.593339] systemd[1]: Listening on Console Output Muting Service Socket.646machine # [ 8.596642] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.647machine # [ 8.598588] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os648machine # [ 8.602221] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki649machine # [ 8.621157] systemd[1]: Listening on Disk Repartitioning Service Socket.650machine # [ 8.623181] systemd[1]: Listening on udev Varlink Socket.651machine # [ 8.632940] systemd[1]: Mounting Huge Pages File System...652machine # [ 8.644858] systemd[1]: Mounting POSIX Message Queue File System...653machine # [ 8.663747] systemd[1]: Mounting Kernel Debug File System...654machine # [ 8.677390] systemd[1]: Mounting Kernel Trace File System...655machine # [ 8.693926] systemd[1]: Starting Create List of Static Device Nodes...656machine # [ 8.712469] systemd[1]: Starting Load Kernel Module 9pnet_virtio...657machine # [ 8.714117] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs658machine # [ 8.734275] systemd[1]: Mounting Kernel Configuration File System...659machine # [ 8.737265] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm660machine # [ 8.742309] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore661machine # [ 8.814189] systemd[1]: Starting Load Kernel Module fuse...662machine # [ 8.825758] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67663machine # [ 8.857992] systemd[1]: Starting Journal Service...664machine # [ 8.886546] systemd[1]: Starting Load Kernel Modules...665machine # [ 8.916981] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...666machine # [ 8.938229] fuse: init (API version 7.45)667machine # [ 8.943482] systemd[1]: Starting Remount Root and Kernel File Systems...668machine # [ 8.957656] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os669machine # [ 8.978585] systemd[1]: Starting Coldplug All udev Devices...670machine # [ 8.998307] systemd-journald[277]: Collecting audit messages is enabled.671machine # [ 9.006125] systemd[1]: Listening on Journal Log Access Socket.672machine # [ 9.001609] systemd[1]: Queued start job for default target Multi-User System.673machine # [ 9.008625] systemd[1]: systemd-journald.service: Deactivated successfully.674machine # [ 9.026817] systemd[1]: Started Journal Service.675machine # [ 9.031821] systemd[1]: Mounted Huge Pages File System.676machine # [ 9.041297] systemd[1]: Mounted POSIX Message Queue File System.677machine # [ 9.046576] systemd[1]: Mounted Kernel Debug File System.678machine # [ 9.058024] systemd[1]: Mounted Kernel Trace File System.679machine # [ 9.063988] systemd-modules-load[278]: Module 'atkbd' is built in680machine # [ 9.072389] systemd[1]: Finished Create List of Static Device Nodes.681machine # [ 9.076567] systemd-modules-load[278]: Module 'loop' is built in682machine # [ 9.085386] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.683machine # [ 9.086400] systemd[1]: Finished Load Kernel Module 9pnet_virtio.684machine # [ 9.087199] systemd[1]: Mounted Kernel Configuration File System.685machine # [ 9.097912] systemd[1]: modprobe@fuse.service: Deactivated successfully.686machine # [ 9.098922] systemd[1]: Finished Load Kernel Module fuse.687machine # [ 9.099645] systemd[1]: Finished Load Kernel Modules.688machine # [ 9.120193] EXT4-fs (vda): re-mounted 2ba3810e-5a4f-4528-a727-7313517e3af4.689machine # [ 9.121248] systemd[1]: Finished Remount Root and Kernel File Systems.690machine # [ 9.130404] systemd[1]: Listening on Disk Image Download Service Socket.691machine # [ 9.140380] systemd[1]: Mounting FUSE Control File System...692machine # [ 9.157447] systemd[1]: Starting Firewall...693machine # [ 9.177066] systemd-oomd[279]: No swap; memory pressure usage will be degraded694machine # [ 9.184854] systemd[1]: Starting Flush Journal to Persistent Storage...695machine # [ 9.188282] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore696machine # [ 9.200497] systemd[1]: Starting Load/Save OS Random Seed...697machine # [ 9.226245] systemd[1]: Starting Apply Kernel Variables...698machine # [ 9.251873] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...699machine # [ 9.261261] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os700machine # [ 9.277314] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.701machine # [ 9.366895] systemd-journald[277]: Received client request to flush runtime journal.702machine # [ 9.443773] systemd[1]: Finished Load/Save OS Random Seed.703machine # [ 9.452973] systemd[1]: Reached target First Boot Complete.704machine # [ 9.462139] systemd[1]: Mounted FUSE Control File System.705machine # [ 9.468605] systemd[1]: Finished Flush Journal to Persistent Storage.706machine # [ 9.472642] systemd[1]: Finished Apply Kernel Variables.707machine # [ 9.518687] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.708machine # [ 9.527132] systemd[1]: Starting Create Static Device Nodes in /dev...709machine # [ 9.644589] systemd[1]: Finished Create Static Device Nodes in /dev.710machine # [ 9.648431] systemd[1]: Reached target Preparation for Local File Systems.711machine # [ 9.655553] systemd[1]: Mounting /run/wrappers...712machine # [ 9.663536] systemd[1]: Starting Rule-based Manager for Device Events and Files...713machine # [ 9.755017] systemd[1]: Mounted /run/wrappers.714machine # [ 9.756923] systemd[1]: Reached target Local File Systems.715machine # [ 9.763785] systemd[1]: Listening on Boot Loader Control Service Socket.716machine # [ 9.772119] systemd[1]: Starting register-nix-paths.service...717machine # [ 9.782452] systemd[1]: Starting Create SUID/SGID Wrappers...718machine # [ 9.785273] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.719machine # [ 9.815562] systemd[1]: Starting Save Transient machine-id to Disk...720machine # [ 9.828869] systemd[1]: Starting Create System Files and Directories...721machine # [ 9.888257] systemd-udevd[323]: Using default interface naming scheme 'v261'.722machine # [ 10.026243] systemd[1]: Finished Save Transient machine-id to Disk.723machine # [ 10.107108] systemd[1]: Finished Create System Files and Directories.724machine # [ 10.114925] systemd[1]: Starting Rebuild Journal Catalog...725machine # [ 10.122822] systemd[1]: Starting Record System Boot/Shutdown in UTMP...726machine # [ 10.273032] systemd[1]: Started Rule-based Manager for Device Events and Files.727machine # [ 10.294097] systemd[1]: Finished Record System Boot/Shutdown in UTMP.728machine # [ 10.389596] systemd[1]: Finished Rebuild Journal Catalog.729machine # [ 10.413310] systemd[1]: Starting Update is Completed...730machine # [ 10.475277] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.731machine # [ 10.513080] systemd[1]: Finished Update is Completed.732machine # [ 10.834677] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.733machine # [ 10.838403] systemd[1]: Finished Create SUID/SGID Wrappers.734machine # [ 11.419837] systemd[1]: Finished Coldplug All udev Devices.735machine # [ 11.432120] systemd[1]: Finished register-nix-paths.service.736machine # [ 11.435867] systemd[1]: Reached target System Initialization.737machine # [ 11.438597] systemd[1]: Started Discard unused filesystem blocks once a week.738machine # [ 11.444410] systemd[1]: Started Daily Cleanup of Temporary Directories.739machine # [ 11.445662] systemd[1]: Reached target Timer Units.740machine # [ 11.446915] systemd[1]: Listening on D-Bus System Message Bus Socket.741machine # [ 11.456610] systemd[1]: Listening on Nix Daemon Socket.742machine # [ 11.464928] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.743machine # [ 11.466308] systemd[1]: Reached target Socket Units.744machine # [ 11.468820] systemd[1]: Reached target Basic System.745machine # [ 11.470885] systemd[1]: Starting Import lastlog data into lastlog2 database...746machine # [ 11.480357] systemd[1]: Starting Name Service Cache Daemon (nsncd)...747machine # [ 11.483951] systemd[1]: Starting Post-Boot Actions...748machine # [ 11.506779] systemd[1]: Started Reset console on configuration changes.749machine # [ 11.526000] systemd[1]: Starting resolvconf update...750machine # [ 11.648226] systemd[1]: Starting D-Bus System Message Bus...751machine # [ 11.694750] systemd[1]: Finished Post-Boot Actions.752machine # [ 11.709354] nsncd[468]: Sep 06 02:30:30.250 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"753machine # [ 11.722510] systemd[1]: Started Name Service Cache Daemon (nsncd).754machine # [ 11.768563] systemd[1]: Reached target Host and Network Name Lookups.755machine # [ 11.773224] systemd[1]: Reached target User and Group Name Lookups.756machine # [ 11.774220] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs757machine # [ 11.791051] systemd[1]: Starting User Login Management...758machine # [ 11.813025] systemd[1]: Finished Import lastlog data into lastlog2 database.759machine # [ 11.961046] systemd[1]: Finished Firewall.760machine # [ 11.977251] dbus-broker-launch[478]: Looking up NSS user entry for 'systemd-timesync'...761machine # [ 11.994839] dbus-broker-launch[478]: NSS returned no entry for 'systemd-timesync'762machine # [ 11.997340] dbus-broker-launch[478]: Invalid user-name in /nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"763machine # [ 12.041253] systemd-logind[500]: New seat seat0.764machine # [ 12.048817] systemd[1]: Started D-Bus System Message Bus.765machine # [ 12.053975] systemd[1]: Started User Login Management.766machine # [ 12.062674] systemd[1]: Starting linger-users.service...767machine # [ 12.092118] systemd[1]: Stopped target Host and Network Name Lookups.768machine # [ 12.099063] systemd[1]: Stopping Host and Network Name Lookups...769machine # [ 12.106423] systemd[1]: Stopped target User and Group Name Lookups.770machine # [ 12.112480] systemd[1]: Stopping User and Group Name Lookups...771machine # [ 12.120313] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...772machine # [ 12.121327] systemd[1]: nscd.service: Deactivated successfully.773machine # [ 12.122235] systemd[1]: Stopped Name Service Cache Daemon (nsncd).774machine # [ 12.123434] dbus-broker-launch[478]: Ready775machine # [ 12.162784] systemd[1]: Starting Name Service Cache Daemon (nsncd)...776machine # [ 12.257059] systemd[1]: Created slice Slice /user/1001.777machine # [ 12.266692] systemd[1]: Starting User Runtime Directory /run/user/1001...778machine # [ 12.289909] nsncd[558]: Sep 06 02:30:30.831 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"779machine # [ 12.304366] systemd[1]: Started Name Service Cache Daemon (nsncd).780machine # [ 12.316192] systemd[1]: linger-users.service: Deactivated successfully.781machine # [ 12.321071] systemd[1]: Finished linger-users.service.782machine # [ 12.325963] systemd[1]: Reached target Host and Network Name Lookups.783machine # [ 12.333655] systemd[1]: Reached target User and Group Name Lookups.784machine # [ 12.348381] systemd[1]: Finished resolvconf update.785machine # [ 12.350763] systemd[1]: Reached target Preparation for Network.786machine # [ 12.359413] systemd[1]: Starting DHCP Client...787machine # [ 12.368336] systemd[1]: Starting Extra networking commands....788machine # [ 12.404200] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.789machine # [ 12.450253] systemd[1]: Finished User Runtime Directory /run/user/1001.790machine # [ 12.488268] systemd[1]: Starting User Manager for UID 1001...791machine # [ 12.525923] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.792machine # [ 12.548418] systemd[1]: Started backdoor.service.793machine # [ 12.665815] (systemd)[589]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)794machine # [ 12.701871] systemd-logind[500]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.795machine # [ 12.782169] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy796machine # [ 12.974427] (udev-worker)[391]: Network interface NamePolicy= disabled on kernel command line.797machine # [ 12.991739] (udev-worker)[386]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.798machine # [ 13.021897] (udev-worker)[386]: Network interface NamePolicy= disabled on kernel command line.799machine # connecting to host...800machine # [ 13.046320] systemd[1]: Condition check resulted in Virtio network device being skipped.801machine # [ 13.058079] systemd[1]: Starting Address configuration of eth1...802machine # [ 13.097786] systemd[589]: Queued start job for default target Main User Target.803machine # [ 13.111576] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy804machine # [ 13.137731] systemd[589]: Created slice User Application Slice.805machine # [ 13.138570] systemd[589]: Started Daily Cleanup of User's Temporary Directories.806machine # [ 13.139586] systemd[589]: Reached target Paths.807machine: Guest shell says: b'Spawning backdoor root shell...\n'808machine # [ 13.150349] systemd[589]: Reached target Timers.809machine: connected to guest root shell810machine: (connecting took 13.43 seconds)811machine: (finished: waiting for the VM to finish booting, in 13.95 seconds)812machine # [ 13.163059] systemd[589]: Starting D-Bus User Message Bus Socket...813machine # [ 13.168904] systemd[589]: Starting Create User Files and Directories...814machine # [ 13.208998] systemd[589]: Finished Create User Files and Directories.815machine # [ 13.285331] systemd[589]: Listening on D-Bus User Message Bus Socket.816machine # [ 13.286380] systemd[589]: Reached target Sockets.817machine # [ 13.287043] systemd[589]: Reached target Basic System.818machine # [ 13.287761] systemd[1]: Started User Manager for UID 1001.819machine # [ 13.328720] systemd[589]: Starting Run user-specific NixOS activation...820machine # [ 13.379929] systemd[589]: Finished Run user-specific NixOS activation.821machine # [ 13.392181] systemd[589]: Reached target Main User Target.822machine # [ 13.411565] systemd[589]: Startup finished in 623ms.823machine # [ 13.429134] systemd-logind[500]: Watching system buttons on /dev/input/event0 (gpio-keys)824machine # [ 13.430597] dhcpcd[596]: dhcpcd-10.3.2 starting825machine # [ 13.502827] dhcpcd[676]: dev: loaded udev826machine # [ 13.570451] 8021q: 802.1Q VLAN Support v1.8827machine # [ 13.570844] 8021q: adding VLAN 0 to HW filter on device eth1828machine # [ 13.579049] mousedev: PS/2 mouse device common for all mice829machine # [ 13.628415] network-addresses-eth1-start[618]: adding address 192.168.1.1/24... done830machine # [ 13.670656] cfg80211: Loading compiled-in X.509 certificates for regulatory database831machine # [ 13.677986] systemd[1]: Finished Extra networking commands..832machine # [ 13.681148] systemd[1]: Reached target Network.833machine # [ 13.693368] systemd[1]: Starting Permit User Sessions...834machine # [ 13.715290] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'835machine # [ 13.715803] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'836machine # [ 13.721025] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2837machine # [ 13.721365] cfg80211: failed to load regulatory.db838machine # [ 13.715391] systemd-logind[500]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)839machine # [ 13.724569] network-addresses-eth1-start[618]: adding address 2001:db8:1::1/64... done840machine # [ 13.766260] systemd[1]: Finished Address configuration of eth1.841machine # [ 13.890464] systemd[1]: Finished Permit User Sessions.842machine # [ 13.902713] systemd[1]: Started Getty on tty1.843machine # [ 13.917683] systemd[1]: Reached target Login Prompts.844machine # [ 14.133408] 8021q: adding VLAN 0 to HW filter on device eth0845machine # [ 14.127886] dhcpcd[676]: eth0: waiting for carrier846machine # [ 14.138119] dhcpcd[676]: libudev: received NULL device847machine # [ 14.139110] dhcpcd[676]: libudev: received NULL device848machine # [ 14.139859] dhcpcd[676]: eth0: carrier acquired849machine # [ 14.226111] dhcpcd[676]: DUID 00:01:00:01:32:2f:8c:c8:52:54:00:12:34:56850machine # [ 14.227229] dhcpcd[676]: eth0: IAID 00:12:34:56851machine # [ 14.227884] dhcpcd[676]: eth0: adding address fe80::5054:ff:fe12:3456852machine # [ 14.492692] dhcpcd[676]: eth0: soliciting a DHCP lease853machine # [ 14.504976] dhcpcd[676]: eth0: offered 10.0.2.15 from 10.0.2.2854machine # [ 14.516142] dhcpcd[676]: eth0: probing address 10.0.2.15/24855machine # [ 14.616502] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3856machine # [ 14.963369] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.857machine # [ 14.978692] systemd[1]: Starting Virtual Console Setup...858machine # [ 15.031077] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.859machine # [ 15.037145] systemd[1]: Stopped Virtual Console Setup.860machine # [ 15.039831] systemd[1]: Starting Virtual Console Setup...861machine # [ 15.044554] systemd-logind[500]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)862machine # [ 15.546179] systemd-vconsole-setup[721]: Configuration of first virtual console was skipped, ignoring remaining ones.863machine # [ 15.551306] systemd[1]: Finished Virtual Console Setup.864machine # [ 16.549109] dhcpcd[676]: eth0: soliciting an IPv6 router865machine # [ 16.552648] dhcpcd[676]: eth0: Router Advertisement from fe80::2866machine # [ 16.555263] dhcpcd[676]: eth0: adding address fec0::5054:ff:fe12:3456/64867machine # [ 16.558375] dhcpcd[676]: eth0: adding route to fec0::/64868machine # [ 16.560752] dhcpcd[676]: eth0: adding default route via fe80::2869machine # [ 18.569300] dhcpcd[676]: eth0: leased 10.0.2.15 for 86400 seconds870machine # [ 18.574675] dhcpcd[676]: eth0: adding route to 10.0.2.0/24871machine # [ 18.578239] dhcpcd[676]: eth0: adding default route via 10.0.2.2872machine # [ 18.741442] systemd[1]: Started DHCP Client.873machine # [ 18.743127] systemd[1]: Reached target Multi-User System.874machine # [ 18.749156] systemd[1]: Startup finished in 1.030s (kernel) + 5.401s (initrd) + 12.311s (userspace) = 18.744s.875machine: (finished: waiting for unit multi-user.target, in 20.38 seconds)876machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test877machine # Checking switch inhibitors... done878machine # [ 20.262505] nixos[824]: switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test879machine # activating the configuration...880machine # [ 20.268461] systemd[1]: Stopped target Local File Systems.881machine # [ 20.269835] systemd[1]: Stopped target Remote File Systems.882machine # setting up /etc...883machine # [ 20.855626] systemd[1]: Reload requested from client PID 824 ('.switch-to-conf') (unit backdoor.service)...884machine # [ 20.858030] systemd[1]: Reloading...885machine # [ 21.525630] systemd[1]: Reloading finished in 663 ms.886machine # reloading user units for usertest...887machine # [ 21.583315] systemd[589]: Created slice User Core Session Slice.888machine # [ 21.590170] systemd[589]: Starting D-Bus User Message Bus...889machine # [ 21.676855] dbus-broker-launch[895]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.import1.service'890machine # [ 21.679788] dbus-broker-launch[895]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.machine1.service'891machine # [ 21.683147] dbus-broker-launch[895]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.portable1.service'892machine # [ 21.686706] dbus-broker-launch[895]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'893machine # [ 21.690696] dbus-broker-launch[895]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored894machine # [ 21.693996] dbus-broker-launch[895]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored895machine # [ 21.695911] systemd[589]: Started D-Bus User Message Bus.896machine # [ 21.703668] dbus-broker-launch[895]: Ready897machine # [ 21.811527] systemd[589]: Reexecution requested from client PID 894 ('switch-to-confi')...898machine # [ 21.815152] systemd[589]: Reexecuting.899machine # [ 21.886838] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy900machine # [ 22.113826] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy901machine # [ 22.121513] systemd[589]: Reload requested from client PID 894 ('switch-to-confi')...902machine # [ 22.124548] systemd[589]: Reloading...903machine # [ 22.337425] systemd[589]: Reloading finished in 212 ms.904machine # [ 22.352323] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy905machine # restarting the following user units: nixos-activation.service906machine # [ 22.363809] systemd[589]: Stopped Run user-specific NixOS activation.907machine # [ 22.366617] systemd[589]: Stopping Run user-specific NixOS activation...908machine # [ 22.370237] systemd[589]: Starting Run user-specific NixOS activation...909machine # [ 22.428978] systemd[589]: Finished Run user-specific NixOS activation.910machine # restarting sysinit-reactivation.target911machine # [ 22.449441] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....912machine # [ 22.629988] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..913machine # [ 22.631273] systemd[1]: Reached target Reactivate sysinit units.914machine # [ 22.639176] systemd[1]: Reached target Remote File Systems.915machine # [ 22.641932] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os916machine # [ 22.643287] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki917machine # [ 22.650542] systemd[1]: Starting linger-users.service...918machine # [ 22.654256] systemd[1]: Reached target Local File Systems.919machine # [ 22.660538] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore920machine # [ 22.666733] systemd[1]: Starting Create SUID/SGID Wrappers...921machine # [ 22.667684] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.922machine # [ 22.677230] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67923machine # [ 22.679486] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore924machine # [ 22.684872] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os925machine # [ 22.686262] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os926machine # [ 22.771891] systemd[1]: linger-users.service: Deactivated successfully.927machine # [ 22.776325] systemd[1]: Finished linger-users.service.928machine # [ 23.135941] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.929machine # [ 23.138091] systemd[1]: Finished Create SUID/SGID Wrappers.930machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service931machine # [ 23.406813] nixos[824]: finished switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test932machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test, in 3.83 seconds)933machine: must succeed: rm /etc/NIXOS934machine: (finished: must succeed: rm /etc/NIXOS, in 0.07 seconds)935machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/7prbf4wwajd81mn77q9y0xvvg807vm95-nixos-system-other-test/bin/switch-to-configuration test936machine # this should succeed (config: /nix/store/7prbf4wwajd81mn77q9y0xvvg807vm95-nixos-system-other-test, action: test)937machine # Checking switch inhibitors... done938machine # [ 24.283797] nixos[972]: switching to system configuration /nix/store/7prbf4wwajd81mn77q9y0xvvg807vm95-nixos-system-other-test939machine # [ 24.286770] systemd[1]: Stopped target Local File Systems.940machine # activating the configuration...941machine # [ 24.289818] systemd[1]: Stopped target Remote File Systems.942machine # removing user ‘usertest’943machine # setting up /etc...944machine # [ 24.906210] systemd[1]: Reload requested from client PID 972 ('.switch-to-conf') (unit backdoor.service)...945machine # [ 24.908386] systemd[1]: Reloading...946machine # [ 25.634434] systemd[1]: Reloading finished in 723 ms.947machine # reloading user units for usertest...948machine # [ 25.815684] systemd[589]: Reexecution requested from client PID 1044 ('switch-to-confi')...949machine # [ 25.819759] systemd[589]: Reexecuting.950machine # [ 25.884694] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy951machine # [ 26.116603] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy952machine # [ 26.123908] systemd[589]: Reload requested from client PID 1044 ('switch-to-confi')...953machine # [ 26.126684] systemd[589]: Reloading...954machine # [ 26.354761] systemd[589]: Reloading finished in 226 ms.955machine # [ 26.372411] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy956machine # restarting the following user units: nixos-activation.service957machine # [ 26.384608] systemd[589]: Stopped Run user-specific NixOS activation.958machine # [ 26.387364] systemd[589]: Stopping Run user-specific NixOS activation...959machine # [ 26.390958] systemd[589]: Starting Run user-specific NixOS activation...960machine # [ 26.451304] systemd[589]: Finished Run user-specific NixOS activation.961machine # restarting sysinit-reactivation.target962machine # [ 26.458004] systemd[1]: Stopped target Reactivate sysinit units.963machine # reloading the following units: dbus-broker.service964machine # [ 26.463853] systemd[1]: Stopping Reactivate sysinit units...965machine # [ 26.465490] systemd[1]: Reached target Reactivate sysinit units.966machine # [ 26.467207] dbus-broker-launch[478]: Caught SIGHUP, trigger reload.967machine # [ 26.468929] systemd[1]: Reloading D-Bus System Message Bus...968machine # [ 26.496466] dbus-broker-launch[478]: Looking up NSS user entry for 'systemd-timesync'...969machine # [ 26.498884] dbus-broker-launch[478]: NSS returned no entry for 'systemd-timesync'970machine # [ 26.500271] dbus-broker-launch[478]: Invalid user-name in /nix/store/2d37a0ag8593x6bsc14irprkj2vpg80c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"971machine # [ 26.535364] systemd[1]: Reloaded D-Bus System Message Bus.972machine # restarting the following units: network-addresses-eth1.service973machine # [ 26.544996] systemd[1]: Stopping Address configuration of eth1...974machine # [ 26.608646] network-addresses-eth1-pre-stop[1056]: deleting address 192.168.1.1/24... done975machine # [ 26.621680] network-addresses-eth1-pre-stop[1056]: deleting address 2001:db8:1::1/64... done976machine # [ 26.633391] systemd[1]: network-addresses-eth1.service: Deactivated successfully.977machine # [ 26.638840] systemd[1]: Stopped Address configuration of eth1.978machine # [ 26.641589] systemd[1]: Starting Address configuration of eth1...979machine # [ 26.728777] network-addresses-eth1-start[1061]: adding address 192.168.1.2/24... done980machine # [ 26.743837] network-addresses-eth1-start[1061]: adding address 2001:db8:1::2/64... done981machine # [ 26.763045] systemd[1]: Finished Address configuration of eth1.982machine # [ 26.768247] systemd[1]: Reached target Local File Systems.983machine # [ 26.771479] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os984machine # [ 26.774402] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki985machine # [ 26.775737] systemd[1]: Reached target Remote File Systems.986machine # [ 26.783691] systemd[1]: Starting linger-users.service...987machine # [ 26.789531] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore988machine # [ 26.800295] systemd[1]: Starting Create SUID/SGID Wrappers...989machine # [ 26.802574] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.990machine # [ 26.810503] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67991machine # [ 26.818464] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore992machine # [ 26.821505] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os993machine # [ 26.824235] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os994machine # [ 26.885051] linger-users-start[1076]: id: ‘usertest’: no such user995machine # [ 26.887296] linger-users-start[1072]: Removing linger for missing user usertest996machine # [ 26.906850] systemd[1]: linger-users.service: Deactivated successfully.997machine # [ 26.910439] systemd[1]: Finished linger-users.service.998machine # [ 27.287508] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.999machine # [ 27.290969] systemd[1]: Finished Create SUID/SGID Wrappers.1000machine # [ 27.550473] nixos[972]: finished switching to system configuration /nix/store/7prbf4wwajd81mn77q9y0xvvg807vm95-nixos-system-other-test1001machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/7prbf4wwajd81mn77q9y0xvvg807vm95-nixos-system-other-test/bin/switch-to-configuration test, in 4.07 seconds)1002machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&11003machine # [ 28.262049] nixos[1140]: switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test1004machine # [ 28.265318] systemd[1]: Stopped target Local File Systems.1005machine # [ 28.267564] systemd[1]: Stopped target Remote File Systems.1006machine # [ 28.799655] systemd[1]: Reload requested from client PID 1140 ('.switch-to-conf') (unit backdoor.service)...1007machine # [ 28.802351] systemd[1]: Reloading...1008machine # [ 29.539523] systemd[1]: Reloading finished in 735 ms.1009machine # [ 29.700309] systemd[589]: Reexecution requested from client PID 1209 ('switch-to-confi')...1010machine # [ 29.705100] systemd[589]: Reexecuting.1011machine # [ 29.767746] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1012machine # [ 29.983194] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1013machine # [ 29.990731] systemd[589]: Reload requested from client PID 1209 ('switch-to-confi')...1014machine # [ 29.993620] systemd[589]: Reloading...1015machine # [ 30.210078] systemd[589]: Reloading finished in 216 ms.1016machine # [ 30.238029] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1017machine # [ 30.250316] systemd[589]: Stopped Run user-specific NixOS activation.1018machine # [ 30.253412] systemd[589]: Stopping Run user-specific NixOS activation...1019machine # [ 30.256003] systemd[589]: Starting Run user-specific NixOS activation...1020machine # [ 30.316449] systemd[589]: Finished Run user-specific NixOS activation.1021machine # [ 30.321179] systemd[1]: Stopped target Reactivate sysinit units.1022machine # [ 30.326596] systemd[1]: Stopping Reactivate sysinit units...1023machine # [ 30.327712] systemd[1]: Reached target Reactivate sysinit units.1024machine # [ 30.329071] systemd[1]: Reloading D-Bus System Message Bus...1025machine # [ 30.330495] dbus-broker-launch[478]: Caught SIGHUP, trigger reload.1026machine # [ 30.344638] dbus-broker-launch[478]: Looking up NSS user entry for 'systemd-timesync'...1027machine # [ 30.347035] dbus-broker-launch[478]: NSS returned no entry for 'systemd-timesync'1028machine # [ 30.348471] dbus-broker-launch[478]: Invalid user-name in /nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1029machine # [ 30.385148] systemd[1]: Reloaded D-Bus System Message Bus.1030machine # [ 30.393248] systemd[1]: Stopping Address configuration of eth1...1031machine # [ 30.448567] network-addresses-eth1-pre-stop[1222]: deleting address 192.168.1.2/24... done1032machine # [ 30.460629] network-addresses-eth1-pre-stop[1222]: deleting address 2001:db8:1::2/64... done1033machine # [ 30.471865] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1034machine # [ 30.476437] systemd[1]: Stopped Address configuration of eth1.1035machine # [ 30.480601] systemd[1]: Starting Address configuration of eth1...1036machine # [ 30.562791] network-addresses-eth1-start[1227]: adding address 192.168.1.1/24... done1037machine # [ 30.576894] network-addresses-eth1-start[1227]: adding address 2001:db8:1::1/64... done1038machine # [ 30.594240] systemd[1]: Finished Address configuration of eth1.1039machine # [ 30.598890] systemd[1]: Reached target Remote File Systems.1040machine # [ 30.602065] systemd[1]: Reached target Local File Systems.1041machine # [ 30.605500] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1042machine # [ 30.612706] systemd[1]: Starting Create SUID/SGID Wrappers...1043machine # [ 30.613707] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1044machine # [ 30.614913] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671045machine # [ 30.620993] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1046machine # [ 30.628989] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1047machine # [ 30.630364] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1048machine # [ 30.631540] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1049machine # [ 30.638011] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1050machine # [ 31.052923] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1051machine # [ 31.055306] systemd[1]: Finished Create SUID/SGID Wrappers.1052machine # [ 31.063029] systemd[1]: Starting linger-users.service...1053machine # [ 31.125085] systemd[1]: linger-users.service: Deactivated successfully.1054machine # [ 31.127904] systemd[1]: Finished linger-users.service.1055machine # [ 31.387954] nixos[1140]: finished switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test1056machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.84 seconds)1057subtest: user services1058machine: waiting for unit user@1001.service1059machine: (finished: waiting for unit user@1001.service, in 0.25 seconds)1060machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11061machine # [ 32.220558] nixos[1300]: switching to system configuration /nix/store/2ny1fyv6gps3m9irlhqiv0pxm5kl77wq-nixos-system-machine-test1062machine # [ 32.224328] systemd[1]: Stopped target Local File Systems.1063machine # [ 32.225957] systemd[1]: Stopped target Remote File Systems.1064machine # [ 32.817468] systemd[1]: Reload requested from client PID 1300 ('.switch-to-conf') (unit backdoor.service)...1065machine # [ 32.819611] systemd[1]: Reloading...1066machine # [ 33.474351] systemd[1]: Reloading finished in 650 ms.1067machine # [ 33.676897] systemd[589]: Reexecution requested from client PID 1369 ('switch-to-confi')...1068machine # [ 33.681209] systemd[589]: Reexecuting.1069machine # [ 33.746289] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1070machine # [ 33.953500] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1071machine # [ 33.960104] systemd[589]: Reload requested from client PID 1369 ('switch-to-confi')...1072machine # [ 33.962512] systemd[589]: Reloading...1073machine # [ 34.162052] systemd[589]: Reloading finished in 198 ms.1074machine # [ 34.176183] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1075machine # [ 34.187428] systemd[589]: Starting usertest.service...1076machine # [ 34.230277] systemd[589]: Finished usertest.service.1077machine # [ 34.234649] systemd[589]: Stopped Run user-specific NixOS activation.1078machine # [ 34.237981] systemd[589]: Stopping Run user-specific NixOS activation...1079machine # [ 34.241894] systemd[589]: Starting Run user-specific NixOS activation...1080machine # [ 34.297963] systemd[589]: Finished Run user-specific NixOS activation.1081machine # [ 34.303424] systemd[1]: Stopped target Reactivate sysinit units.1082machine # [ 34.305934] systemd[1]: Stopping Reactivate sysinit units...1083machine # [ 34.306820] systemd[1]: Reached target Reactivate sysinit units.1084machine # [ 34.310604] systemd[1]: Reached target Remote File Systems.1085machine # [ 34.313082] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1086machine # [ 34.314936] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1087machine # [ 34.321773] systemd[1]: Starting linger-users.service...1088machine # [ 34.328350] systemd[1]: Reached target Local File Systems.1089machine # [ 34.333108] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1090machine # [ 34.340810] systemd[1]: Starting Create SUID/SGID Wrappers...1091machine # [ 34.346366] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1092machine # [ 34.347748] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671093machine # [ 34.358590] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1094machine # [ 34.361975] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1095machine # [ 34.364450] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1096machine # [ 34.429737] systemd[1]: linger-users.service: Deactivated successfully.1097machine # [ 34.432430] systemd[1]: Finished linger-users.service.1098machine # [ 34.732449] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1099machine # [ 34.736242] systemd[1]: Finished Create SUID/SGID Wrappers.1100machine # [ 34.995026] nixos[1300]: finished switching to system configuration /nix/store/2ny1fyv6gps3m9irlhqiv0pxm5kl77wq-nixos-system-machine-test1101machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.35 seconds)1102machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1103machine # [ 35.110030] sudo[1440]: 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.service1104machine # [ 35.129105] sudo[1440]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1105machine # [ 35.144965] sudo[1440]: pam_unix(sudo:session): session closed for user usertest1106machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.14 seconds)1107machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11108machine # [ 35.764763] nixos[1449]: switching to system configuration /nix/store/2ny1fyv6gps3m9irlhqiv0pxm5kl77wq-nixos-system-machine-test1109machine # [ 35.768183] systemd[1]: Stopped target Local File Systems.1110machine # [ 35.771192] systemd[1]: Stopped target Remote File Systems.1111machine # [ 36.265433] systemd[1]: Reload requested from client PID 1449 ('.switch-to-conf') (unit backdoor.service)...1112machine # [ 36.267382] systemd[1]: Reloading...1113machine # [ 36.934048] systemd[1]: Reloading finished in 663 ms.1114machine # [ 37.125057] systemd[589]: Reexecution requested from client PID 1518 ('switch-to-confi')...1115machine # [ 37.130180] systemd[589]: Reexecuting.1116machine # [ 37.186654] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1117machine # [ 37.391896] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1118machine # [ 37.399342] systemd[589]: Reload requested from client PID 1518 ('switch-to-confi')...1119machine # [ 37.404133] systemd[589]: Reloading...1120machine # [ 37.615004] systemd[589]: Reloading finished in 209 ms.1121machine # [ 37.631592] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1122machine # [ 37.640539] systemd[589]: Stopped Run user-specific NixOS activation.1123machine # [ 37.643440] systemd[589]: Stopping Run user-specific NixOS activation...1124machine # [ 37.647403] systemd[589]: Starting Run user-specific NixOS activation...1125machine # [ 37.696476] systemd[589]: Finished Run user-specific NixOS activation.1126machine # [ 37.701115] systemd[1]: Stopped target Reactivate sysinit units.1127machine # [ 37.703811] systemd[1]: Stopping Reactivate sysinit units...1128machine # [ 37.706062] systemd[1]: Reached target Reactivate sysinit units.1129machine # [ 37.708325] systemd[1]: Reached target Remote File Systems.1130machine # [ 37.713341] systemd[1]: Reached target Local File Systems.1131machine # [ 37.716532] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1132machine # [ 37.723028] systemd[1]: Starting Create SUID/SGID Wrappers...1133machine # [ 37.725889] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1134machine # [ 37.728566] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671135machine # [ 37.732416] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1136machine # [ 37.737243] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1137machine # [ 37.741602] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1138machine # [ 37.746460] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1139machine # [ 37.747849] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1140machine # [ 38.102559] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1141machine # [ 38.107521] systemd[1]: Finished Create SUID/SGID Wrappers.1142machine # [ 38.110338] systemd[1]: Starting linger-users.service...1143machine # [ 38.171083] systemd[1]: linger-users.service: Deactivated successfully.1144machine # [ 38.173859] systemd[1]: Finished linger-users.service.1145machine # [ 38.434305] nixos[1449]: finished switching to system configuration /nix/store/2ny1fyv6gps3m9irlhqiv0pxm5kl77wq-nixos-system-machine-test1146machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.30 seconds)1147machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&11148machine # [ 39.039707] nixos[1590]: switching to system configuration /nix/store/vqdws0rwdpx3i98lddk8wpl3rgfv5xrh-nixos-system-machine-test1149machine # [ 39.042417] systemd[1]: Stopped target Local File Systems.1150machine # [ 39.044890] systemd[1]: Stopped target Remote File Systems.1151machine # [ 39.595800] systemd[1]: Reload requested from client PID 1590 ('.switch-to-conf') (unit backdoor.service)...1152machine # [ 39.598196] systemd[1]: Reloading...1153machine # [ 40.226065] systemd[1]: Reloading finished in 626 ms.1154machine # [ 40.437273] systemd[589]: Stopped usertest.service.1155machine # [ 40.440866] systemd[589]: Reexecution requested from client PID 1659 ('switch-to-confi')...1156machine # [ 40.443682] systemd[589]: Reexecuting.1157machine # [ 40.502970] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1158machine # [ 40.705171] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1159machine # [ 40.712104] systemd[589]: Reload requested from client PID 1659 ('switch-to-confi')...1160machine # [ 40.713652] systemd[589]: Reloading...1161machine # [ 40.914174] systemd[589]: Reloading finished in 198 ms.1162machine # [ 40.930004] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1163machine # [ 40.939691] systemd[589]: Starting usertest.service...1164machine # [ 40.983148] systemd[589]: Finished usertest.service.1165machine # [ 40.986385] systemd[589]: Stopped Run user-specific NixOS activation.1166machine # [ 40.988744] systemd[589]: Stopping Run user-specific NixOS activation...1167machine # [ 40.991766] systemd[589]: Starting Run user-specific NixOS activation...1168machine # [ 41.038325] systemd[589]: Finished Run user-specific NixOS activation.1169machine # [ 41.043071] systemd[1]: Stopped target Reactivate sysinit units.1170machine # [ 41.046073] systemd[1]: Stopping Reactivate sysinit units...1171machine # [ 41.047039] systemd[1]: Reached target Reactivate sysinit units.1172machine # [ 41.050438] systemd[1]: Reached target Remote File Systems.1173machine # [ 41.058079] systemd[1]: Starting linger-users.service...1174machine # [ 41.061348] systemd[1]: Reached target Local File Systems.1175machine # [ 41.068235] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1176machine # [ 41.073532] systemd[1]: Starting Create SUID/SGID Wrappers...1177machine # [ 41.081964] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1178machine # [ 41.083391] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671179machine # [ 41.090943] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1180machine # [ 41.094854] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1181machine # [ 41.097132] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1182machine # [ 41.099568] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1183machine # [ 41.102058] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1184machine # [ 41.161509] systemd[1]: linger-users.service: Deactivated successfully.1185machine # [ 41.165497] systemd[1]: Finished linger-users.service.1186machine # [ 41.453895] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1187machine # [ 41.457697] systemd[1]: Finished Create SUID/SGID Wrappers.1188machine # [ 41.718634] nixos[1590]: finished switching to system configuration /nix/store/vqdws0rwdpx3i98lddk8wpl3rgfv5xrh-nixos-system-machine-test1189machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 3.30 seconds)1190machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1191machine # [ 41.829996] sudo[1730]: 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.service1192machine # [ 41.850949] sudo[1730]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1193machine # [ 41.867982] sudo[1730]: pam_unix(sudo:session): session closed for user usertest1194machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.13 seconds)1195machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&11196machine # [ 42.392535] nixos[1739]: switching to system configuration /nix/store/na2443f2m74rwdyqyximaq8agsh23ygi-nixos-system-machine-test1197machine # [ 42.395566] systemd[1]: Stopped target Local File Systems.1198machine # [ 42.398188] systemd[1]: Stopped target Remote File Systems.1199machine # [ 42.942558] systemd[1]: Reload requested from client PID 1739 ('.switch-to-conf') (unit backdoor.service)...1200machine # [ 42.944945] systemd[1]: Reloading...1201machine # [ 43.585363] systemd[1]: Reloading finished in 638 ms.1202machine # [ 43.780607] systemd[589]: Reexecution requested from client PID 1808 ('switch-to-confi')...1203machine # [ 43.784797] systemd[589]: Reexecuting.1204machine # [ 43.849867] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1205machine # [ 44.102933] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1206machine # [ 44.110174] systemd[589]: Reload requested from client PID 1808 ('switch-to-confi')...1207machine # [ 44.112735] systemd[589]: Reloading...1208machine # [ 44.349072] systemd[589]: Reloading finished in 234 ms.1209machine # [ 44.360356] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1210machine # [ 44.368044] systemd[589]: Stopped usertest.service.1211machine # [ 44.370444] systemd[589]: Stopping usertest.service...1212machine # [ 44.373991] systemd[589]: Starting usertest.service...1213machine # [ 44.414983] systemd[589]: Finished usertest.service.1214machine # [ 44.420758] systemd[589]: Stopped Run user-specific NixOS activation.1215machine # [ 44.423459] systemd[589]: Stopping Run user-specific NixOS activation...1216machine # [ 44.426880] systemd[589]: Starting Run user-specific NixOS activation...1217machine # [ 44.479134] systemd[589]: Finished Run user-specific NixOS activation.1218machine # [ 44.484530] systemd[1]: Stopped target Reactivate sysinit units.1219machine # [ 44.486523] systemd[1]: Stopping Reactivate sysinit units...1220machine # [ 44.487565] systemd[1]: Reached target Reactivate sysinit units.1221machine # [ 44.491418] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1222machine # [ 44.493674] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1223machine # [ 44.499644] systemd[1]: Reached target Remote File Systems.1224machine # [ 44.505009] systemd[1]: Starting linger-users.service...1225machine # [ 44.509866] systemd[1]: Reached target Local File Systems.1226machine # [ 44.519043] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1227machine # [ 44.526579] systemd[1]: Starting Create SUID/SGID Wrappers...1228machine # [ 44.527578] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1229machine # [ 44.531955] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671230machine # [ 44.535371] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1231machine # [ 44.541020] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1232machine # [ 44.542993] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1233machine # [ 44.617099] systemd[1]: linger-users.service: Deactivated successfully.1234machine # [ 44.620770] systemd[1]: Finished linger-users.service.1235machine # [ 44.931016] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1236machine # [ 44.934314] systemd[1]: Finished Create SUID/SGID Wrappers.1237machine # [ 45.194194] nixos[1739]: finished switching to system configuration /nix/store/na2443f2m74rwdyqyximaq8agsh23ygi-nixos-system-machine-test1238machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 3.34 seconds)1239machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1240machine # [ 45.306232] sudo[1879]: 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.service1241machine # [ 45.324759] sudo[1879]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1242machine # [ 45.339104] sudo[1879]: pam_unix(sudo:session): session closed for user usertest1243machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.13 seconds)1244machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&11245machine # [ 45.952515] nixos[1888]: switching to system configuration /nix/store/zy902p4p4jlksfrm0gy1psibqnnqbns3-nixos-system-machine-test1246machine # [ 45.955561] systemd[1]: Stopped target Local File Systems.1247machine # [ 45.958519] systemd[1]: Stopped target Remote File Systems.1248machine # [ 46.513459] systemd[1]: Reload requested from client PID 1888 ('.switch-to-conf') (unit backdoor.service)...1249machine # [ 46.515565] systemd[1]: Reloading...1250machine # [ 47.196587] systemd[1]: Reloading finished in 678 ms.1251machine # [ 47.430564] systemd[589]: Reexecution requested from client PID 1956 ('switch-to-confi')...1252machine # [ 47.433222] systemd[589]: Reexecuting.1253machine # [ 47.511380] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1254machine # [ 47.759132] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1255machine # [ 47.770888] systemd[589]: Reload requested from client PID 1956 ('switch-to-confi')...1256machine # [ 47.774135] systemd[589]: Reloading...1257machine # [ 48.006451] systemd[589]: Reloading finished in 230 ms.1258machine # [ 48.020315] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1259machine # [ 48.031652] systemd[589]: Reloading usertest.service...1260machine # [ 48.082044] systemd[589]: Reloaded usertest.service.1261machine # [ 48.088706] systemd[589]: Stopped Run user-specific NixOS activation.1262machine # [ 48.091333] systemd[589]: Stopping Run user-specific NixOS activation...1263machine # [ 48.095095] systemd[589]: Starting Run user-specific NixOS activation...1264machine # [ 48.152461] systemd[589]: Finished Run user-specific NixOS activation.1265machine # [ 48.157338] systemd[1]: Stopped target Reactivate sysinit units.1266machine # [ 48.159447] systemd[1]: Stopping Reactivate sysinit units...1267machine # [ 48.161772] systemd[1]: Reached target Reactivate sysinit units.1268machine # [ 48.164525] systemd[1]: Reached target Remote File Systems.1269machine # [ 48.170072] systemd[1]: Starting linger-users.service...1270machine # [ 48.175633] systemd[1]: Reached target Local File Systems.1271machine # [ 48.182982] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1272machine # [ 48.188714] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1273machine # [ 48.193853] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1274machine # [ 48.199567] systemd[1]: Starting Create SUID/SGID Wrappers...1275machine # [ 48.203909] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1276machine # [ 48.207999] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671277machine # [ 48.212946] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1278machine # [ 48.216490] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1279machine # [ 48.219737] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1280machine # [ 48.286620] systemd[1]: linger-users.service: Deactivated successfully.1281machine # [ 48.290854] systemd[1]: Finished linger-users.service.1282machine # [ 48.615560] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1283machine # [ 48.619018] systemd[1]: Finished Create SUID/SGID Wrappers.1284machine # [ 48.878645] nixos[1888]: finished switching to system configuration /nix/store/zy902p4p4jlksfrm0gy1psibqnnqbns3-nixos-system-machine-test1285machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 3.55 seconds)1286machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1287machine # [ 48.965825] sudo[2028]: 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.service1288machine # [ 48.983595] sudo[2028]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1289machine # [ 48.998528] sudo[2028]: pam_unix(sudo:session): session closed for user usertest1290machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds)1291machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11292machine # [ 49.689696] nixos[2037]: switching to system configuration /nix/store/2ny1fyv6gps3m9irlhqiv0pxm5kl77wq-nixos-system-machine-test1293machine # [ 49.694345] systemd[1]: Stopped target Local File Systems.1294machine # [ 49.696548] systemd[1]: Stopped target Remote File Systems.1295machine # [ 50.219232] systemd[1]: Reload requested from client PID 2037 ('.switch-to-conf') (unit backdoor.service)...1296machine # [ 50.221461] systemd[1]: Reloading...1297machine # [ 51.003153] systemd[1]: Reloading finished in 778 ms.1298machine # [ 51.174586] systemd[589]: Stopped usertest.service.1299machine # [ 51.179090] systemd[589]: Reexecution requested from client PID 2106 ('switch-to-confi')...1300machine # [ 51.181413] systemd[589]: Reexecuting.1301machine # [ 51.250657] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1302machine # [ 51.456493] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1303machine # [ 51.462896] systemd[589]: Reload requested from client PID 2106 ('switch-to-confi')...1304machine # [ 51.465644] systemd[589]: Reloading...1305machine # [ 51.677197] systemd[589]: Reloading finished in 210 ms.1306machine # [ 51.692207] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1307machine # [ 51.703438] systemd[589]: Starting usertest.service...1308machine # [ 51.746426] systemd[589]: Finished usertest.service.1309machine # [ 51.749815] systemd[589]: Stopped Run user-specific NixOS activation.1310machine # [ 51.752105] systemd[589]: Stopping Run user-specific NixOS activation...1311machine # [ 51.755224] systemd[589]: Starting Run user-specific NixOS activation...1312machine # [ 51.812969] systemd[589]: Finished Run user-specific NixOS activation.1313machine # [ 51.819854] systemd[1]: Stopped target Reactivate sysinit units.1314machine # [ 51.823616] systemd[1]: Stopping Reactivate sysinit units...1315machine # [ 51.829308] systemd[1]: Reached target Reactivate sysinit units.1316machine # [ 51.830283] systemd[1]: Reached target Local File Systems.1317machine # [ 51.831043] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1318machine # [ 51.838263] systemd[1]: Starting Create SUID/SGID Wrappers...1319machine # [ 51.839210] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1320machine # [ 51.842169] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671321machine # [ 51.847288] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1322machine # [ 51.853720] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1323machine # [ 51.855037] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1324machine # [ 51.862819] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1325machine # [ 51.867871] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1326machine # [ 51.870324] systemd[1]: Reached target Remote File Systems.1327machine # [ 52.220240] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1328machine # [ 52.227761] systemd[1]: Finished Create SUID/SGID Wrappers.1329machine # [ 52.231539] systemd[1]: Starting linger-users.service...1330machine # [ 52.310791] systemd[1]: linger-users.service: Deactivated successfully.1331machine # [ 52.314638] systemd[1]: Finished linger-users.service.1332machine # [ 52.571656] nixos[2037]: finished switching to system configuration /nix/store/2ny1fyv6gps3m9irlhqiv0pxm5kl77wq-nixos-system-machine-test1333machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.58 seconds)1334machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1335machine # [ 52.683656] sudo[2176]: 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.service1336machine # [ 52.705166] sudo[2176]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1337machine # [ 52.721254] sudo[2176]: pam_unix(sudo:session): session closed for user usertest1338machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.14 seconds)1339machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&11340machine # [ 53.323586] nixos[2185]: switching to system configuration /nix/store/qsmbamgi8m8bqfzm7y8bs5bkj5m3nwkg-nixos-system-machine-test1341machine # [ 53.326531] systemd[1]: Stopped target Local File Systems.1342machine # [ 53.328912] systemd[1]: Stopped target Remote File Systems.1343machine # [ 53.901443] systemd[1]: Reload requested from client PID 2185 ('.switch-to-conf') (unit backdoor.service)...1344machine # [ 53.903908] systemd[1]: Reloading...1345machine # [ 54.969728] systemd[1]: Reloading finished in 1062 ms.1346machine # [ 55.183967] systemd[589]: Reexecution requested from client PID 2253 ('switch-to-confi')...1347machine # [ 55.185770] systemd[589]: Reexecuting.1348machine # [ 55.250573] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1349machine # [ 55.474564] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1350machine # [ 55.482581] systemd[589]: Reload requested from client PID 2253 ('switch-to-confi')...1351machine # [ 55.484490] systemd[589]: Reloading...1352machine # [ 55.693613] systemd[589]: Reloading finished in 207 ms.1353machine # [ 55.708183] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1354machine # [ 55.718266] systemd[589]: Reloading usertest.service...1355machine # [ 55.753276] systemd[589]: Reloaded usertest.service.1356machine # [ 55.759139] systemd[589]: Stopped Run user-specific NixOS activation.1357machine # [ 55.761676] systemd[589]: Stopping Run user-specific NixOS activation...1358machine # [ 55.765148] systemd[589]: Starting Run user-specific NixOS activation...1359machine # [ 55.814689] systemd[589]: Finished Run user-specific NixOS activation.1360machine # [ 55.819056] systemd[1]: Stopped target Reactivate sysinit units.1361machine # [ 55.821801] systemd[1]: Stopping Reactivate sysinit units...1362machine # [ 55.822679] systemd[1]: Reached target Reactivate sysinit units.1363machine # [ 55.826986] systemd[1]: Reached target Remote File Systems.1364machine # [ 55.830260] systemd[1]: Starting linger-users.service...1365machine # [ 55.833950] systemd[1]: Reached target Local File Systems.1366machine # [ 55.842890] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1367machine # [ 55.851089] systemd[1]: Starting Create SUID/SGID Wrappers...1368machine # [ 55.854467] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1369machine # [ 55.856009] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671370machine # [ 55.882529] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1371machine # [ 55.886290] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1372machine # [ 55.889892] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1373machine # [ 55.893145] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1374machine # [ 55.896910] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1375machine # [ 55.985670] systemd[1]: linger-users.service: Deactivated successfully.1376machine # [ 55.990281] systemd[1]: Finished linger-users.service.1377machine # [ 56.286083] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1378machine # [ 56.289566] systemd[1]: Finished Create SUID/SGID Wrappers.1379machine # [ 56.547255] nixos[2185]: finished switching to system configuration /nix/store/qsmbamgi8m8bqfzm7y8bs5bkj5m3nwkg-nixos-system-machine-test1380machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 3.84 seconds)1381machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1382machine # [ 56.632678] sudo[2324]: 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.service1383machine # [ 56.651143] sudo[2324]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1384machine # [ 56.667854] sudo[2324]: pam_unix(sudo:session): session closed for user usertest1385machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds)1386machine: must fail: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&11387machine # [ 57.307730] nixos[2333]: switching to system configuration /nix/store/79q1lp0l8631z07y9z2fby0w8y9q9dbv-nixos-system-machine-test1388machine # [ 57.313152] systemd[1]: Stopped target Local File Systems.1389machine # [ 57.314955] systemd[1]: Stopped target Remote File Systems.1390machine # [ 57.845442] systemd[1]: Reload requested from client PID 2333 ('.switch-to-conf') (unit backdoor.service)...1391machine # [ 57.847416] systemd[1]: Reloading...1392machine # [ 59.409998] systemd[1]: Reloading finished in 1559 ms.1393machine # [ 59.604589] systemd[589]: Stopped usertest.service.1394machine # [ 59.608630] systemd[589]: Reexecution requested from client PID 2402 ('switch-to-confi')...1395machine # [ 59.610184] systemd[589]: Reexecuting.1396machine # [ 59.661741] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1397machine # [ 59.866662] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1398machine # [ 59.873866] systemd[589]: Reload requested from client PID 2402 ('switch-to-confi')...1399machine # [ 59.877585] systemd[589]: Reloading...1400machine # [ 60.074241] systemd[589]: Reloading finished in 197 ms.1401machine # [ 60.088186] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1402machine # [ 60.098496] systemd[589]: Starting usertest.service...1403machine # [ 60.136977] systemd[589]: usertest.service: Main process exited, code=exited, status=1/FAILURE1404machine # [ 60.138494] systemd[589]: usertest.service: Failed with result 'exit-code'.1405machine # [ 60.141213] systemd[589]: Failed to start usertest.service.1406machine # [ 60.143759] systemd[589]: Stopped Run user-specific NixOS activation.1407machine # [ 60.146138] systemd[589]: Stopping Run user-specific NixOS activation...1408machine # [ 60.149033] systemd[589]: Starting Run user-specific NixOS activation...1409machine # [ 60.195945] systemd[589]: Finished Run user-specific NixOS activation.1410machine # [ 60.200329] systemd[1]: Stopped target Reactivate sysinit units.1411machine # [ 60.202147] systemd[1]: Stopping Reactivate sysinit units...1412machine # [ 60.204937] systemd[1]: Reached target Reactivate sysinit units.1413machine # [ 60.206489] systemd[1]: Reached target Local File Systems.1414machine # [ 60.208621] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1415machine # [ 60.212786] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1416machine # [ 60.214081] systemd[1]: Reached target Remote File Systems.1417machine # [ 60.219944] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1418machine # [ 60.227146] systemd[1]: Starting Create SUID/SGID Wrappers...1419machine # [ 60.229652] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1420machine # [ 60.231297] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671421machine # [ 60.234181] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1422machine # [ 60.236131] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1423machine # [ 60.237349] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1424machine # [ 60.553908] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1425machine # [ 60.558272] systemd[1]: Finished Create SUID/SGID Wrappers.1426machine # [ 60.561143] systemd[1]: Starting linger-users.service...1427machine # [ 60.626261] systemd[1]: linger-users.service: Deactivated successfully.1428machine # [ 60.629755] systemd[1]: Finished linger-users.service.1429machine # [ 60.886686] nixos[2333]: switching to system configuration /nix/store/79q1lp0l8631z07y9z2fby0w8y9q9dbv-nixos-system-machine-test failed (status 4)1430machine: (finished: must fail: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 4.23 seconds)1431machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11432machine # [ 61.449289] nixos[2475]: switching to system configuration /nix/store/2ny1fyv6gps3m9irlhqiv0pxm5kl77wq-nixos-system-machine-test1433machine # [ 61.451700] systemd[1]: Stopped target Remote File Systems.1434machine # [ 61.454104] systemd[1]: Stopped target Local File Systems.1435machine # [ 61.956770] systemd[1]: Reload requested from client PID 2475 ('.switch-to-conf') (unit backdoor.service)...1436machine # [ 61.958878] systemd[1]: Reloading...1437machine # [ 62.596314] systemd[1]: Reloading finished in 635 ms.1438machine # [ 62.739917] systemd[589]: Reexecution requested from client PID 2544 ('switch-to-confi')...1439machine # [ 62.743649] systemd[589]: Reexecuting.1440machine # [ 62.814273] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1441machine # [ 63.067885] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1442machine # [ 63.077492] systemd[589]: Reload requested from client PID 2544 ('switch-to-confi')...1443machine # [ 63.080057] systemd[589]: Reloading...1444machine # [ 63.322502] systemd[589]: Reloading finished in 241 ms.1445machine # [ 63.336521] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1446machine # [ 63.358622] systemd[589]: Starting usertest.service...1447machine # [ 63.411762] systemd[589]: Finished usertest.service.1448machine # [ 63.418103] systemd[589]: Stopped Run user-specific NixOS activation.1449machine # [ 63.419388] systemd[589]: Stopping Run user-specific NixOS activation...1450machine # [ 63.422920] systemd[589]: Starting Run user-specific NixOS activation...1451machine # [ 63.493350] systemd[589]: Finished Run user-specific NixOS activation.1452machine # [ 63.501684] systemd[1]: Stopped target Reactivate sysinit units.1453machine # [ 63.503118] systemd[1]: Stopping Reactivate sysinit units...1454machine # [ 63.505184] systemd[1]: Reached target Reactivate sysinit units.1455machine # [ 63.508895] systemd[1]: Reached target Remote File Systems.1456machine # [ 63.518229] systemd[1]: Starting linger-users.service...1457machine # [ 63.525514] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1458machine # [ 63.527281] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1459machine # [ 63.537650] systemd[1]: Reached target Local File Systems.1460machine # [ 63.538523] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1461machine # [ 63.547279] systemd[1]: Starting Create SUID/SGID Wrappers...1462machine # [ 63.552597] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1463machine # [ 63.553940] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671464machine # [ 63.561230] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1465machine # [ 63.567619] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1466machine # [ 63.569187] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1467machine # [ 63.640287] systemd[1]: linger-users.service: Deactivated successfully.1468machine # [ 63.644469] systemd[1]: Finished linger-users.service.1469machine # [ 64.099252] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1470machine # [ 64.103408] systemd[1]: Finished Create SUID/SGID Wrappers.1471machine # [ 64.363010] nixos[2475]: finished switching to system configuration /nix/store/2ny1fyv6gps3m9irlhqiv0pxm5kl77wq-nixos-system-machine-test1472machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.48 seconds)1473machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1474machine # [ 64.457179] sudo[2614]: 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.service1475machine # [ 64.479359] sudo[2614]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1476machine # [ 64.496742] sudo[2614]: pam_unix(sudo:session): session closed for user usertest1477machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.12 seconds)1478machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&11479machine # [ 65.208548] nixos[2623]: switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test1480machine # [ 65.213475] systemd[1]: Stopped target Remote File Systems.1481machine # [ 65.215723] systemd[1]: Stopped target Local File Systems.1482machine # [ 65.894087] systemd[1]: Reload requested from client PID 2623 ('.switch-to-conf') (unit backdoor.service)...1483machine # [ 65.897341] systemd[1]: Reloading...1484machine # [ 66.790252] systemd[1]: Reloading finished in 888 ms.1485machine # [ 66.979482] systemd[589]: Stopped usertest.service.1486machine # [ 66.981550] systemd[589]: Reexecution requested from client PID 2692 ('switch-to-confi')...1487machine # [ 66.985252] systemd[589]: Reexecuting.1488machine # [ 67.063189] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1489machine # [ 67.374374] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1490machine # [ 67.384344] systemd[589]: Reload requested from client PID 2692 ('switch-to-confi')...1491machine # [ 67.387201] systemd[589]: Reloading...1492machine # [ 67.690641] systemd[589]: Reloading finished in 301 ms.1493machine # [ 67.704305] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1494machine # [ 67.722559] systemd[589]: Stopped Run user-specific NixOS activation.1495machine # [ 67.725645] systemd[589]: Stopping Run user-specific NixOS activation...1496machine # [ 67.728578] systemd[589]: Starting Run user-specific NixOS activation...1497machine # [ 67.781588] systemd[589]: Finished Run user-specific NixOS activation.1498machine # [ 67.786203] systemd[1]: Stopped target Reactivate sysinit units.1499machine # [ 67.787527] systemd[1]: Stopping Reactivate sysinit units...1500machine # [ 67.789428] systemd[1]: Reached target Reactivate sysinit units.1501machine # [ 67.794600] systemd[1]: Reached target Remote File Systems.1502machine # [ 67.797896] systemd[1]: Starting linger-users.service...1503machine # [ 67.808387] systemd[1]: Reached target Local File Systems.1504machine # [ 67.809313] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1505machine # [ 67.818664] systemd[1]: Starting Create SUID/SGID Wrappers...1506machine # [ 67.819625] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1507machine # [ 67.830057] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671508machine # [ 67.836428] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1509machine # [ 67.840510] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1510machine # [ 67.841918] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1511machine # [ 67.843280] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1512machine # [ 67.845437] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1513machine # [ 67.924228] systemd[1]: linger-users.service: Deactivated successfully.1514machine # [ 67.927866] systemd[1]: Finished linger-users.service.1515machine # [ 68.417252] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1516machine # [ 68.422026] systemd[1]: Finished Create SUID/SGID Wrappers.1517machine # [ 68.682692] nixos[2623]: finished switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test1518machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 4.20 seconds)1519machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1520machine # [ 68.794342] sudo[2760]: 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.service1521machine # [ 68.817073] sudo[2760]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1522machine # [ 68.836969] sudo[2760]: pam_unix(sudo:session): session closed for user usertest1523machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.14 seconds)1524machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1525machine # [ 68.897613] sudo[2766]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1526machine # [ 68.912623] sudo[2766]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1527machine # [ 68.927921] sudo[2766]: pam_unix(sudo:session): session closed for user usertest1528machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.09 seconds)1529machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service1530machine # [ 68.989678] sudo[2772]: 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.service1531machine # [ 69.005058] sudo[2772]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1532machine # [ 69.022975] sudo[2772]: pam_unix(sudo:session): session closed for user usertest1533machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.09 seconds)1534machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1535machine # [ 69.113206] sudo[2778]: 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.service1536machine # [ 69.138909] sudo[2778]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1537machine # [ 69.160549] sudo[2778]: pam_unix(sudo:session): session closed for user usertest1538machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.14 seconds)1539machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1540machine # [ 69.216310] sudo[2784]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1541machine # [ 69.232806] sudo[2784]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1542machine # [ 69.244730] systemd[589]: Reload requested from client PID 2789 ('systemctl')...1543machine # [ 69.247587] systemd[589]: Reloading...1544machine # [ 69.469174] systemd[589]: Reloading finished in 219 ms.1545machine # [ 69.486840] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1546machine # [ 69.500207] sudo[2784]: pam_unix(sudo:session): session closed for user usertest1547machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.34 seconds)1548machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1549machine # [ 69.568499] sudo[2794]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1550machine # [ 69.595385] sudo[2794]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1551machine # [ 69.622016] systemd[589]: Starting migrated.service...1552machine # [ 69.670326] systemd[589]: Finished migrated.service.1553machine # [ 69.675892] sudo[2794]: pam_unix(sudo:session): session closed for user usertest1554machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.17 seconds)1555machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1556machine # [ 69.735025] sudo[2802]: 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.service1557machine # [ 69.752540] sudo[2802]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1558machine # [ 69.768435] sudo[2802]: pam_unix(sudo:session): session closed for user usertest1559machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.09 seconds)1560machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1561machine # [ 69.831495] sudo[2808]: 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-owner1562machine # [ 69.848818] sudo[2808]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1563machine # [ 69.865666] sudo[2808]: pam_unix(sudo:session): session closed for user usertest1564machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.10 seconds)1565machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1566machine # [ 69.924625] sudo[2814]: 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.service1567machine # [ 69.941078] sudo[2814]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1568machine # [ 69.971821] sudo[2814]: pam_unix(sudo:session): session closed for user usertest1569machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.11 seconds)1570machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&11571machine # [ 70.642845] nixos[2823]: switching to system configuration /nix/store/4lhsbbcl313hg7718f4wg9ibnm02dhal-nixos-system-machine-test1572machine # [ 70.646823] systemd[1]: Stopped target Local File Systems.1573machine # [ 70.648782] systemd[1]: Stopped target Remote File Systems.1574machine # [ 71.383197] systemd[1]: Reload requested from client PID 2823 ('.switch-to-conf') (unit backdoor.service)...1575machine # [ 71.385023] systemd[1]: Reloading...1576machine # [ 72.276859] systemd[1]: Reloading finished in 888 ms.1577machine # [ 72.485142] systemd[589]: Reexecution requested from client PID 2892 ('switch-to-confi')...1578machine # [ 72.489818] systemd[589]: Reexecuting.1579machine # [ 72.569107] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1580machine # [ 72.815934] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1581machine # [ 72.824134] systemd[589]: Reload requested from client PID 2892 ('switch-to-confi')...1582machine # [ 72.826735] systemd[589]: Reloading...1583machine # [ 73.057684] systemd[589]: Reloading finished in 229 ms.1584machine # [ 73.072472] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1585machine # [ 73.085809] systemd[589]: Stopped Run user-specific NixOS activation.1586machine # [ 73.088895] systemd[589]: Stopping Run user-specific NixOS activation...1587machine # [ 73.092802] systemd[589]: Starting Run user-specific NixOS activation...1588machine # [ 73.205393] systemd[589]: Reload requested from client PID 2904 ('systemctl') (unit nixos-activation.service)...1589machine # [ 73.208605] systemd[589]: Reloading...1590machine # [ 73.417062] systemd[589]: Reloading finished in 207 ms.1591machine # [ 73.432326] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1592machine # [ 73.481390] systemd[589]: Stopped migrated.service.1593machine # [ 73.503802] systemd[589]: Finished Run user-specific NixOS activation.1594machine # [ 73.507910] systemd[589]: Reload requested from client PID 2892 ('switch-to-confi')...1595machine # [ 73.514513] systemd[589]: Reloading...1596machine # [ 73.766682] systemd[589]: Reloading finished in 253 ms.1597machine # [ 73.780220] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1598machine # [ 73.798359] systemd[589]: Starting migrated.service...1599machine # [ 73.852436] systemd[589]: Finished migrated.service.1600machine # [ 73.858641] systemd[1]: Stopped target Reactivate sysinit units.1601machine # [ 73.863065] systemd[1]: Stopping Reactivate sysinit units...1602machine # [ 73.866067] systemd[1]: Reached target Reactivate sysinit units.1603machine # [ 73.868588] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1604machine # [ 73.870139] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1605machine # [ 73.877691] systemd[1]: Reached target Local File Systems.1606machine # [ 73.882615] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1607machine # [ 73.888547] systemd[1]: Starting Create SUID/SGID Wrappers...1608machine # [ 73.889515] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1609machine # [ 73.890757] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671610machine # [ 73.894975] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1611machine # [ 73.901256] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1612machine # [ 73.908289] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1613machine # [ 73.909577] systemd[1]: Reached target Remote File Systems.1614machine # [ 74.285308] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1615machine # [ 74.290748] systemd[1]: Finished Create SUID/SGID Wrappers.1616machine # [ 74.293745] systemd[1]: Starting linger-users.service...1617machine # [ 74.361028] systemd[1]: linger-users.service: Deactivated successfully.1618machine # [ 74.364249] systemd[1]: Finished linger-users.service.1619machine # [ 74.622980] nixos[2823]: finished switching to system configuration /nix/store/4lhsbbcl313hg7718f4wg9ibnm02dhal-nixos-system-machine-test1620machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 4.66 seconds)1621machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1622machine # [ 74.713378] sudo[2974]: 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.service1623machine # [ 74.734352] sudo[2974]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1624machine # [ 74.750736] sudo[2974]: pam_unix(sudo:session): session closed for user usertest1625machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.12 seconds)1626machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1627machine # [ 74.805391] sudo[2980]: 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.service1628machine # [ 74.820753] sudo[2980]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1629machine # [ 74.851767] sudo[2980]: pam_unix(sudo:session): session closed for user usertest1630machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds)1631machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1632machine # [ 74.901222] sudo[2986]: 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-owner1633machine # [ 74.915157] sudo[2986]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1634machine # [ 74.929097] sudo[2986]: pam_unix(sudo:session): session closed for user usertest1635machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)1636machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&11637machine # [ 75.511708] nixos[2995]: switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test1638machine # [ 75.516690] systemd[1]: Stopped target Remote File Systems.1639machine # [ 75.518256] systemd[1]: Stopped target Local File Systems.1640machine # [ 76.007045] systemd[1]: Reload requested from client PID 2995 ('.switch-to-conf') (unit backdoor.service)...1641machine # [ 76.009325] systemd[1]: Reloading...1642machine # [ 76.745449] systemd[1]: Reloading finished in 733 ms.1643machine # [ 76.898385] systemd[589]: Stopped migrated.service.1644machine # [ 76.901954] systemd[589]: Reexecution requested from client PID 3064 ('switch-to-confi')...1645machine # [ 76.903318] systemd[589]: Reexecuting.1646machine # [ 76.979227] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1647machine # [ 77.201504] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1648machine # [ 77.208672] systemd[589]: Reload requested from client PID 3064 ('switch-to-confi')...1649machine # [ 77.211145] systemd[589]: Reloading...1650machine # [ 77.424190] systemd[589]: Reloading finished in 211 ms.1651machine # [ 77.440535] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1652machine # [ 77.456799] systemd[589]: Stopped Run user-specific NixOS activation.1653machine # [ 77.460120] systemd[589]: Stopping Run user-specific NixOS activation...1654machine # [ 77.466090] systemd[589]: Starting Run user-specific NixOS activation...1655machine # [ 77.538709] systemd[589]: Finished Run user-specific NixOS activation.1656machine # [ 77.544815] systemd[1]: Stopped target Reactivate sysinit units.1657machine # [ 77.549065] systemd[1]: Stopping Reactivate sysinit units...1658machine # [ 77.550260] systemd[1]: Reached target Reactivate sysinit units.1659machine # [ 77.553257] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1660machine # [ 77.556878] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1661machine # [ 77.561993] systemd[1]: Reached target Remote File Systems.1662machine # [ 77.568658] systemd[1]: Starting linger-users.service...1663machine # [ 77.571679] systemd[1]: Reached target Local File Systems.1664machine # [ 77.581585] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1665machine # [ 77.589627] systemd[1]: Starting Create SUID/SGID Wrappers...1666machine # [ 77.590643] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1667machine # [ 77.591926] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671668machine # [ 77.598087] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1669machine # [ 77.604311] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1670machine # [ 77.606166] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1671machine # [ 77.675037] systemd[1]: linger-users.service: Deactivated successfully.1672machine # [ 77.678980] systemd[1]: Finished linger-users.service.1673machine # [ 77.963946] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1674machine # [ 77.967517] systemd[1]: Finished Create SUID/SGID Wrappers.1675machine # [ 78.225016] nixos[2995]: finished switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test1676machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.31 seconds)1677machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1678machine # [ 78.310559] sudo[3132]: 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.service1679machine # [ 78.332770] sudo[3132]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1680machine # [ 78.351310] sudo[3132]: pam_unix(sudo:session): session closed for user usertest1681machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.12 seconds)1682machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1683machine # [ 78.405748] sudo[3138]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1684machine # [ 78.420712] sudo[3138]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1685machine # [ 78.434280] sudo[3138]: pam_unix(sudo:session): session closed for user usertest1686machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.08 seconds)1687machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service1688machine # [ 78.480943] sudo[3144]: 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.service1689machine # [ 78.494472] sudo[3144]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1690machine # [ 78.508338] sudo[3144]: pam_unix(sudo:session): session closed for user usertest1691machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.07 seconds)1692machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1693machine # [ 78.556447] sudo[3150]: 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.service1694machine # [ 78.570035] sudo[3150]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1695machine # [ 78.582150] sudo[3150]: pam_unix(sudo:session): session closed for user usertest1696machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.07 seconds)1697machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1698machine # [ 78.626031] sudo[3156]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1699machine # [ 78.638784] sudo[3156]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1700machine # [ 78.649077] systemd[589]: Reload requested from client PID 3161 ('systemctl')...1701machine # [ 78.651634] systemd[589]: Reloading...1702machine # [ 78.846047] systemd[589]: Reloading finished in 193 ms.1703machine # [ 78.860161] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1704machine # [ 78.873896] sudo[3156]: pam_unix(sudo:session): session closed for user usertest1705machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.29 seconds)1706machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1707machine # [ 78.920929] sudo[3166]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1708machine # [ 78.935680] sudo[3166]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1709machine # [ 78.951929] systemd[589]: Starting migrated.service...1710machine # [ 78.991108] systemd[589]: Finished migrated.service.1711machine # [ 78.995746] sudo[3166]: pam_unix(sudo:session): session closed for user usertest1712machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.12 seconds)1713machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1714machine # [ 79.042062] sudo[3174]: 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.service1715machine # [ 79.055343] sudo[3174]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1716machine # [ 79.067931] sudo[3174]: pam_unix(sudo:session): session closed for user usertest1717machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1718machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1719machine # [ 79.114298] sudo[3180]: 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-owner1720machine # [ 79.127959] sudo[3180]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1721machine # [ 79.141939] sudo[3180]: pam_unix(sudo:session): session closed for user usertest1722machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)1723machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1724machine # [ 79.188903] sudo[3186]: 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.service1725machine # [ 79.203683] sudo[3186]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1726machine # [ 79.229686] sudo[3186]: pam_unix(sudo:session): session closed for user usertest1727machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.09 seconds)1728machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&11729machine # [ 79.802024] nixos[3195]: switching to system configuration /nix/store/1v3m658hlwh30s4kpqw4hwfih3iwcvnj-nixos-system-machine-test1730machine # [ 79.806182] systemd[1]: Stopped target Local File Systems.1731machine # [ 79.807191] systemd[1]: Stopped target Remote File Systems.1732machine # [ 80.350984] systemd[1]: Reload requested from client PID 3195 ('.switch-to-conf') (unit backdoor.service)...1733machine # [ 80.353223] systemd[1]: Reloading...1734machine # [ 80.968239] systemd[1]: Reloading finished in 612 ms.1735machine # [ 81.175200] systemd[589]: Reexecution requested from client PID 3264 ('switch-to-confi')...1736machine # [ 81.178538] systemd[589]: Reexecuting.1737machine # [ 81.229690] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1738machine # [ 81.453415] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1739machine # [ 81.461342] systemd[589]: Reload requested from client PID 3264 ('switch-to-confi')...1740machine # [ 81.463805] systemd[589]: Reloading...1741machine # [ 81.666071] systemd[589]: Reloading finished in 200 ms.1742machine # [ 81.680187] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1743machine # [ 81.690573] systemd[589]: Stopped Run user-specific NixOS activation.1744machine # [ 81.693211] systemd[589]: Stopping Run user-specific NixOS activation...1745machine # [ 81.696506] systemd[589]: Starting Run user-specific NixOS activation...1746machine # [ 81.761169] systemd[589]: Reload requested from client PID 3276 ('systemctl') (unit nixos-activation.service)...1747machine # [ 81.764159] systemd[589]: Reloading...1748machine # [ 81.966163] systemd[589]: Reloading finished in 200 ms.1749machine # [ 81.980176] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1750machine # [ 82.000253] systemd[589]: Finished Run user-specific NixOS activation.1751machine # [ 82.003108] systemd[589]: Reload requested from client PID 3264 ('switch-to-confi')...1752machine # [ 82.005801] systemd[589]: Reloading...1753machine # [ 82.195187] systemd[589]: Reloading finished in 188 ms.1754machine # [ 82.212176] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1755machine # [ 82.224087] systemd[589]: Stopped migrated.service.1756machine # [ 82.224925] systemd[589]: Stopping migrated.service...1757machine # [ 82.227809] systemd[589]: Starting migrated.service...1758machine # [ 82.265929] systemd[589]: Finished migrated.service.1759machine # [ 82.273100] systemd[1]: Stopped target Reactivate sysinit units.1760machine # [ 82.276473] systemd[1]: Stopping Reactivate sysinit units...1761machine # [ 82.277334] systemd[1]: Reached target Reactivate sysinit units.1762machine # [ 82.281220] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1763machine # [ 82.285257] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1764machine # [ 82.287802] systemd[1]: Reached target Remote File Systems.1765machine # [ 82.290115] systemd[1]: Reached target Local File Systems.1766machine # [ 82.295392] systemd[1]: Starting linger-users.service...1767machine # [ 82.299309] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1768machine # [ 82.310564] systemd[1]: Starting Create SUID/SGID Wrappers...1769machine # [ 82.311433] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1770machine # [ 82.314494] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671771machine # [ 82.317279] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1772machine # [ 82.319492] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1773machine # [ 82.322052] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1774machine # [ 82.385766] systemd[1]: linger-users.service: Deactivated successfully.1775machine # [ 82.389176] systemd[1]: Finished linger-users.service.1776machine # [ 82.667256] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1777machine # [ 82.669443] systemd[1]: Finished Create SUID/SGID Wrappers.1778machine # [ 82.930105] nixos[3195]: finished switching to system configuration /nix/store/1v3m658hlwh30s4kpqw4hwfih3iwcvnj-nixos-system-machine-test1779machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 3.71 seconds)1780machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1781machine # [ 83.016939] sudo[3345]: 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.service1782machine # [ 83.040671] sudo[3345]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1783machine # [ 83.057343] sudo[3345]: pam_unix(sudo:session): session closed for user usertest1784machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.12 seconds)1785machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1786machine # [ 83.110216] sudo[3351]: 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.service1787machine # [ 83.125315] sudo[3351]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1788machine # [ 83.152383] sudo[3351]: pam_unix(sudo:session): session closed for user usertest1789machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.09 seconds)1790machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1791machine # [ 83.197700] sudo[3357]: 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-owner1792machine # [ 83.214488] sudo[3357]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1793machine # [ 83.233369] sudo[3357]: pam_unix(sudo:session): session closed for user usertest1794machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)1795machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&11796machine # [ 83.790512] nixos[3366]: switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test1797machine # [ 83.793263] systemd[1]: Stopped target Remote File Systems.1798machine # [ 83.795502] systemd[1]: Stopped target Local File Systems.1799machine # [ 84.263817] systemd[1]: Reload requested from client PID 3366 ('.switch-to-conf') (unit backdoor.service)...1800machine # [ 84.266133] systemd[1]: Reloading...1801machine # [ 84.866095] systemd[1]: Reloading finished in 597 ms.1802machine # [ 85.037648] systemd[589]: Stopped migrated.service.1803machine # [ 85.041614] systemd[589]: Reexecution requested from client PID 3435 ('switch-to-confi')...1804machine # [ 85.043060] systemd[589]: Reexecuting.1805machine # [ 85.094465] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1806machine # [ 85.299302] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1807machine # [ 85.306761] systemd[589]: Reload requested from client PID 3435 ('switch-to-confi')...1808machine # [ 85.309702] systemd[589]: Reloading...1809machine # [ 85.515623] systemd[589]: Reloading finished in 204 ms.1810machine # [ 85.528191] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1811machine # [ 85.538318] systemd[589]: Stopped Run user-specific NixOS activation.1812machine # [ 85.540856] systemd[589]: Stopping Run user-specific NixOS activation...1813machine # [ 85.543712] systemd[589]: Starting Run user-specific NixOS activation...1814machine # [ 85.590467] systemd[589]: Finished Run user-specific NixOS activation.1815machine # [ 85.594584] systemd[1]: Stopped target Reactivate sysinit units.1816machine # [ 85.596569] systemd[1]: Stopping Reactivate sysinit units...1817machine # [ 85.598430] systemd[1]: Reached target Reactivate sysinit units.1818machine # [ 85.599976] systemd[1]: Reached target Remote File Systems.1819machine # [ 85.603660] systemd[1]: Reached target Local File Systems.1820machine # [ 85.607204] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1821machine # [ 85.611095] systemd[1]: Starting Create SUID/SGID Wrappers...1822machine # [ 85.615648] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1823machine # [ 85.620518] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671824machine # [ 85.622670] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1825machine # [ 85.630137] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1826machine # [ 85.631454] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1827machine # [ 85.634104] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1828machine # [ 85.635366] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1829machine # [ 85.986905] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1830machine # [ 85.992813] systemd[1]: Finished Create SUID/SGID Wrappers.1831machine # [ 85.996544] systemd[1]: Starting linger-users.service...1832machine # [ 86.080290] systemd[1]: linger-users.service: Deactivated successfully.1833machine # [ 86.084694] systemd[1]: Finished linger-users.service.1834machine # [ 86.345111] nixos[3366]: finished switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test1835machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.12 seconds)1836machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1837machine # [ 86.436896] sudo[3503]: 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.service1838machine # [ 86.456701] sudo[3503]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1839machine # [ 86.474807] sudo[3503]: pam_unix(sudo:session): session closed for user usertest1840machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.12 seconds)1841machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1842machine # [ 86.524518] sudo[3509]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1843machine # [ 86.538876] sudo[3509]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1844machine # [ 86.551813] sudo[3509]: pam_unix(sudo:session): session closed for user usertest1845machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.07 seconds)1846machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service1847machine # [ 86.599954] sudo[3515]: 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.service1848machine # [ 86.614684] sudo[3515]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1849machine # [ 86.628403] sudo[3515]: pam_unix(sudo:session): session closed for user usertest1850machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.08 seconds)1851machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1852machine # [ 86.679124] sudo[3521]: 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.service1853machine # [ 86.693395] sudo[3521]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1854machine # [ 86.704587] sudo[3521]: pam_unix(sudo:session): session closed for user usertest1855machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.08 seconds)1856machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1857machine # [ 86.746477] sudo[3527]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1858machine # [ 86.758975] sudo[3527]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1859machine # [ 86.769457] systemd[589]: Reload requested from client PID 3532 ('systemctl')...1860machine # [ 86.773654] systemd[589]: Reloading...1861machine # [ 86.974923] systemd[589]: Reloading finished in 202 ms.1862machine # [ 86.988188] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1863machine # [ 87.003785] sudo[3527]: pam_unix(sudo:session): session closed for user usertest1864machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.30 seconds)1865machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1866machine # [ 87.054106] sudo[3537]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1867machine # [ 87.067875] sudo[3537]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1868machine # [ 87.085128] systemd[589]: Starting migrated.service...1869machine # [ 87.127967] systemd[589]: Finished migrated.service.1870machine # [ 87.132974] sudo[3537]: pam_unix(sudo:session): session closed for user usertest1871machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.13 seconds)1872machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1873machine # [ 87.181652] sudo[3545]: 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.service1874machine # [ 87.195249] sudo[3545]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1875machine # [ 87.208127] sudo[3545]: pam_unix(sudo:session): session closed for user usertest1876machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1877machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1878machine # [ 87.251221] sudo[3551]: 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-owner1879machine # [ 87.265055] sudo[3551]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1880machine # [ 87.277802] sudo[3551]: pam_unix(sudo:session): session closed for user usertest1881machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)1882machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1883machine # [ 87.330611] sudo[3557]: 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.service1884machine # [ 87.345838] sudo[3557]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1885machine # [ 87.370557] sudo[3557]: pam_unix(sudo:session): session closed for user usertest1886machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.09 seconds)1887machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&11888machine # [ 87.974890] nixos[3566]: switching to system configuration /nix/store/9i8acrgsw53dcy1ylw4c42irldv3xwnn-nixos-system-machine-test1889machine # [ 87.978732] systemd[1]: Stopped target Local File Systems.1890machine # [ 87.980348] systemd[1]: Stopped target Remote File Systems.1891machine # [ 88.473246] systemd[1]: Reload requested from client PID 3566 ('.switch-to-conf') (unit backdoor.service)...1892machine # [ 88.475237] systemd[1]: Reloading...1893machine # [ 89.083389] systemd[1]: Reloading finished in 605 ms.1894machine # [ 89.240701] systemd[589]: Reexecution requested from client PID 3635 ('switch-to-confi')...1895machine # [ 89.245144] systemd[589]: Reexecuting.1896machine # [ 89.305611] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1897machine # [ 89.501013] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1898machine # [ 89.507609] systemd[589]: Reload requested from client PID 3635 ('switch-to-confi')...1899machine # [ 89.509682] systemd[589]: Reloading...1900machine # [ 89.696106] systemd[589]: Reloading finished in 185 ms.1901machine # [ 89.708159] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1902machine # [ 89.718945] systemd[589]: Stopped Run user-specific NixOS activation.1903machine # [ 89.720263] systemd[589]: Stopping Run user-specific NixOS activation...1904machine # [ 89.723183] systemd[589]: Starting Run user-specific NixOS activation...1905machine # [ 89.784423] systemd[589]: Reload requested from client PID 3647 ('systemctl') (unit nixos-activation.service)...1906machine # [ 89.787150] systemd[589]: Reloading...1907machine # [ 89.998671] systemd[589]: Reloading finished in 210 ms.1908machine # [ 90.014545] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1909machine # [ 90.035454] systemd[589]: Finished Run user-specific NixOS activation.1910machine # [ 90.039149] systemd[589]: Reload requested from client PID 3635 ('switch-to-confi')...1911machine # [ 90.041541] systemd[589]: Reloading...1912machine # [ 90.254093] systemd[589]: Reloading finished in 211 ms.1913machine # [ 90.268218] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1914machine # [ 90.282980] systemd[589]: Reloading migrated.service...1915machine # [ 90.322177] systemd[589]: Reloaded migrated.service.1916machine # [ 90.329832] systemd[1]: Stopped target Reactivate sysinit units.1917machine # [ 90.332527] systemd[1]: Stopping Reactivate sysinit units...1918machine # [ 90.333453] systemd[1]: Reached target Reactivate sysinit units.1919machine # [ 90.335935] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1920machine # [ 90.338435] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1921machine # [ 90.341390] systemd[1]: Reached target Remote File Systems.1922machine # [ 90.347033] systemd[1]: Starting linger-users.service...1923machine # [ 90.354482] systemd[1]: Reached target Local File Systems.1924machine # [ 90.361864] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1925machine # [ 90.365871] systemd[1]: Starting Create SUID/SGID Wrappers...1926machine # [ 90.366757] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1927machine # [ 90.367966] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671928machine # [ 90.373324] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1929machine # [ 90.375645] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1930machine # [ 90.379075] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1931machine # [ 90.441844] systemd[1]: linger-users.service: Deactivated successfully.1932machine # [ 90.444267] systemd[1]: Finished linger-users.service.1933machine # [ 90.720722] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1934machine # [ 90.723871] systemd[1]: Finished Create SUID/SGID Wrappers.1935machine # [ 90.982054] nixos[3566]: finished switching to system configuration /nix/store/9i8acrgsw53dcy1ylw4c42irldv3xwnn-nixos-system-machine-test1936machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 3.62 seconds)1937machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1938machine # [ 91.068689] sudo[3716]: 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.service1939machine # [ 91.085898] sudo[3716]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1940machine # [ 91.101381] sudo[3716]: pam_unix(sudo:session): session closed for user usertest1941machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds)1942machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1943machine # [ 91.154084] sudo[3722]: 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-owner1944machine # [ 91.170058] sudo[3722]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1945machine # [ 91.182513] sudo[3722]: pam_unix(sudo:session): session closed for user usertest1946machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)1947machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&11948machine # [ 91.745208] nixos[3731]: switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test1949machine # [ 91.750685] systemd[1]: Stopped target Local File Systems.1950machine # [ 91.752067] systemd[1]: Stopped target Remote File Systems.1951machine # [ 92.231856] systemd[1]: Reload requested from client PID 3731 ('.switch-to-conf') (unit backdoor.service)...1952machine # [ 92.236349] systemd[1]: Reloading...1953machine # [ 92.897868] systemd[1]: Reloading finished in 658 ms.1954machine # [ 93.061709] systemd[589]: Stopped migrated.service.1955machine # [ 93.068745] systemd[589]: Reexecution requested from client PID 3800 ('switch-to-confi')...1956machine # [ 93.070818] systemd[589]: Reexecuting.1957machine # [ 93.138369] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1958machine # [ 93.324922] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1959machine # [ 93.332103] systemd[589]: Reload requested from client PID 3800 ('switch-to-confi')...1960machine # [ 93.333460] systemd[589]: Reloading...1961machine # [ 93.535311] systemd[589]: Reloading finished in 197 ms.1962machine # [ 93.552117] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1963machine # [ 93.566791] systemd[589]: Stopped Run user-specific NixOS activation.1964machine # [ 93.571212] systemd[589]: Stopping Run user-specific NixOS activation...1965machine # [ 93.574241] systemd[589]: Starting Run user-specific NixOS activation...1966machine # [ 93.623681] systemd[589]: Finished Run user-specific NixOS activation.1967machine # [ 93.628628] systemd[1]: Stopped target Reactivate sysinit units.1968machine # [ 93.629634] systemd[1]: Stopping Reactivate sysinit units...1969machine # [ 93.630480] systemd[1]: Reached target Reactivate sysinit units.1970machine # [ 93.634121] systemd[1]: Reached target Local File Systems.1971machine # [ 93.637101] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1972machine # [ 93.644148] systemd[1]: Starting Create SUID/SGID Wrappers...1973machine # [ 93.645090] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1974machine # [ 93.646294] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671975machine # [ 93.650567] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1976machine # [ 93.665522] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1977machine # [ 93.666931] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1978machine # [ 93.668451] systemd[1]: Reached target Remote File Systems.1979machine # [ 93.669266] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1980machine # [ 93.670526] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1981machine # [ 93.977062] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1982machine # [ 93.980259] systemd[1]: Finished Create SUID/SGID Wrappers.1983machine # [ 93.984521] systemd[1]: Starting linger-users.service...1984machine # [ 94.043409] systemd[1]: linger-users.service: Deactivated successfully.1985machine # [ 94.046441] systemd[1]: Finished linger-users.service.1986machine # [ 94.305557] nixos[3731]: finished switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test1987machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.14 seconds)1988machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1989machine # [ 94.390008] sudo[3868]: 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.service1990machine # [ 94.407584] sudo[3868]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1991machine # [ 94.423384] sudo[3868]: pam_unix(sudo:session): session closed for user usertest1992machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds)1993machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1994machine # [ 94.471708] sudo[3874]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1995machine # [ 94.486030] sudo[3874]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1996machine # [ 94.498028] sudo[3874]: pam_unix(sudo:session): session closed for user usertest1997machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.07 seconds)1998machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service1999machine # [ 94.542016] sudo[3880]: 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.service2000machine # [ 94.555034] sudo[3880]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2001machine # [ 94.567238] sudo[3880]: pam_unix(sudo:session): session closed for user usertest2002machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.07 seconds)2003machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2004machine # [ 94.609302] sudo[3886]: 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.service2005machine # [ 94.622022] sudo[3886]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2006machine # [ 94.635811] sudo[3886]: pam_unix(sudo:session): session closed for user usertest2007machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.07 seconds)2008machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2009machine # [ 94.686168] sudo[3892]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2010machine # [ 94.700538] sudo[3892]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2011machine # [ 94.710924] systemd[589]: Reload requested from client PID 3897 ('systemctl')...2012machine # [ 94.712732] systemd[589]: Reloading...2013machine # [ 94.926176] systemd[589]: Reloading finished in 210 ms.2014machine # [ 94.940172] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2015machine # [ 94.952545] sudo[3892]: pam_unix(sudo:session): session closed for user usertest2016machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.32 seconds)2017machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2018machine # [ 94.998229] sudo[3902]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2019machine # [ 95.011403] sudo[3902]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2020machine # [ 95.028259] systemd[589]: Starting migrated.service...2021machine # [ 95.063361] systemd[589]: Finished migrated.service.2022machine # [ 95.067903] sudo[3902]: pam_unix(sudo:session): session closed for user usertest2023machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.12 seconds)2024machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2025machine # [ 95.113852] sudo[3910]: 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.service2026machine # [ 95.126906] sudo[3910]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2027machine # [ 95.139462] sudo[3910]: pam_unix(sudo:session): session closed for user usertest2028machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2029machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2030machine # [ 95.185610] sudo[3916]: 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-owner2031machine # [ 95.199071] sudo[3916]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2032machine # [ 95.210287] sudo[3916]: pam_unix(sudo:session): session closed for user usertest2033machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)2034machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2035machine # [ 95.256609] sudo[3922]: 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.service2036machine # [ 95.269697] sudo[3922]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2037machine # [ 95.292138] sudo[3922]: pam_unix(sudo:session): session closed for user usertest2038machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds)2039machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&12040machine # [ 95.826279] nixos[3931]: switching to system configuration /nix/store/i586csrdcp6wdjiwz33ynp4s53gn1igd-nixos-system-machine-test2041machine # [ 95.829008] systemd[1]: Stopped target Remote File Systems.2042machine # [ 95.831245] systemd[1]: Stopped target Local File Systems.2043machine # [ 96.355131] systemd[1]: Reload requested from client PID 3931 ('.switch-to-conf') (unit backdoor.service)...2044machine # [ 96.357664] systemd[1]: Reloading...2045machine # [ 96.978042] systemd[1]: Reloading finished in 618 ms.2046machine # [ 97.140253] systemd[589]: Reexecution requested from client PID 4000 ('switch-to-confi')...2047machine # [ 97.144424] systemd[589]: Reexecuting.2048machine # [ 97.197582] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2049machine # [ 97.391972] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2050machine # [ 97.398466] systemd[589]: Reload requested from client PID 4000 ('switch-to-confi')...2051machine # [ 97.400688] systemd[589]: Reloading...2052machine # [ 97.593626] systemd[589]: Reloading finished in 191 ms.2053machine # [ 97.609544] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2054machine # [ 97.618116] systemd[589]: Stopped Run user-specific NixOS activation.2055machine # [ 97.619098] systemd[589]: Stopping Run user-specific NixOS activation...2056machine # [ 97.621899] systemd[589]: Starting Run user-specific NixOS activation...2057machine # [ 97.677786] systemd[589]: Reload requested from client PID 4012 ('systemctl') (unit nixos-activation.service)...2058machine # [ 97.680602] systemd[589]: Reloading...2059machine # [ 97.871511] systemd[589]: Reloading finished in 189 ms.2060machine # [ 97.884149] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2061machine # [ 97.903574] systemd[589]: Finished Run user-specific NixOS activation.2062machine # [ 97.906536] systemd[589]: Reload requested from client PID 4000 ('switch-to-confi')...2063machine # [ 97.909093] systemd[589]: Reloading...2064machine # [ 98.101955] systemd[589]: Reloading finished in 191 ms.2065machine # [ 98.116247] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2066machine # [ 98.131235] systemd[1]: Stopped target Reactivate sysinit units.2067machine # [ 98.134349] systemd[1]: Stopping Reactivate sysinit units...2068machine # [ 98.135173] systemd[1]: Reached target Reactivate sysinit units.2069machine # [ 98.137083] systemd[1]: Reached target Remote File Systems.2070machine # [ 98.137901] systemd[1]: Reached target Local File Systems.2071machine # [ 98.146790] systemd[1]: Starting linger-users.service...2072machine # [ 98.153541] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2073machine # [ 98.154931] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2074machine # [ 98.161049] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2075machine # [ 98.167253] systemd[1]: Starting Create SUID/SGID Wrappers...2076machine # [ 98.168598] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2077machine # [ 98.170096] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672078machine # [ 98.176959] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2079machine # [ 98.178516] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2080machine # [ 98.179893] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2081machine # [ 98.246448] systemd[1]: linger-users.service: Deactivated successfully.2082machine # [ 98.249201] systemd[1]: Finished linger-users.service.2083machine # [ 98.504775] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2084machine # [ 98.507958] systemd[1]: Finished Create SUID/SGID Wrappers.2085machine # [ 98.764823] nixos[3931]: finished switching to system configuration /nix/store/i586csrdcp6wdjiwz33ynp4s53gn1igd-nixos-system-machine-test2086machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 3.48 seconds)2087machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2088machine # [ 98.846996] sudo[4079]: 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.service2089machine # [ 98.865040] sudo[4079]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2090machine # [ 98.879051] sudo[4079]: pam_unix(sudo:session): session closed for user usertest2091machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)2092machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2093machine # [ 98.927151] sudo[4085]: 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-owner2094machine # [ 98.941883] sudo[4085]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2095machine # [ 98.954337] sudo[4085]: pam_unix(sudo:session): session closed for user usertest2096machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)2097machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&12098machine # [ 99.481056] nixos[4094]: switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test2099machine # [ 99.483851] systemd[1]: Stopped target Remote File Systems.2100machine # [ 99.486141] systemd[1]: Stopped target Local File Systems.2101machine # [ 99.922218] systemd[1]: Reload requested from client PID 4094 ('.switch-to-conf') (unit backdoor.service)...2102machine # [ 99.924458] systemd[1]: Reloading...2103machine # [ 100.518850] systemd[1]: Reloading finished in 592 ms.2104machine # [ 100.665438] systemd[589]: Stopped migrated.service.2105machine # [ 100.668464] systemd[589]: Reexecution requested from client PID 4162 ('switch-to-confi')...2106machine # [ 100.672853] systemd[589]: Reexecuting.2107machine # [ 100.734130] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2108machine # [ 100.941918] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2109machine # [ 100.949137] systemd[589]: Reload requested from client PID 4162 ('switch-to-confi')...2110machine # [ 100.951928] systemd[589]: Reloading...2111machine # [ 101.193357] systemd[589]: Reloading finished in 239 ms.2112machine # [ 101.208573] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2113machine # [ 101.227670] systemd[589]: Stopped Run user-specific NixOS activation.2114machine # [ 101.231701] systemd[589]: Stopping Run user-specific NixOS activation...2115machine # [ 101.236499] systemd[589]: Starting Run user-specific NixOS activation...2116machine # [ 101.287755] systemd[589]: Finished Run user-specific NixOS activation.2117machine # [ 101.292413] systemd[1]: Stopped target Reactivate sysinit units.2118machine # [ 101.293393] systemd[1]: Stopping Reactivate sysinit units...2119machine # [ 101.294183] systemd[1]: Reached target Reactivate sysinit units.2120machine # [ 101.296595] systemd[1]: Reached target Local File Systems.2121machine # [ 101.302297] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2122machine # [ 101.305347] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2123machine # [ 101.309925] systemd[1]: Reached target Remote File Systems.2124machine # [ 101.312694] systemd[1]: Starting linger-users.service...2125machine # [ 101.322700] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2126machine # [ 101.333874] systemd[1]: Starting Create SUID/SGID Wrappers...2127machine # [ 101.334763] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2128machine # [ 101.336525] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672129machine # [ 101.339402] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2130machine # [ 101.341849] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2131machine # [ 101.343662] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2132machine # [ 101.410305] systemd[1]: linger-users.service: Deactivated successfully.2133machine # [ 101.413513] systemd[1]: Finished linger-users.service.2134machine # [ 101.684056] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2135machine # [ 101.687274] systemd[1]: Finished Create SUID/SGID Wrappers.2136machine # [ 101.945085] nixos[4094]: finished switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test2137machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.01 seconds)2138machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2139machine # [ 102.058905] sudo[4231]: 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.service2140machine # [ 102.085218] sudo[4231]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2141machine # [ 102.106560] sudo[4231]: pam_unix(sudo:session): session closed for user usertest2142machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.15 seconds)2143machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user2144machine # [ 102.160682] sudo[4237]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user2145machine # [ 102.175649] sudo[4237]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2146machine # [ 102.189615] sudo[4237]: pam_unix(sudo:session): session closed for user usertest2147machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.08 seconds)2148machine: must succeed: sudo -u usertest cp /nix/store/sjwrvqsv40pz3pq93zpd2s2k0jw0h8qf-migrated.service ~usertest/.local/share/systemd/user/migrated.service2149machine # [ 102.238330] sudo[4243]: 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.service2150machine # [ 102.252958] sudo[4243]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2151machine # [ 102.266915] sudo[4243]: pam_unix(sudo:session): session closed for user usertest2152machine: (finished: must succeed: sudo -u usertest cp /nix/store/sjwrvqsv40pz3pq93zpd2s2k0jw0h8qf-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.08 seconds)2153machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2154machine # [ 102.314038] sudo[4249]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2155machine # [ 102.327656] sudo[4249]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2156machine # [ 102.337972] systemd[589]: Reload requested from client PID 4254 ('systemctl')...2157machine # [ 102.342085] systemd[589]: Reloading...2158machine # [ 102.549907] systemd[589]: Reloading finished in 207 ms.2159machine # [ 102.564194] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2160machine # [ 102.577762] sudo[4249]: pam_unix(sudo:session): session closed for user usertest2161machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.31 seconds)2162machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2163machine # [ 102.636861] sudo[4259]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2164machine # [ 102.650868] sudo[4259]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2165machine # [ 102.670575] systemd[589]: Starting migrated.service...2166machine # [ 102.711793] systemd[589]: Finished migrated.service.2167machine # [ 102.716593] sudo[4259]: pam_unix(sudo:session): session closed for user usertest2168machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.14 seconds)2169machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2170machine # [ 102.762597] sudo[4267]: 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.service2171machine # [ 102.776739] sudo[4267]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2172machine # [ 102.787910] sudo[4267]: pam_unix(sudo:session): session closed for user usertest2173machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2174machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2175machine # [ 102.835402] sudo[4273]: 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-owner2176machine # [ 102.848714] sudo[4273]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2177machine # [ 102.860853] sudo[4273]: pam_unix(sudo:session): session closed for user usertest2178machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)2179machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2180machine # [ 102.906201] sudo[4279]: 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.service2181machine # [ 102.919485] sudo[4279]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2182machine # [ 102.966005] sudo[4279]: pam_unix(sudo:session): session closed for user usertest2183machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.11 seconds)2184machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12185machine # [ 103.558932] nixos[4288]: switching to system configuration /nix/store/p07waraby75g7ic3wqh9knd2gm8azh02-nixos-system-machine-test2186machine # [ 103.564664] systemd[1]: Stopped target Local File Systems.2187machine # [ 103.566136] systemd[1]: Stopped target Remote File Systems.2188machine # [ 104.085624] systemd[1]: Reload requested from client PID 4288 ('.switch-to-conf') (unit backdoor.service)...2189machine # [ 104.087650] systemd[1]: Reloading...2190machine # [ 104.671412] systemd[1]: Reloading finished in 581 ms.2191machine # [ 104.824253] systemd[589]: Reexecution requested from client PID 4357 ('switch-to-confi')...2192machine # [ 104.827987] systemd[589]: Reexecuting.2193machine # [ 104.889949] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2194machine # [ 105.095445] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2195machine # [ 105.102902] systemd[589]: Reload requested from client PID 4357 ('switch-to-confi')...2196machine # [ 105.104774] systemd[589]: Reloading...2197machine # [ 105.307275] systemd[589]: Reloading finished in 201 ms.2198machine # [ 105.324170] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2199machine # [ 105.334408] systemd[589]: Stopped Run user-specific NixOS activation.2200machine # [ 105.335454] systemd[589]: Stopping Run user-specific NixOS activation...2201machine # [ 105.338117] systemd[589]: Starting Run user-specific NixOS activation...2202machine # [ 105.382117] systemd[589]: Finished Run user-specific NixOS activation.2203machine # [ 105.384983] systemd[589]: Reload requested from client PID 4357 ('switch-to-confi')...2204machine # [ 105.386249] systemd[589]: Reloading...2205machine # [ 105.577759] systemd[589]: Reloading finished in 189 ms.2206machine # [ 105.592157] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2207machine # [ 105.602755] systemd[589]: Stopped migrated.service.2208machine # [ 105.604716] systemd[589]: Stopping migrated.service...2209machine # [ 105.607363] systemd[589]: Starting migrated.service...2210machine # [ 105.645684] systemd[589]: Finished migrated.service.2211machine # [ 105.651968] systemd[1]: Stopped target Reactivate sysinit units.2212machine # [ 105.654576] systemd[1]: Stopping Reactivate sysinit units...2213machine # [ 105.655411] systemd[1]: Reached target Reactivate sysinit units.2214machine # [ 105.659077] systemd[1]: Reached target Remote File Systems.2215machine # [ 105.661572] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2216machine # [ 105.662869] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2217machine # [ 105.672380] systemd[1]: Reached target Local File Systems.2218machine # [ 105.673239] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2219machine # [ 105.679556] systemd[1]: Starting Create SUID/SGID Wrappers...2220machine # [ 105.681882] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2221machine # [ 105.683099] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672222machine # [ 105.685420] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2223machine # [ 105.687460] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2224machine # [ 105.689056] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2225machine # [ 106.031142] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2226machine # [ 106.033335] systemd[1]: Finished Create SUID/SGID Wrappers.2227machine # [ 106.039027] systemd[1]: Starting linger-users.service...2228machine # [ 106.100410] systemd[1]: linger-users.service: Deactivated successfully.2229machine # [ 106.103662] systemd[1]: Finished linger-users.service.2230machine # [ 106.361094] nixos[4288]: finished switching to system configuration /nix/store/p07waraby75g7ic3wqh9knd2gm8azh02-nixos-system-machine-test2231machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.41 seconds)2232machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2233machine # [ 106.459570] sudo[4431]: 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.service2234machine # [ 106.484865] sudo[4431]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2235machine # [ 106.503146] sudo[4431]: pam_unix(sudo:session): session closed for user usertest2236machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.13 seconds)2237machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2238machine # [ 106.558400] sudo[4437]: 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.service2239machine # [ 106.573268] sudo[4437]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2240machine # [ 106.604966] sudo[4437]: pam_unix(sudo:session): session closed for user usertest2241machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds)2242machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2243machine # [ 106.655104] sudo[4443]: 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-owner2244machine # [ 106.670715] sudo[4443]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2245machine # [ 106.683953] sudo[4443]: pam_unix(sudo:session): session closed for user usertest2246machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)2247machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12248machine # [ 107.227064] nixos[4452]: switching to system configuration /nix/store/p07waraby75g7ic3wqh9knd2gm8azh02-nixos-system-machine-test2249machine # [ 107.229892] systemd[1]: Stopped target Remote File Systems.2250machine # [ 107.232258] systemd[1]: Stopped target Local File Systems.2251machine # [ 107.683064] systemd[1]: Reload requested from client PID 4452 ('.switch-to-conf') (unit backdoor.service)...2252machine # [ 107.685212] systemd[1]: Reloading...2253machine # [ 108.341487] systemd[1]: Reloading finished in 653 ms.2254machine # [ 108.491016] systemd[589]: Reexecution requested from client PID 4521 ('switch-to-confi')...2255machine # [ 108.497231] systemd[589]: Reexecuting.2256machine # [ 108.541937] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2257machine # [ 108.738924] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2258machine # [ 108.745989] systemd[589]: Reload requested from client PID 4521 ('switch-to-confi')...2259machine # [ 108.749045] systemd[589]: Reloading...2260machine # [ 108.948713] systemd[589]: Reloading finished in 198 ms.2261machine # [ 108.964183] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2262machine # [ 108.973384] systemd[589]: Stopped Run user-specific NixOS activation.2263machine # [ 108.975778] systemd[589]: Stopping Run user-specific NixOS activation...2264machine # [ 108.978868] systemd[589]: Starting Run user-specific NixOS activation...2265machine # [ 109.029574] systemd[589]: Finished Run user-specific NixOS activation.2266machine # [ 109.034626] systemd[1]: Stopped target Reactivate sysinit units.2267machine # [ 109.038458] systemd[1]: Stopping Reactivate sysinit units...2268machine # [ 109.039578] systemd[1]: Reached target Reactivate sysinit units.2269machine # [ 109.042377] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2270machine # [ 109.045406] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2271machine # [ 109.050480] systemd[1]: Reached target Remote File Systems.2272machine # [ 109.056357] systemd[1]: Starting linger-users.service...2273machine # [ 109.060136] systemd[1]: Reached target Local File Systems.2274machine # [ 109.064484] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2275machine # [ 109.070876] systemd[1]: Starting Create SUID/SGID Wrappers...2276machine # [ 109.071815] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2277machine # [ 109.084265] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672278machine # [ 109.086482] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2279machine # [ 109.091154] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2280machine # [ 109.094969] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2281machine # [ 109.161989] systemd[1]: linger-users.service: Deactivated successfully.2282machine # [ 109.163707] systemd[1]: Finished linger-users.service.2283machine # [ 109.433949] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2284machine # [ 109.438032] systemd[1]: Finished Create SUID/SGID Wrappers.2285machine # [ 109.694903] nixos[4452]: finished switching to system configuration /nix/store/p07waraby75g7ic3wqh9knd2gm8azh02-nixos-system-machine-test2286machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.02 seconds)2287machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd2288machine # [ 109.782479] sudo[4589]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd2289machine # [ 109.803355] sudo[4589]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2290machine # [ 109.818937] sudo[4589]: pam_unix(sudo:session): session closed for user usertest2291machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.11 seconds)2292machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2293machine # [ 109.871077] sudo[4595]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2294machine # [ 109.887330] sudo[4595]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2295machine # [ 109.898611] systemd[589]: Reload requested from client PID 4600 ('systemctl')...2296machine # [ 109.903061] systemd[589]: Reloading...2297machine # [ 110.109765] systemd[589]: Reloading finished in 207 ms.2298machine # [ 110.124179] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2299machine # [ 110.136356] sudo[4595]: pam_unix(sudo:session): session closed for user usertest2300machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.32 seconds)2301machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&12302machine # [ 110.656620] nixos[4608]: switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test2303machine # [ 110.659475] systemd[1]: Stopped target Remote File Systems.2304machine # [ 110.661785] systemd[1]: Stopped target Local File Systems.2305machine # [ 111.098582] systemd[1]: Reload requested from client PID 4608 ('.switch-to-conf') (unit backdoor.service)...2306machine # [ 111.100731] systemd[1]: Reloading...2307machine # [ 111.725501] systemd[1]: Reloading finished in 622 ms.2308machine # [ 111.886970] systemd[589]: Stopped migrated.service.2309machine # [ 111.888857] systemd[589]: Reexecution requested from client PID 4677 ('switch-to-confi')...2310machine # [ 111.892717] systemd[589]: Reexecuting.2311machine # [ 111.958510] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2312machine # [ 112.172243] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2313machine # [ 112.179487] systemd[589]: Reload requested from client PID 4677 ('switch-to-confi')...2314machine # [ 112.181936] systemd[589]: Reloading...2315machine # [ 112.378145] systemd[589]: Reloading finished in 193 ms.2316machine # [ 112.396201] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2317machine # [ 112.408089] systemd[589]: Stopped Run user-specific NixOS activation.2318machine # [ 112.409250] systemd[589]: Stopping Run user-specific NixOS activation...2319machine # [ 112.412841] systemd[589]: Starting Run user-specific NixOS activation...2320machine # [ 112.462041] systemd[589]: Finished Run user-specific NixOS activation.2321machine # [ 112.466152] systemd[1]: Stopped target Reactivate sysinit units.2322machine # [ 112.468729] systemd[1]: Stopping Reactivate sysinit units...2323machine # [ 112.471183] systemd[1]: Reached target Reactivate sysinit units.2324machine # [ 112.473095] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2325machine # [ 112.474378] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2326machine # [ 112.476658] systemd[1]: Reached target Local File Systems.2327machine # [ 112.478607] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2328machine # [ 112.485467] systemd[1]: Starting Create SUID/SGID Wrappers...2329machine # [ 112.486330] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2330machine # [ 112.487558] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672331machine # [ 112.492727] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2332machine # [ 112.503721] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2333machine # [ 112.505181] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2334machine # [ 112.506390] systemd[1]: Reached target Remote File Systems.2335machine # [ 112.825548] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2336machine # [ 112.829950] systemd[1]: Finished Create SUID/SGID Wrappers.2337machine # [ 112.832915] systemd[1]: Starting linger-users.service...2338machine # [ 112.898508] systemd[1]: linger-users.service: Deactivated successfully.2339machine # [ 112.901056] systemd[1]: Finished linger-users.service.2340machine # [ 113.162740] nixos[4608]: finished switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test2341machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.04 seconds)2342machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2343machine # [ 113.280803] sudo[4745]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2344machine # [ 113.304448] sudo[4745]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2345machine # [ 113.321499] sudo[4745]: pam_unix(sudo:session): session closed for user usertest2346machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.15 seconds)2347machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service2348machine # [ 113.377086] sudo[4751]: 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.service2349machine # [ 113.394077] sudo[4751]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2350machine # [ 113.409667] sudo[4751]: pam_unix(sudo:session): session closed for user usertest2351machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.09 seconds)2352machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2353machine # [ 113.463171] sudo[4757]: 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.service2354machine # [ 113.477323] sudo[4757]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2355machine # [ 113.490070] sudo[4757]: pam_unix(sudo:session): session closed for user usertest2356machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.08 seconds)2357machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2358machine # [ 113.541614] sudo[4763]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2359machine # [ 113.555936] sudo[4763]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2360machine # [ 113.567521] systemd[589]: Reload requested from client PID 4768 ('systemctl')...2361machine # [ 113.572236] systemd[589]: Reloading...2362machine # [ 113.800794] systemd[589]: Reloading finished in 229 ms.2363machine # [ 113.816221] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2364machine # [ 113.831450] sudo[4763]: pam_unix(sudo:session): session closed for user usertest2365machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.34 seconds)2366machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2367machine # [ 113.882736] sudo[4773]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2368machine # [ 113.896786] sudo[4773]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2369machine # [ 113.913678] systemd[589]: Starting migrated.service...2370machine # [ 113.950206] systemd[589]: Finished migrated.service.2371machine # [ 113.954960] sudo[4773]: pam_unix(sudo:session): session closed for user usertest2372machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.12 seconds)2373machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2374machine # [ 114.002149] sudo[4781]: 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.service2375machine # [ 114.017090] sudo[4781]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2376machine # [ 114.029172] sudo[4781]: pam_unix(sudo:session): session closed for user usertest2377machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2378machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2379machine # [ 114.077350] sudo[4787]: 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-owner2380machine # [ 114.090763] sudo[4787]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2381machine # [ 114.102769] sudo[4787]: pam_unix(sudo:session): session closed for user usertest2382machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)2383machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2384machine # [ 114.149365] sudo[4793]: 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.service2385machine # [ 114.163156] sudo[4793]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2386machine # [ 114.186627] sudo[4793]: pam_unix(sudo:session): session closed for user usertest2387machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds)2388machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12389machine # [ 114.747293] nixos[4802]: switching to system configuration /nix/store/p07waraby75g7ic3wqh9knd2gm8azh02-nixos-system-machine-test2390machine # [ 114.750790] systemd[1]: Stopped target Local File Systems.2391machine # [ 114.752291] systemd[1]: Stopped target Remote File Systems.2392machine # [ 115.235342] systemd[1]: Reload requested from client PID 4802 ('.switch-to-conf') (unit backdoor.service)...2393machine # [ 115.237866] systemd[1]: Reloading...2394machine # [ 115.952469] systemd[1]: Reloading finished in 708 ms.2395machine # [ 116.124800] systemd[589]: Reexecution requested from client PID 4871 ('switch-to-confi')...2396machine # [ 116.126264] systemd[589]: Reexecuting.2397machine # [ 116.182915] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2398machine # [ 116.389661] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2399machine # [ 116.398395] systemd[589]: Reload requested from client PID 4871 ('switch-to-confi')...2400machine # [ 116.401244] systemd[589]: Reloading...2401machine # [ 116.613768] systemd[589]: Reloading finished in 210 ms.2402machine # [ 116.628212] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2403machine # [ 116.639515] systemd[589]: Stopped Run user-specific NixOS activation.2404machine # [ 116.642368] systemd[589]: Stopping Run user-specific NixOS activation...2405machine # [ 116.645457] systemd[589]: Starting Run user-specific NixOS activation...2406machine # [ 116.696234] systemd[589]: Finished Run user-specific NixOS activation.2407machine # [ 116.698897] systemd[589]: Reload requested from client PID 4871 ('switch-to-confi')...2408machine # [ 116.703038] systemd[589]: Reloading...2409machine # [ 116.896802] systemd[589]: Reloading finished in 194 ms.2410machine # [ 116.912265] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2411machine # [ 116.928533] systemd[1]: Stopped target Reactivate sysinit units.2412machine # [ 116.931901] systemd[1]: Stopping Reactivate sysinit units...2413machine # [ 116.933343] systemd[1]: Reached target Reactivate sysinit units.2414machine # [ 116.935842] systemd[1]: Reached target Local File Systems.2415machine # [ 116.937846] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2416machine # [ 116.939144] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2417machine # [ 116.942449] systemd[1]: Reached target Remote File Systems.2418machine # [ 116.947308] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2419machine # [ 116.954499] systemd[1]: Starting Create SUID/SGID Wrappers...2420machine # [ 116.955439] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2421machine # [ 116.957435] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672422machine # [ 116.961258] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2423machine # [ 116.965002] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2424machine # [ 116.966394] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2425machine # [ 117.303044] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2426machine # [ 117.307750] systemd[1]: Finished Create SUID/SGID Wrappers.2427machine # [ 117.310662] systemd[1]: Starting linger-users.service...2428machine # [ 117.373228] systemd[1]: linger-users.service: Deactivated successfully.2429machine # [ 117.375901] systemd[1]: Finished linger-users.service.2430machine # [ 117.635382] nixos[4802]: finished switching to system configuration /nix/store/p07waraby75g7ic3wqh9knd2gm8azh02-nixos-system-machine-test2431machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.46 seconds)2432machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2433machine # [ 117.740878] sudo[4943]: 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.service2434machine # [ 117.761561] sudo[4943]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2435machine # [ 117.789290] sudo[4943]: pam_unix(sudo:session): session closed for user usertest2436machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.14 seconds)2437machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2438machine # [ 117.842955] sudo[4949]: 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-owner2439machine # [ 117.857528] sudo[4949]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2440machine # [ 117.871117] sudo[4949]: pam_unix(sudo:session): session closed for user usertest2441machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)2442machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd2443machine # [ 117.924647] sudo[4955]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd2444machine # [ 117.939487] sudo[4955]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2445machine # [ 117.953528] sudo[4955]: pam_unix(sudo:session): session closed for user usertest2446machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.08 seconds)2447machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2448machine # [ 118.006944] sudo[4961]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2449machine # [ 118.021169] sudo[4961]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2450machine # [ 118.032468] systemd[589]: Reload requested from client PID 4966 ('systemctl')...2451machine # [ 118.034858] systemd[589]: Reloading...2452machine # [ 118.243340] systemd[589]: Reloading finished in 206 ms.2453machine # [ 118.256208] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2454machine # [ 118.268091] sudo[4961]: pam_unix(sudo:session): session closed for user usertest2455machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.31 seconds)2456machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service2457machine # [ 118.324783] sudo[4971]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user stop migrated.service2458machine # [ 118.338699] sudo[4971]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2459machine # [ 118.353890] systemd[589]: Stopped migrated.service.2460machine # [ 118.358729] sudo[4971]: pam_unix(sudo:session): session closed for user usertest2461machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.09 seconds)2462machine: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&12463machine # [ 118.990481] nixos[4980]: switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test2464machine # [ 118.995519] systemd[1]: Stopped target Local File Systems.2465machine # [ 118.997327] systemd[1]: Stopped target Remote File Systems.2466machine # [ 119.517445] systemd[1]: Reload requested from client PID 4980 ('.switch-to-conf') (unit backdoor.service)...2467machine # [ 119.519577] systemd[1]: Reloading...2468machine # [ 120.146842] systemd[1]: Reloading finished in 624 ms.2469machine # [ 120.313309] systemd[589]: Reexecution requested from client PID 5048 ('switch-to-confi')...2470machine # [ 120.317806] systemd[589]: Reexecuting.2471machine # [ 120.391821] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2472machine # [ 120.626075] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2473machine # [ 120.634821] systemd[589]: Reload requested from client PID 5048 ('switch-to-confi')...2474machine # [ 120.637630] systemd[589]: Reloading...2475machine # [ 120.853139] systemd[589]: Reloading finished in 213 ms.2476machine # [ 120.868220] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2477machine # [ 120.880536] systemd[589]: Stopped Run user-specific NixOS activation.2478machine # [ 120.883159] systemd[589]: Stopping Run user-specific NixOS activation...2479machine # [ 120.886302] systemd[589]: Starting Run user-specific NixOS activation...2480machine # [ 120.940479] systemd[589]: Finished Run user-specific NixOS activation.2481machine # [ 120.945763] systemd[1]: Stopped target Reactivate sysinit units.2482machine # [ 120.947096] systemd[1]: Stopping Reactivate sysinit units...2483machine # [ 120.948826] systemd[1]: Reached target Reactivate sysinit units.2484machine # [ 120.952884] systemd[1]: Reached target Remote File Systems.2485machine # [ 120.958895] systemd[1]: Reached target Local File Systems.2486machine # [ 120.960187] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2487machine # [ 120.964570] systemd[1]: Starting Create SUID/SGID Wrappers...2488machine # [ 120.965441] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2489machine # [ 120.966652] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672490machine # [ 120.971058] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2491machine # [ 120.984267] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2492machine # [ 120.985636] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2493machine # [ 120.986826] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2494machine # [ 120.989123] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2495machine # [ 121.334892] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2496machine # [ 121.337631] systemd[1]: Finished Create SUID/SGID Wrappers.2497machine # [ 121.343857] systemd[1]: Starting linger-users.service...2498machine # [ 121.411249] systemd[1]: linger-users.service: Deactivated successfully.2499machine # [ 121.413941] systemd[1]: Finished linger-users.service.2500machine # [ 121.672589] nixos[4980]: finished switching to system configuration /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test2501machine: (finished: must succeed: /nix/store/dpl153x0i57g4jq2kacwrxa7xp5kkhlg-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.32 seconds)2502(finished: subtest: user services, in 90.28 seconds)2503(finished: run the VM test script, in 122.48 seconds)2504test script finished in 122.56s2505cleanup2506kill QemuMachine (pid 45)2507machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14)2508(finished: cleanup, in 0.19 seconds)2509additionally exposed symbols:2510 machine, other,2511 vlan1,2512 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