vm-test-run-switch-test-user
checks.aarch64-linux.switchTest-user
· build #15
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7machine: waiting for unit multi-user.target8machine: waiting for the VM to finish booting9machine: starting vm10machine: QEMU running (pid 45)11machine # Disk image does not exist, creating the virtualisation disk image...12machine # Formatting '/build/vm-state-machine/tmp.knM9tWyTcK', 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: 7768744b-c166-4270-9619-55b07a67f79a17machine # 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.48 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Aug 28 06:22:54 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/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/init regInfo=/nix/store/p2bdcc7gqfh9cdgwwpllc4fq730xhhz0-closure-info/registration console=ttyAMA0,115200n8 console=tty061machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/p2bdcc7gqfh9cdgwwpllc4fq730xhhz0-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.000468] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)106machine # [ 0.000628] Console: colour dummy device 80x25107machine # [ 0.000636] printk: legacy console [tty0] enabled108machine # [ 0.000828] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)109machine # [ 0.000835] pid_max: default: 32768 minimum: 301110machine # [ 0.000916] LSM: initializing lsm=capability,landlock,yama,bpf,ima111machine # [ 0.001047] landlock: Up and running.112machine # [ 0.001050] Yama: becoming mindful.113machine # [ 0.001516] LSM support for eBPF active114machine # [ 0.001691] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)115machine # [ 0.001710] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)116machine # [ 0.002808] cacheinfo: Unable to detect cache hierarchy for CPU 0117machine # [ 0.003653] rcu: Hierarchical SRCU implementation.118machine # [ 0.003659] rcu: Max phase no-delay instances is 1000.119machine # [ 0.004947] fsl-mc MSI: its@8080000 domain created120machine # [ 0.005039] EFI services will not be available.121machine # [ 0.005125] smp: Bringing up secondary CPUs ...122machine # [ 0.005134] smp: Brought up 1 node, 1 CPU123machine # [ 0.005137] SMP: Total of 1 processors activated.124machine # [ 0.005140] CPU: All CPU(s) started at EL1125machine # [ 0.005155] CPU features: detected: Branch Target Identification126machine # [ 0.005160] CPU features: detected: ARMv8.4 Translation Table Level127machine # [ 0.005163] CPU features: detected: Instruction cache invalidation not required for I/D coherence128machine # [ 0.005166] CPU features: detected: Data cache clean to the PoU not required for I/D coherence129machine # [ 0.005170] CPU features: detected: Common not Private translations130machine # [ 0.005173] CPU features: detected: CRC32 instructions131machine # [ 0.005176] CPU features: detected: Data cache clean to Point of Deep Persistence132machine # [ 0.005179] CPU features: detected: Data cache clean to Point of Persistence133machine # [ 0.005182] CPU features: detected: Data independent timing control (DIT)134machine # [ 0.005186] CPU features: detected: E0PD135machine # [ 0.005188] CPU features: detected: Enhanced Counter Virtualization136machine # [ 0.005192] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)137machine # [ 0.005195] CPU features: detected: Enhanced Virtualization Traps138machine # [ 0.005198] CPU features: detected: Fine Grained Traps139machine # [ 0.005202] CPU features: detected: Generic authentication (architected QARMA5 algorithm)140machine # [ 0.005207] CPU features: detected: RCpc load-acquire (LDAPR)141machine # [ 0.005210] CPU features: detected: LSE atomic instructions142machine # [ 0.005213] CPU features: detected: Privileged Access Never143machine # [ 0.005216] CPU features: detected: PMUv3144machine # [ 0.005218] CPU features: detected: RAS Extension Support145machine # [ 0.005221] CPU features: detected: RASv1p1 Extension Support146machine # [ 0.005223] CPU features: detected: Random Number Generator147machine # [ 0.005226] CPU features: detected: Speculation barrier (SB)148machine # [ 0.005229] CPU features: detected: Stage-2 Force Write-Back149machine # [ 0.005232] CPU features: detected: TLB range maintenance instructions150machine # [ 0.005237] CPU features: detected: Speculative Store Bypassing Safe (SSBS)151machine # [ 0.005274] alternatives: applying system-wide alternatives152machine # [ 0.008338] CPU features: detected: BBM Level 2 without TLB conflict abort153machine # [ 0.008555] Memory: 894712K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 112596K reserved, 32768K cma-reserved)154machine # [ 0.008921] devtmpfs: initialized155machine # [ 0.010672] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)156machine # [ 0.010694] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).157machine # [ 0.010871] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL158machine # [ 0.010876] 0 pages in range for non-PLT usage159machine # [ 0.010877] 508288 pages in range for PLT usage160machine # [ 0.010991] pinctrl core: initialized pinctrl subsystem161machine # [ 0.011820] DMI not present or invalid.162machine # [ 0.015208] NET: Registered PF_NETLINK/PF_ROUTE protocol family163machine # [ 0.017539] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations164machine # [ 0.017685] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations165machine # [ 0.017848] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations166machine # [ 0.017872] audit: initializing netlink subsys (disabled)167machine # [ 0.018432] thermal_sys: Registered thermal governor 'fair_share'168machine # [ 0.018434] thermal_sys: Registered thermal governor 'bang_bang'169machine # [ 0.018437] thermal_sys: Registered thermal governor 'step_wise'170machine # [ 0.018440] thermal_sys: Registered thermal governor 'user_space'171machine # [ 0.018446] thermal_sys: Registered thermal governor 'power_allocator'172machine # [ 0.018503] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1173machine # [ 0.018513] cpuidle: using governor ladder174machine # [ 0.018519] cpuidle: using governor menu175machine # [ 0.019322] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.176machine # [ 0.019346] ASID allocator initialised with 65536 entries177machine # [ 0.020582] Serial: AMBA PL011 UART driver178machine # [ 0.025882] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1179machine # [ 0.026055] printk: console [ttyAMA0] enabled180machine # [ 0.150287] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages181machine # [ 0.150312] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page182machine # [ 0.150317] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages183machine # [ 0.150322] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page184machine # [ 0.150326] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages185machine # [ 0.150331] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page186machine # [ 0.150335] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages187machine # [ 0.150339] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page188machine # [ 0.158037] fbcon: Taking over console189machine # [ 0.158058] ACPI: Interpreter disabled.190machine # [ 0.166630] iommu: Default domain type: Translated191machine # [ 0.166647] iommu: DMA domain TLB invalidation policy: strict mode192machine # [ 0.167131] SCSI subsystem initialized193machine # [ 0.169118] usbcore: registered new interface driver usbfs194machine # [ 0.169153] usbcore: registered new interface driver hub195machine # [ 0.169170] usbcore: registered new device driver usb196machine # [ 0.169461] pps_core: LinuxPPS API ver. 1 registered197machine # [ 0.169467] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>198machine # [ 0.169478] PTP clock support registered199machine # [ 0.169528] EDAC MC: Ver: 3.0.0200machine # [ 0.174173] scmi_core: SCMI protocol bus registered201machine # [ 0.175247] FPGA manager framework202machine # [ 0.176237] vgaarb: loaded203machine # [ 0.176910] clocksource: Switched to clocksource arch_sys_counter204machine # [ 0.181895] VFS: Disk quotas dquot_6.6.0205machine # [ 0.181943] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)206machine # [ 0.183660] netfs: FS-Cache loaded207machine # [ 0.183773] pnp: PnP ACPI: disabled208machine # [ 0.187802] NET: Registered PF_INET protocol family209machine # [ 0.187960] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)210machine # [ 0.218887] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)211machine # [ 0.218945] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)212machine # [ 0.218975] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)213machine # [ 0.219027] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)214machine # [ 0.219105] TCP: Hash tables configured (established 8192 bind 8192)215machine # [ 0.219225] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)216machine # [ 0.219290] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)217machine # [ 0.219354] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)218machine # [ 0.219450] NET: Registered PF_UNIX/PF_LOCAL protocol family219machine # [ 0.219489] NET: Registered PF_XDP protocol family220machine # [ 0.219510] PCI: CLS 0 bytes, default 64221machine # [ 0.219798] Trying to unpack rootfs image as initramfs...222machine # [ 0.234722] kvm [1]: HYP mode not available223machine # [ 0.377519] Initialise system trusted keyrings224machine # [ 0.378298] workingset: timestamp_bits=42 max_order=18 bucket_order=0225machine # [ 0.385438] squashfs: version 4.0 (2009/01/31) Phillip Lougher226machine # [ 0.386289] 9p: Installing v9fs 9p2000 file system support227machine # [ 0.406482] Key type asymmetric registered228machine # [ 0.406512] Asymmetric key parser 'x509' registered229machine # [ 0.406598] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)230machine # [ 0.408784] io scheduler mq-deadline registered231machine # [ 0.408796] io scheduler kyber registered232machine # [ 0.422481] pl061_gpio 9030000.pl061: PL061 GPIO chip registered233machine # [ 0.423934] ledtrig-cpu: registered to indicate activity on CPUs234machine # [ 0.424335] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:235machine # [ 0.424353] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000236machine # [ 0.424365] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000237machine # [ 0.424374] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000238machine # [ 0.424394] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits239machine # [ 0.424416] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]240machine # [ 0.424501] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00241machine # [ 0.424510] pci_bus 0000:00: root bus resource [bus 00-ff]242machine # [ 0.424517] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]243machine # [ 0.424523] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]244machine # [ 0.424527] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]245machine # [ 0.424586] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint246machine # [ 0.437189] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint247machine # [ 0.437379] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]248machine # [ 0.437397] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]249machine # [ 0.437428] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]250machine # [ 0.437445] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]251machine # [ 0.437899] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint252machine # [ 0.438082] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]253machine # [ 0.438098] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]254machine # [ 0.438128] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]255machine # [ 0.438587] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint256machine # [ 0.438770] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]257machine # [ 0.438787] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]258machine # [ 0.438818] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]259machine # [ 0.439293] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint260machine # [ 0.439473] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]261machine # [ 0.439489] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]262machine # [ 0.439519] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]263machine # [ 0.439978] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint264machine # [ 0.440161] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]265machine # [ 0.440177] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]266machine # [ 0.440208] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]267machine # [ 0.440655] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint268machine # [ 0.440839] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]269machine # [ 0.440855] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]270machine # [ 0.440886] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]271machine # [ 0.469548] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint272machine # [ 0.469773] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]273machine # [ 0.469792] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]274machine # [ 0.469823] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275machine # [ 0.469839] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]276machine # [ 0.470301] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint277machine # [ 0.470489] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]278machine # [ 0.470520] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279machine # [ 0.470985] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint280machine # [ 0.471200] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.471232] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.471621] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint283machine # [ 0.471806] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]284machine # [ 0.472064] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint285machine # [ 0.472354] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]286machine # [ 0.472372] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]287machine # [ 0.472402] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]288machine # [ 0.472851] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint289machine # [ 0.488134] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]290machine # [ 0.488156] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]291machine # [ 0.488186] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]292machine # [ 0.488793] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned293machine # [ 0.488805] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned294machine # [ 0.488811] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned295machine # [ 0.488856] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned296machine # [ 0.488903] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned297machine # [ 0.495923] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned298machine # [ 0.495975] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned299machine # [ 0.496022] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned300machine # [ 0.496071] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned301machine # [ 0.496119] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned302machine # [ 0.496166] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned303machine # [ 0.496213] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned304machine # [ 0.496307] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned305machine # [ 0.496355] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned306machine # [ 0.496377] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned307machine # [ 0.496399] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned308machine # [ 0.496421] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned309machine # [ 0.496444] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned310machine # [ 0.496469] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned311machine # [ 0.496491] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned312machine # [ 0.496513] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned313machine # [ 0.496535] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned314machine # [ 0.496557] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned315machine # [ 0.496579] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned316machine # [ 0.496602] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned317machine # [ 0.496624] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned318machine # [ 0.496645] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned319machine # [ 0.496666] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned320machine # [ 0.496688] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned321machine # [ 0.496709] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned322machine # [ 0.496731] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned323machine # [ 0.496753] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned324machine # [ 0.496775] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned325machine # [ 0.496798] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned326machine # [ 0.496825] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]327machine # [ 0.496834] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]328machine # [ 0.496839] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]329machine # [ 0.530129] pci 0000:00:0a.0: enabling device (0000 -> 0002)330machine # [ 0.551485] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)331machine # [ 0.561668] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)332machine # [ 0.573244] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)333machine # [ 0.575507] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)334machine # [ 0.577593] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)335machine # [ 0.579890] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)336machine # [ 0.590478] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)337machine # [ 0.592727] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)338machine # [ 0.604311] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)339machine # [ 0.610609] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)340machine # [ 0.621207] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)341machine # [ 0.634405] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled342machine # [ 0.636351] msm_serial: driver initialized343machine # [ 0.636521] SuperH (H)SCI(F) driver initialized344machine # [ 0.636576] STM32 USART driver initialized345machine # [ 0.671083] loop: module loaded346machine # [ 0.671360] virtio_blk virtio5: 1/0/0 default/read/poll queues347machine # [ 0.672242] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)348machine # [ 0.685782] megasas: 07.734.00.00-rc1349machine # [ 0.686536] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]350machine # [ 0.688997] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000351machine # [ 0.689032] Intel/Sharp Extended Query Table at 0x0031352machine # [ 0.690605] Using buffer write method353machine # [ 0.690708] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]354machine # [ 0.692437] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000355machine # [ 0.692477] Intel/Sharp Extended Query Table at 0x0031356machine # [ 0.711193] Using buffer write method357machine # [ 0.711252] Concatenating MTD devices:358machine # [ 0.711257] (0): "0.flash"359machine # [ 0.711261] (1): "0.flash"360machine # [ 0.711265] into device "0.flash"361machine # [ 0.992339] Freeing initrd memory: 25736K362machine # [ 0.998835] tun: Universal TUN/TAP device driver, 1.6363machine # [ 1.002777] thunder_xcv, ver 1.0364machine # [ 1.002826] thunder_bgx, ver 1.0365machine # [ 1.002848] nicpf, ver 1.0366machine # [ 1.003439] e1000: Intel(R) PRO/1000 Network Driver367machine # [ 1.003446] e1000: Copyright (c) 1999-2006 Intel Corporation.368machine # [ 1.003478] e1000e: Intel(R) PRO/1000 Network Driver369machine # [ 1.003486] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.370machine # [ 1.003510] igb: Intel(R) Gigabit Ethernet Network Driver371machine # [ 1.003515] igb: Copyright (c) 2007-2014 Intel Corporation.372machine # [ 1.003536] igbvf: Intel(R) Gigabit Virtual Function Network Driver373machine # [ 1.003542] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.374machine # [ 1.003676] sky2: driver version 1.30375machine # [ 1.005596] ehci-pci 0000:00:0a.0: EHCI Host Controller376machine # [ 1.005627] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1377machine # [ 1.005870] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000378machine # [ 1.015670] usbcore: registered new interface driver usb-storage379machine # [ 1.015739] usbcore: registered new interface driver usbserial_generic380machine # [ 1.015753] usbserial: USB Serial support registered for generic381machine # [ 1.016383] hv_vmbus: registering driver hyperv_keyboard382machine # [ 1.016982] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00383machine # [ 1.017367] hub 1-0:1.0: USB hub found384machine # [ 1.017389] hub 1-0:1.0: 6 ports detected385machine # [ 1.022701] rtc-pl031 9010000.pl031: registered as rtc0386machine # [ 1.022730] rtc-pl031 9010000.pl031: setting system clock to 2026-09-01T03:10:37 UTC (1788232237)387machine # [ 1.023098] i2c_dev: i2c /dev entries driver388machine # [ 1.028274] sdhci: Secure Digital Host Controller Interface driver389machine # [ 1.028293] sdhci: Copyright(c) Pierre Ossman390machine # [ 1.028565] Synopsys Designware Multimedia Card Interface Driver391machine # [ 1.031011] sdhci-pltfm: SDHCI platform and OF driver helper392machine # [ 1.032870] hid: raw HID events driver (C) Jiri Kosina393machine # [ 1.034486] usbcore: registered new interface driver usbhid394machine # [ 1.034497] usbhid: USB HID core driver395machine # [ 1.036860] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available396machine # [ 1.039559] drop_monitor: Initializing network drop monitor service397machine # [ 1.039778] NET: Registered PF_INET6 protocol family398machine # [ 1.041993] Segment Routing with IPv6399machine # [ 1.042016] In-situ OAM (IOAM) with IPv6400machine # [ 1.042046] NET: Registered PF_PACKET protocol family401machine # [ 1.043713] 9pnet: Installing 9P2000 support402machine # [ 1.046662] Key type dns_resolver registered403machine # [ 1.053441] registered taskstats version 1404machine # [ 1.053616] Loading compiled-in X.509 certificates405machine # [ 1.062593] Demotion targets for Node 0: null406machine # [ 1.062723] Key type .fscrypt registered407machine # [ 1.062730] Key type fscrypt-provisioning registered408machine # [ 1.062839] ima: No TPM chip found, activating TPM-bypass!409machine # [ 1.062862] ima: Allocated hash algorithm: sha1410machine # [ 1.062887] ima: No architecture policies found411machine # [ 1.067286] input: gpio-keys as /devices/platform/gpio-keys/input/input0412machine # [ 1.086895] clk: Disabling unused clocks413machine # [ 1.086933] PM: genpd: Disabling unused power domains414machine # [ 1.091355] Freeing unused kernel memory: 4736K415machine # [ 1.091587] Run /init as init process416machine # [ 1.107489] systemd[1]: Successfully made /usr/ read-only.417machine # [ 1.265016] usb 1-1: new high-speed USB device number 2 using ehci-pci418machine # [ 1.419581] 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.442510] systemd[1]: systemd 262-d0fcc5a 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.454780] systemd[1]: Detected virtualization qemu.421machine # [ 1.456968] systemd[1]: Detected architecture arm64.422machine # [ 1.458903] systemd[1]: Running in initrd.423machine # [ 1.461630] systemd[1]: Initializing machine ID from random generator.424machine # [ 1.464606] systemd[1]: Hostname set to <machine>.425machine # [ 1.509252] 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.617351] systemd[1]: bpf-restrict-fs: LSM BPF program attached427machine # [ 1.633032] usb 1-2: new high-speed USB device number 3 using ehci-pci428machine # [ 1.674927] systemd[1]: Queued start job for default target Initrd Default Target.429machine # [ 1.683137] systemd[1]: Created slice Slice /system/modprobe.430machine # [ 1.684236] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.431machine # [ 1.685503] systemd[1]: Expecting device /dev/disk/by-label/nixos...432machine # [ 1.686373] systemd[1]: Reached target Path Units.433machine # [ 1.687041] systemd[1]: Reached target Slice Units.434machine # [ 1.687741] systemd[1]: Reached target Swaps.435machine # [ 1.688355] systemd[1]: Reached target Timer Units.436machine # [ 1.689237] systemd[1]: Listening on D-Bus System Message Bus Socket.437machine # [ 1.690303] systemd[1]: Listening on Journal Socket (/dev/log).438machine # [ 1.691303] systemd[1]: Listening on Journal Sockets.439machine # [ 1.692111] systemd[1]: Listening on udev Kernel Socket.440machine # [ 1.692953] systemd[1]: Reached target Socket Units.441machine # [ 1.695284] systemd[1]: Starting Create List of Static Device Nodes...442machine # [ 1.701990] systemd[1]: Starting Load Kernel Module 9pnet_virtio...443machine # [ 1.702967] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs444machine # [ 1.713294] systemd[1]: Mounting Kernel Configuration File System...445machine # [ 1.745328] systemd[1]: Starting Journal Service...446machine # [ 1.753588] systemd[1]: Starting Load Kernel Modules...447machine # [ 1.754446] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os448machine # [ 1.761169] systemd[1]: Starting Coldplug All udev Devices...449machine # [ 1.781859] systemd[1]: Finished Create List of Static Device Nodes.450machine # [ 1.784627] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.451machine # [ 1.789108] systemd[1]: Finished Load Kernel Module 9pnet_virtio.452machine # [ 1.789677] systemd[1]: Mounted Kernel Configuration File System.453machine # [ 1.805801] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...454machine # [ 1.807657] 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/input2455machine # [ 1.807829] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0456machine # [ 1.830111] systemd-journald[73]: Collecting audit messages is disabled.457machine # [ 1.844403] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.458machine # [ 1.854598] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev459machine # [ 1.873254] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0460machine # [ 1.873528] [drm] features: -virgl +edid -resource_blob -host_visible461machine # [ 1.873543] [drm] features: -context_init462machine # [ 1.874327] [drm] number of scanouts: 1463machine # [ 1.874349] [drm] number of cap sets: 0464machine # [ 1.885882] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.465machine # [ 1.889003] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic466machine # [ 1.889026] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0467machine # [ 1.901665] systemd[1]: Starting Create Static Device Nodes in /dev...468machine # [ 1.953048] Console: switching to colour frame buffer device 160x50469machine # [ 1.953751] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device470machine # [ 1.967219] systemd[1]: Finished Load Kernel Modules.471machine # [ 1.970991] systemd[1]: Starting Apply Kernel Variables...472machine # [ 1.989243] systemd[1]: Finished Create Static Device Nodes in /dev.473machine # [ 1.992235] systemd[1]: Reached target Preparation for Local File Systems.474machine # [ 1.994574] systemd[1]: Reached target Local File Systems.475machine # [ 2.001411] systemd[1]: Starting Rule-based Manager for Device Events and Files...476machine # [ 2.012551] systemd-modules-load[74]: Inserted module 'dm_mod'477machine # [ 2.030745] systemd[1]: Started Journal Service.478machine # [ 2.024294] systemd-modules-load[74]: Module 'virtio_balloon' is built in479machine # [ 2.032171] systemd-modules-load[74]: Module 'virtio_console' is built in480machine # [ 2.036566] systemd-modules-load[74]: Inserted module 'virtio_gpu'481machine # [ 2.037669] systemd-modules-load[74]: Module 'virtio_rng' is built in482machine # [ 2.038721] systemd[1]: Starting Create System Files and Directories...483machine # [ 2.057455] systemd[1]: Finished Apply Kernel Variables.484machine # [ 2.093679] systemd[1]: Finished Create System Files and Directories.485machine # [ 2.105254] systemd-udevd[80]: Using default interface naming scheme 'v261'.486machine # [ 2.138789] systemd[1]: Started Rule-based Manager for Device Events and Files.487machine # [ 2.220382] systemd[1]: Starting Virtual Console Setup...488machine # [ 2.300648] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.489machine # [ 2.304614] systemd[1]: Finished Virtual Console Setup.490machine # [ 2.917997] systemd[1]: Finished Coldplug All udev Devices.491machine # [ 2.919769] systemd[1]: Reached target System Initialization.492machine # [ 2.920774] systemd[1]: Reached target Basic System.493machine # [ 3.077460] (udev-worker)[94]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.494machine # [ 3.091557] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.495machine # [ 3.116394] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.496machine # [ 3.184157] systemd[1]: Found device /dev/disk/by-label/nixos.497machine # [ 3.189154] systemd[1]: Reached target Initrd Root Device.498machine # [ 3.193897] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...499machine # [ 3.255548] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks500machine # [ 3.267392] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.501machine # [ 3.272232] systemd[1]: Mounting /sysroot...502machine # [ 3.326084] EXT4-fs (vda): mounted filesystem 7768744b-c166-4270-9619-55b07a67f79a r/w with ordered data mode. Quota mode: none.503machine # [ 3.317328] systemd[1]: Mounted /sysroot.504machine # [ 3.319019] systemd[1]: Reached target Initrd Root File System.505machine # [ 3.324147] systemd[1]: Starting Mountpoints Configured in the Real Root...506machine # [ 3.356525] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.507machine # [ 3.361231] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...508machine # [ 3.364779] systemd[1]: Reloading...509machine # [ 3.581704] systemd[1]: Reloading finished in 217 ms.510machine # [ 3.614679] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...511machine # [ 3.617395] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...512machine # [ 3.629379] systemd[1]: Starting Load Kernel Module 9pnet_virtio...513machine # [ 3.632226] systemd[1]: initrd-parse-etc.service: Deactivated successfully.514machine # [ 3.634108] systemd[1]: Finished Mountpoints Configured in the Real Root.515machine # [ 3.640308] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.516machine # [ 3.663527] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.517machine # [ 3.666296] systemd[1]: Finished Load Kernel Module 9pnet_virtio.518machine # [ 3.758191] systemd[1]: Mounting /sysroot/nix/.ro-store...519machine # [ 3.771112] systemd[1]: Mounting /sysroot/nix/.rw-store...520machine # [ 3.783206] systemd[1]: Mounting /sysroot/run...521machine # [ 3.804310] systemd[1]: Mounting /sysroot/tmp/shared...522machine # [ 3.851052] systemd[1]: Mounting /sysroot/tmp/xchg...523machine # [ 3.895946] systemd[1]: Mounted /sysroot/nix/.ro-store.524machine # [ 3.899410] systemd[1]: Mounted /sysroot/nix/.rw-store.525machine # [ 3.909245] systemd[1]: Mounted /sysroot/run.526machine # [ 3.911176] systemd[1]: Mounted /sysroot/tmp/shared.527machine # [ 3.928712] systemd[1]: Starting rw-sysroot-nix-store.service...528machine # [ 3.930952] systemd[1]: Mounted /sysroot/tmp/xchg.529machine # [ 3.965325] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.530machine # [ 3.968124] systemd[1]: Finished rw-sysroot-nix-store.service.531machine # [ 4.595469] (udev-worker)[94]: mtd0ro: Failed to find and pin callout binary "/nix/store/aap1fb66p72ib35mngb3ccjh9halipzf-systemd-262-d0fcc5a/lib/udev/mtd_probe": No such file or directory532machine # [ 4.599346] (udev-worker)[94]: mtd0ro: /nix/store/aap1fb66p72ib35mngb3ccjh9halipzf-systemd-262-d0fcc5a/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.634368] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.534machine # [ 4.635572] systemd[1]: Stopped Virtual Console Setup.535machine # [ 4.640319] systemd[1]: Stopping Virtual Console Setup...536machine # [ 4.641185] systemd[1]: Starting Virtual Console Setup...537machine # [ 4.657481] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.538machine # [ 4.664323] systemd[1]: Stopped Virtual Console Setup.539machine # [ 4.668212] systemd[1]: Starting Virtual Console Setup...540machine # [ 4.695415] systemd-vconsole-setup[155]: Configuration of first virtual console was skipped, ignoring remaining ones.541machine # [ 4.699028] systemd[1]: Finished Virtual Console Setup.542machine # [ 4.758425] systemd[1]: Mounting /sysroot/nix/store...543machine # [ 4.795437] systemd[1]: Mounted /sysroot/nix/store.544machine # [ 4.797583] systemd[1]: Reached target Initrd File Systems.545machine # [ 4.802438] systemd[1]: Starting Find NixOS closure...546machine # [ 4.811957] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...547machine # [ 4.845060] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.548machine # [ 4.849252] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.549machine # [ 4.863108] systemd[1]: Finished Find NixOS closure.550machine # [ 4.866889] systemd[1]: Reached target Initrd Default Target.551machine # [ 4.872709] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...552machine # [ 4.900282] systemd[1]: Stopped target Initrd Default Target.553machine # [ 4.902030] systemd[1]: Stopped target Basic System.554machine # [ 4.903020] systemd[1]: Stopped target Initrd Root Device.555machine # [ 4.907398] systemd[1]: Stopped target Path Units.556machine # [ 4.908383] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.557machine # [ 4.909424] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.558machine # [ 4.910505] systemd[1]: Stopped target Slice Units.559machine # [ 4.911228] systemd[1]: Stopped target Socket Units.560machine # [ 4.911894] systemd[1]: Stopped target System Initialization.561machine # [ 4.916352] systemd[1]: Stopped target Swaps.562machine # [ 4.917566] systemd[1]: Stopped target Timer Units.563machine # [ 4.920212] systemd[1]: dbus.socket: Deactivated successfully.564machine # [ 4.921127] systemd[1]: Closed D-Bus System Message Bus Socket.565machine # [ 4.923277] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.566machine # [ 4.928245] systemd[1]: Stopped Find NixOS closure.567machine # [ 4.930387] systemd[1]: Starting Load Kernel Module 9pnet_virtio...568machine # [ 4.934912] systemd[1]: Starting rw-sysroot-nix-store.service...569machine # [ 4.941708] systemd[1]: systemd-sysctl.service: Deactivated successfully.570machine # [ 4.947433] systemd[1]: Stopped Apply Kernel Variables.571machine # [ 4.951638] systemd[1]: systemd-modules-load.service: Deactivated successfully.572machine # [ 4.956489] systemd[1]: Stopped Load Kernel Modules.573machine # [ 4.958839] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.574machine # [ 4.961081] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.575machine # [ 4.962381] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.576machine # [ 4.963423] systemd[1]: Stopped Create System Files and Directories.577machine # [ 4.970551] systemd[1]: Stopped target Local File Systems.578machine # [ 4.976377] systemd[1]: Stopped target Preparation for Local File Systems.579machine # [ 4.979078] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.580machine # [ 4.982999] systemd[1]: Stopped Coldplug All udev Devices.581machine # [ 4.984602] systemd[1]: Stopping Rule-based Manager for Device Events and Files...582machine # [ 4.987680] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.583machine # [ 4.992377] systemd[1]: Stopped Virtual Console Setup.584machine # [ 4.993178] systemd[1]: initrd-cleanup.service: Deactivated successfully.585machine # [ 4.994116] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.586machine # [ 4.995056] systemd[1]: systemd-udevd.service: Deactivated successfully.587machine # [ 4.995977] systemd[1]: Stopped Rule-based Manager for Device Events and Files.588machine # [ 5.000209] systemd[1]: systemd-udevd.service: Consumed 1.581s CPU time over 2.985s wall clock time, 22M memory peak.589machine # [ 5.002389] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.590machine # [ 5.003567] systemd[1]: Finished Load Kernel Module 9pnet_virtio.591machine # [ 5.004650] systemd[1]: Starting Cleanup udev Database...592machine # [ 5.005435] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.593machine # [ 5.006543] systemd[1]: Stopped Create Static Device Nodes in /dev.594machine # [ 5.007426] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.595machine # [ 5.013660] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.596machine # [ 5.016248] systemd[1]: kmod-static-nodes.service: Deactivated successfully.597machine # [ 5.020444] systemd[1]: Stopped Create List of Static Device Nodes.598machine # [ 5.021671] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.599machine # [ 5.022758] systemd[1]: Finished rw-sysroot-nix-store.service.600machine # [ 5.055709] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.601machine # [ 5.059718] systemd[1]: Finished Cleanup udev Database.602machine # [ 5.061138] systemd[1]: Reached target Switch Root.603machine # [ 5.062481] systemd[1]: Starting NixOS Activation...604machine # [ 5.269587] initrd-nixos-activation-start[180]: booting system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test605machine # [ 5.343105] initrd-nixos-activation-start[180]: running activation script...606machine # [ 5.789346] initrd-nixos-activation-start[203]: setting up /etc...607machine # [ 6.070478] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.608machine # [ 6.073586] systemd[1]: Finished NixOS Activation.609machine # [ 6.076256] systemd[1]: Starting Switch Root...610machine # [ 6.102210] systemd[1]: Switching root.611machine # [ 6.299076] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).612machine # [ 6.900081] systemd[1]: systemd 262-d0fcc5a 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.913655] systemd[1]: Detected virtualization qemu.614machine # [ 6.917989] systemd[1]: Detected architecture arm64.615machine # [ 6.920264] systemd[1]: Detected first boot.616machine # [ 6.928617] systemd[1]: Initializing machine ID from random generator.617machine # [ 7.130083] systemd[1]: bpf-restrict-fs: LSM BPF program attached618machine # [ 7.314573] systemd[1]: Applying preset policy.619machine # [ 7.801558] systemd[1]: Populated /etc with preset unit settings.620machine # [ 8.346908] systemd[1]: initrd-switch-root.service: Deactivated successfully.621machine # [ 8.348454] systemd[1]: Stopped initrd-switch-root.service.622machine # [ 8.352438] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.623machine # [ 8.355579] systemd[1]: Created slice Slice /system/getty.624machine # [ 8.357671] systemd[1]: Created slice User and Session Slice.625machine # [ 8.359266] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.626machine # [ 8.361404] systemd[1]: Started Forward Password Requests to Wall Directory Watch.627machine # [ 8.364036] systemd[1]: Expecting device /dev/hvc0...628machine # [ 8.365251] systemd[1]: Expecting device /dev/ttyAMA0...629machine # [ 8.367777] systemd[1]: Reached target Local Encrypted Volumes.630machine # [ 8.369111] systemd[1]: Stopped target initrd-fs.target.631machine # [ 8.370665] systemd[1]: Stopped target initrd-root-fs.target.632machine # [ 8.372209] systemd[1]: Stopped target initrd-switch-root.target.633machine # [ 8.374365] systemd[1]: Reached target Virtual Machines and Containers.634machine # [ 8.376448] systemd[1]: Reached target Path Units.635machine # [ 8.378362] systemd[1]: Reached target Remote File Systems.636machine # [ 8.380285] systemd[1]: Reached target Slice Units.637machine # [ 8.382409] systemd[1]: Reached target Swaps.638machine # [ 8.387860] systemd[1]: Listening on Query the User Interactively for a Password.639machine # [ 8.395770] systemd[1]: Listening on Process Core Dump Socket.640machine # [ 8.400271] systemd[1]: Listening on Credential Encryption/Decryption.641machine # [ 8.405434] systemd[1]: Listening on Factory Reset Management.642machine # [ 8.406961] systemd[1]: Listening on Hostname Service Socket.643machine # [ 8.413894] systemd[1]: Starting Journal Log Access Socket...644machine # [ 8.416874] systemd[1]: Listening on Journal Audit Socket.645machine # [ 8.422333] systemd[1]: Listening on Console Output Muting Service Socket.646machine # [ 8.424653] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.647machine # [ 8.426841] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os648machine # [ 8.429714] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki649machine # [ 8.442663] systemd[1]: Listening on Disk Repartitioning Service Socket.650machine # [ 8.445556] systemd[1]: Listening on udev Varlink Socket.651machine # [ 8.452046] systemd[1]: Mounting Huge Pages File System...652machine # [ 8.459798] systemd[1]: Mounting POSIX Message Queue File System...653machine # [ 8.468067] systemd[1]: Mounting Kernel Debug File System...654machine # [ 8.484616] systemd[1]: Mounting Kernel Trace File System...655machine # [ 8.500143] systemd[1]: Starting Create List of Static Device Nodes...656machine # [ 8.513897] systemd[1]: Starting Load Kernel Module 9pnet_virtio...657machine # [ 8.515571] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs658machine # [ 8.538262] systemd[1]: Mounting Kernel Configuration File System...659machine # [ 8.540361] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm660machine # [ 8.544333] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore661machine # [ 8.564712] systemd[1]: Starting Load Kernel Module fuse...662machine # [ 8.576703] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67663machine # [ 8.629358] systemd[1]: Starting Journal Service...664machine # [ 8.701264] systemd[1]: Starting Load Kernel Modules...665machine # [ 8.712489] fuse: init (API version 7.45)666machine # [ 8.724103] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...667machine # [ 8.755616] systemd[1]: Starting Remount Root and Kernel File Systems...668machine # [ 8.766405] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os669machine # [ 8.797359] systemd-journald[275]: Collecting audit messages is enabled.670machine # [ 8.815054] systemd[1]: Starting Coldplug All udev Devices...671machine # [ 8.804552] systemd[1]: Queued start job for default target Multi-User System.672machine # [ 8.807428] systemd[1]: systemd-journald.service: Deactivated successfully.673machine # [ 8.832768] systemd-modules-load[276]: Module 'atkbd' is built in674machine # [ 8.852490] systemd[1]: Started Journal Service.675machine # [ 8.842263] systemd-modules-load[276]: Module 'loop' is built in676machine # [ 8.850704] systemd[1]: Listening on Journal Log Access Socket.677machine # [ 8.855578] systemd[1]: Mounted Huge Pages File System.678machine # [ 8.857663] systemd[1]: Mounted POSIX Message Queue File System.679machine # [ 8.863706] systemd[1]: Mounted Kernel Debug File System.680machine # [ 8.870755] systemd[1]: Mounted Kernel Trace File System.681machine # [ 8.873106] systemd[1]: Finished Create List of Static Device Nodes.682machine # [ 8.893446] EXT4-fs (vda): re-mounted 7768744b-c166-4270-9619-55b07a67f79a.683machine # [ 8.888407] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.684machine # [ 8.889620] systemd[1]: Finished Load Kernel Module 9pnet_virtio.685machine # [ 8.890583] systemd[1]: Mounted Kernel Configuration File System.686machine # [ 8.891465] systemd[1]: modprobe@fuse.service: Deactivated successfully.687machine # [ 8.901303] systemd[1]: Finished Load Kernel Module fuse.688machine # [ 8.907955] systemd[1]: Finished Load Kernel Modules.689machine # [ 8.910849] systemd[1]: Finished Remount Root and Kernel File Systems.690machine # [ 8.928232] systemd[1]: Listening on Disk Image Download Service Socket.691machine # [ 8.940908] systemd[1]: Mounting FUSE Control File System...692machine # [ 8.947998] systemd[1]: Starting Firewall...693machine # [ 8.969201] systemd-oomd[277]: No swap; memory pressure usage will be degraded694machine # [ 8.974165] systemd[1]: Starting Flush Journal to Persistent Storage...695machine # [ 8.982610] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore696machine # [ 9.004875] systemd[1]: Starting Load/Save OS Random Seed...697machine # [ 9.030761] systemd[1]: Starting Apply Kernel Variables...698machine # [ 9.069370] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...699machine # [ 9.081121] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os700machine # [ 9.083982] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.701machine # [ 9.135595] systemd-journald[275]: Received client request to flush runtime journal.702machine # [ 9.253577] systemd[1]: Finished Load/Save OS Random Seed.703machine # [ 9.263102] systemd[1]: Reached target First Boot Complete.704machine # [ 9.280274] systemd[1]: Mounted FUSE Control File System.705machine # [ 9.281916] systemd[1]: Finished Flush Journal to Persistent Storage.706machine # [ 9.304582] systemd[1]: Finished Apply Kernel Variables.707machine # [ 9.367641] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.708machine # [ 9.376669] systemd[1]: Starting Create Static Device Nodes in /dev...709machine # [ 9.507679] systemd[1]: Finished Create Static Device Nodes in /dev.710machine # [ 9.518718] systemd[1]: Reached target Preparation for Local File Systems.711machine # [ 9.523923] systemd[1]: Mounting /run/wrappers...712machine # [ 9.533565] systemd[1]: Starting Rule-based Manager for Device Events and Files...713machine # [ 9.630017] systemd[1]: Mounted /run/wrappers.714machine # [ 9.633695] systemd[1]: Reached target Local File Systems.715machine # [ 9.648215] systemd[1]: Listening on Boot Loader Control Service Socket.716machine # [ 9.655686] systemd[1]: Starting register-nix-paths.service...717machine # [ 9.662779] systemd[1]: Starting Create SUID/SGID Wrappers...718machine # [ 9.670123] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.719machine # [ 9.718941] systemd[1]: Starting Save Transient machine-id to Disk...720machine # [ 9.732695] systemd[1]: Starting Create System Files and Directories...721machine # [ 9.756679] systemd-udevd[322]: Using default interface naming scheme 'v261'.722machine # [ 9.928694] systemd[1]: Finished Save Transient machine-id to Disk.723machine # [ 10.050402] systemd[1]: Finished Create System Files and Directories.724machine # [ 10.063856] systemd[1]: Starting Rebuild Journal Catalog...725machine # [ 10.090257] systemd[1]: Starting Record System Boot/Shutdown in UTMP...726machine # [ 10.117321] systemd[1]: Started Rule-based Manager for Device Events and Files.727machine # [ 10.307253] systemd[1]: Finished Record System Boot/Shutdown in UTMP.728machine # [ 10.332202] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.729machine # [ 10.375950] systemd[1]: Finished Rebuild Journal Catalog.730machine # [ 10.389448] systemd[1]: Starting Update is Completed...731machine # [ 10.493981] systemd[1]: Finished Update is Completed.732machine # [ 10.821136] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.733machine # [ 10.823943] systemd[1]: Finished Create SUID/SGID Wrappers.734machine # [ 11.389876] systemd[1]: Finished register-nix-paths.service.735machine # [ 11.449306] systemd[1]: Finished Coldplug All udev Devices.736machine # [ 11.451450] systemd[1]: Reached target System Initialization.737machine # [ 11.457896] systemd[1]: Started Discard unused filesystem blocks once a week.738machine # [ 11.461370] systemd[1]: Started Daily Cleanup of Temporary Directories.739machine # [ 11.464584] systemd[1]: Reached target Timer Units.740machine # [ 11.468389] systemd[1]: Listening on D-Bus System Message Bus Socket.741machine # [ 11.474732] systemd[1]: Listening on Nix Daemon Socket.742machine # [ 11.477345] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.743machine # [ 11.485304] systemd[1]: Reached target Socket Units.744machine # [ 11.488357] systemd[1]: Reached target Basic System.745machine # [ 11.490458] systemd[1]: Starting Import lastlog data into lastlog2 database...746machine # [ 11.493536] systemd[1]: Starting Name Service Cache Daemon (nsncd)...747machine # [ 11.511790] systemd[1]: Starting Post-Boot Actions...748machine # [ 11.532485] systemd[1]: Started Reset console on configuration changes.749machine # [ 11.580493] systemd[1]: Starting resolvconf update...750machine # [ 11.628208] nsncd[468]: Sep 01 03:10:48.120 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"751machine # [ 11.648695] systemd[1]: Starting D-Bus System Message Bus...752machine # [ 11.660896] systemd[1]: Started Name Service Cache Daemon (nsncd).753machine # [ 11.709743] systemd[1]: Finished Post-Boot Actions.754machine # [ 11.738793] systemd[1]: Reached target Host and Network Name Lookups.755machine # [ 11.744385] systemd[1]: Reached target User and Group Name Lookups.756machine # [ 11.754605] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs757machine # [ 11.767056] systemd[1]: Starting User Login Management...758machine # [ 11.825355] systemd[1]: Finished Import lastlog data into lastlog2 database.759machine # [ 11.868652] systemd[1]: Finished Firewall.760machine # [ 11.930586] dbus-broker-launch[479]: Looking up NSS user entry for 'systemd-timesync'...761machine # [ 11.940453] dbus-broker-launch[479]: NSS returned no entry for 'systemd-timesync'762machine # [ 11.946059] dbus-broker-launch[479]: Invalid user-name in /nix/store/wm92fkgjxj51rc2glagdzcf6qmv17jy0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"763machine # [ 11.982440] systemd-logind[500]: New seat seat0.764machine # [ 11.990683] systemd[1]: Started User Login Management.765machine # [ 12.000190] systemd[1]: Starting linger-users.service...766machine # [ 12.002721] systemd[1]: Started D-Bus System Message Bus.767machine # [ 12.059119] dbus-broker-launch[479]: Ready768machine # [ 12.062921] systemd[1]: Stopped target Host and Network Name Lookups.769machine # [ 12.069993] systemd[1]: Stopping Host and Network Name Lookups...770machine # [ 12.072045] systemd[1]: Stopped target User and Group Name Lookups.771machine # [ 12.077627] systemd[1]: Stopping User and Group Name Lookups...772machine # [ 12.081498] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...773machine # [ 12.085338] systemd[1]: nscd.service: Deactivated successfully.774machine # [ 12.091189] systemd[1]: Stopped Name Service Cache Daemon (nsncd).775machine # [ 12.124916] systemd[1]: Starting Name Service Cache Daemon (nsncd)...776machine # [ 12.220388] systemd[1]: Created slice Slice /user/1001.777machine # [ 12.237144] systemd[1]: Starting User Runtime Directory /run/user/1001...778machine # [ 12.281695] systemd[1]: linger-users.service: Deactivated successfully.779machine # [ 12.304502] systemd[1]: Finished linger-users.service.780machine # [ 12.323359] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.781machine # [ 12.342574] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.782machine # [ 12.363570] systemd[1]: Started backdoor.service.783machine # [ 12.376695] nsncd[554]: Sep 01 03:10:48.840 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"784machine # [ 12.409169] systemd[1]: Started Name Service Cache Daemon (nsncd).785machine # [ 12.440722] systemd[1]: Reached target Host and Network Name Lookups.786machine # [ 12.462572] systemd[1]: Reached target User and Group Name Lookups.787machine # [ 12.501851] systemd[1]: Finished User Runtime Directory /run/user/1001.788machine # [ 12.528083] systemd[1]: Starting User Manager for UID 1001...789machine # [ 12.613932] (udev-worker)[372]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.790machine # [ 12.676127] (udev-worker)[371]: Network interface NamePolicy= disabled on kernel command line.791machine # [ 12.700660] (systemd)[583]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)792machine # [ 12.738749] (udev-worker)[372]: Network interface NamePolicy= disabled on kernel command line.793machine # [ 12.759801] systemd[1]: Finished resolvconf update.794machine # [ 12.771636] systemd[1]: Reached target Preparation for Network.795machine # [ 12.779128] systemd[1]: Starting DHCP Client...796machine # [ 12.797550] systemd[1]: Starting Extra networking commands....797machine # [ 12.807170] systemd-logind[500]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.798machine # [ 12.824668] systemd-logind[500]: Watching system buttons on /dev/input/event0 (gpio-keys)799machine # [ 12.877911] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy800machine # connecting to host...801machine: Guest shell says: b'Spawning backdoor root shell...\n'802machine: connected to guest root shell803machine: (connecting took 13.32 seconds)804machine: (finished: waiting for the VM to finish booting, in 13.91 seconds)805machine # [ 13.055716] systemd[1]: Condition check resulted in Virtio network device being skipped.806machine # [ 13.075763] systemd[1]: Starting Address configuration of eth1...807machine # [ 13.169217] systemd[583]: Queued start job for default target Main User Target.808machine # [ 13.196908] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy809machine # [ 13.198667] systemd[583]: Created slice User Application Slice.810machine # [ 13.199509] systemd[583]: Started Daily Cleanup of User's Temporary Directories.811machine # [ 13.252901] systemd[583]: Reached target Paths.812machine # [ 13.253746] systemd[583]: Reached target Timers.813machine # [ 13.301397] systemd[583]: Starting D-Bus User Message Bus Socket...814machine # [ 13.324913] systemd[583]: Starting Create User Files and Directories...815machine # [ 13.342561] systemd[583]: Finished Create User Files and Directories.816machine # [ 13.397650] systemd[583]: Listening on D-Bus User Message Bus Socket.817machine # [ 13.434717] systemd[583]: Reached target Sockets.818machine # [ 13.458431] systemd[583]: Reached target Basic System.819machine # [ 13.499072] systemd[583]: Starting Run user-specific NixOS activation...820machine # [ 13.520424] systemd[583]: Finished Run user-specific NixOS activation.821machine # [ 13.546001] systemd[583]: Reached target Main User Target.822machine # [ 13.547317] network-addresses-eth1-start[602]: adding address 192.168.1.1/24... done823machine # [ 13.571819] mousedev: PS/2 mouse device common for all mice824machine # [ 13.589566] systemd[583]: Startup finished in 728ms.825machine # [ 13.603538] systemd[1]: Started User Manager for UID 1001.826machine # [ 13.641084] network-addresses-eth1-start[602]: adding address 2001:db8:1::1/64... done827machine # [ 13.659057] systemd-logind[500]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)828machine # [ 13.718872] systemd[1]: Finished Address configuration of eth1.829machine # [ 13.751498] dhcpcd[607]: dhcpcd-10.3.2 starting830machine # [ 13.808991] dhcpcd[684]: dev: loaded udev831machine # [ 13.883643] 8021q: 802.1Q VLAN Support v1.8832machine # [ 13.884068] 8021q: adding VLAN 0 to HW filter on device eth1833machine # [ 13.989874] cfg80211: Loading compiled-in X.509 certificates for regulatory database834machine # [ 14.032090] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'835machine # [ 14.032637] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'836machine # [ 14.038535] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2837machine # [ 14.038900] cfg80211: failed to load regulatory.db838machine # [ 14.090777] systemd[1]: Finished Extra networking commands..839machine # [ 14.091742] systemd[1]: Reached target Network.840machine # [ 14.113932] systemd[1]: Starting Permit User Sessions...841machine # [ 14.375023] systemd[1]: Finished Permit User Sessions.842machine # [ 14.391108] systemd[1]: Started Getty on tty1.843machine # [ 14.391868] systemd[1]: Reached target Login Prompts.844machine # [ 14.597575] 8021q: adding VLAN 0 to HW filter on device eth0845machine # [ 14.585734] dhcpcd[684]: eth0: waiting for carrier846machine # [ 14.593192] dhcpcd[684]: libudev: received NULL device847machine # [ 14.599859] dhcpcd[684]: libudev: received NULL device848machine # [ 14.609375] dhcpcd[684]: eth0: carrier acquired849machine # [ 14.663251] dhcpcd[684]: DUID 00:01:00:01:32:28:fe:bb:52:54:00:12:34:56850machine # [ 14.671178] dhcpcd[684]: eth0: IAID 00:12:34:56851machine # [ 14.671944] dhcpcd[684]: eth0: adding address fe80::5054:ff:fe12:3456852machine # [ 14.683720] dhcpcd[684]: eth0: soliciting a DHCP lease853machine # [ 14.690021] dhcpcd[684]: eth0: offered 10.0.2.15 from 10.0.2.2854machine # [ 14.690983] dhcpcd[684]: eth0: probing address 10.0.2.15/24855machine # [ 14.882436] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3856machine # [ 15.285601] systemd[1]: Starting Virtual Console Setup...857machine # [ 15.324469] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.858machine # [ 15.366234] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.859machine # [ 15.369938] systemd[1]: Stopped Virtual Console Setup.860machine # [ 15.371375] systemd[1]: Starting Virtual Console Setup...861machine # [ 15.381405] systemd-logind[500]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)862machine # [ 15.888505] systemd-vconsole-setup[724]: Configuration of first virtual console was skipped, ignoring remaining ones.863machine # [ 15.896417] systemd[1]: Finished Virtual Console Setup.864machine # [ 16.933141] dhcpcd[684]: eth0: soliciting an IPv6 router865machine # [ 16.937142] dhcpcd[684]: eth0: Router Advertisement from fe80::2866machine # [ 16.939936] dhcpcd[684]: eth0: adding address fec0::5054:ff:fe12:3456/64867machine # [ 16.943234] dhcpcd[684]: eth0: adding route to fec0::/64868machine # [ 16.945765] dhcpcd[684]: eth0: adding default route via fe80::2869machine # [ 20.293798] dhcpcd[684]: eth0: leased 10.0.2.15 for 86400 seconds870machine # [ 20.298710] dhcpcd[684]: eth0: adding route to 10.0.2.0/24871machine # [ 20.302497] dhcpcd[684]: eth0: adding default route via 10.0.2.2872machine # [ 20.499155] systemd[1]: Started DHCP Client.873machine # [ 20.501753] systemd[1]: Reached target Multi-User System.874machine # [ 20.502810] systemd[1]: Startup finished in 1.079s (kernel) + 5.326s (initrd) + 14.095s (userspace) = 20.501s.875machine: (finished: waiting for unit multi-user.target, in 21.90 seconds)876machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test877machine # Checking switch inhibitors... done878machine # [ 21.641211] nixos[832]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test879machine # activating the configuration...880machine # [ 21.646061] systemd[1]: Stopped target Remote File Systems.881machine # [ 21.647322] systemd[1]: Stopped target Local File Systems.882machine # setting up /etc...883machine # [ 22.173983] systemd[1]: Reload requested from client PID 832 ('.switch-to-conf') (unit backdoor.service)...884machine # [ 22.176103] systemd[1]: Reloading...885machine # [ 23.237498] systemd[1]: Reloading finished in 1057 ms.886machine # reloading user units for usertest...887machine # [ 23.292473] systemd[583]: Created slice User Core Session Slice.888machine # [ 23.296478] systemd[583]: Starting D-Bus User Message Bus...889machine # [ 23.371168] dbus-broker-launch[902]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/wm92fkgjxj51rc2glagdzcf6qmv17jy0-system-path/share/dbus-1/services/org.freedesktop.import1.service'890machine # [ 23.374372] dbus-broker-launch[902]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/wm92fkgjxj51rc2glagdzcf6qmv17jy0-system-path/share/dbus-1/services/org.freedesktop.machine1.service'891machine # [ 23.377670] dbus-broker-launch[902]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/wm92fkgjxj51rc2glagdzcf6qmv17jy0-system-path/share/dbus-1/services/org.freedesktop.portable1.service'892machine # [ 23.381152] dbus-broker-launch[902]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/wm92fkgjxj51rc2glagdzcf6qmv17jy0-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'893machine # [ 23.385268] dbus-broker-launch[902]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored894machine # [ 23.388421] dbus-broker-launch[902]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored895machine # [ 23.390594] systemd[583]: Started D-Bus User Message Bus.896machine # [ 23.399224] dbus-broker-launch[902]: Ready897machine # [ 23.509321] systemd[583]: Reexecution requested from client PID 901 ('switch-to-confi')...898machine # [ 23.513048] systemd[583]: Reexecuting.899machine # [ 23.574726] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy900machine # [ 23.783197] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy901machine # [ 23.791442] systemd[583]: Reload requested from client PID 901 ('switch-to-confi')...902machine # [ 23.794388] systemd[583]: Reloading...903machine # [ 23.998754] systemd[583]: Reloading finished in 203 ms.904machine # [ 24.013727] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy905machine # restarting the following user units: nixos-activation.service906machine # [ 24.024078] systemd[583]: Stopped Run user-specific NixOS activation.907machine # [ 24.025112] systemd[583]: Stopping Run user-specific NixOS activation...908machine # [ 24.028500] systemd[583]: Starting Run user-specific NixOS activation...909machine # [ 24.096322] systemd[583]: Finished Run user-specific NixOS activation.910machine # restarting sysinit-reactivation.target911machine # [ 24.117446] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....912machine # [ 24.246848] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..913machine # [ 24.249547] systemd[1]: Reached target Reactivate sysinit units.914machine # [ 24.252747] systemd[1]: Reached target Local File Systems.915machine # [ 24.253609] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore916machine # [ 24.261397] systemd[1]: Starting Create SUID/SGID Wrappers...917machine # [ 24.262398] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.918machine # [ 24.263602] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67919machine # [ 24.269563] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore920machine # [ 24.272589] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os921machine # [ 24.280521] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os922machine # [ 24.281825] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os923machine # [ 24.283091] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki924machine # [ 24.290197] systemd[1]: Reached target Remote File Systems.925machine # [ 24.662808] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.926machine # [ 24.668253] systemd[1]: Finished Create SUID/SGID Wrappers.927machine # [ 24.671292] systemd[1]: Starting linger-users.service...928machine # [ 24.739704] systemd[1]: linger-users.service: Deactivated successfully.929machine # [ 24.743264] systemd[1]: Finished linger-users.service.930machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service931machine # [ 25.000765] nixos[832]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test932machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test, in 4.03 seconds)933machine: must succeed: rm /etc/NIXOS934machine: (finished: must succeed: rm /etc/NIXOS, in 0.05 seconds)935machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/hqw8f8yyb0g9dij3s0k5akh8rx3bbqh4-nixos-system-other-test/bin/switch-to-configuration test936machine # this should succeed (config: /nix/store/hqw8f8yyb0g9dij3s0k5akh8rx3bbqh4-nixos-system-other-test, action: test)937machine # Checking switch inhibitors... done938machine # [ 25.720332] nixos[980]: switching to system configuration /nix/store/hqw8f8yyb0g9dij3s0k5akh8rx3bbqh4-nixos-system-other-test939machine # [ 25.723331] systemd[1]: Stopped target Remote File Systems.940machine # activating the configuration...941machine # [ 25.726385] systemd[1]: Stopped target Local File Systems.942machine # removing user ‘usertest’943machine # setting up /etc...944machine # [ 26.320467] systemd[1]: Reload requested from client PID 980 ('.switch-to-conf') (unit backdoor.service)...945machine # [ 26.322555] systemd[1]: Reloading...946machine # [ 27.518363] systemd[1]: Reloading finished in 1192 ms.947machine # reloading user units for usertest...948machine # [ 27.693152] systemd[583]: Reexecution requested from client PID 1051 ('switch-to-confi')...949machine # [ 27.697521] systemd[583]: Reexecuting.950machine # [ 27.762298] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy951machine # [ 27.966348] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy952machine # [ 27.973454] systemd[583]: Reload requested from client PID 1051 ('switch-to-confi')...953machine # [ 27.975794] systemd[583]: Reloading...954machine # [ 28.172878] systemd[583]: Reloading finished in 195 ms.955machine # [ 28.188249] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy956machine # restarting the following user units: nixos-activation.service957machine # [ 28.199832] systemd[583]: Stopped Run user-specific NixOS activation.958machine # [ 28.200952] systemd[583]: Stopping Run user-specific NixOS activation...959machine # [ 28.204327] systemd[583]: Starting Run user-specific NixOS activation...960machine # [ 28.257627] systemd[583]: Finished Run user-specific NixOS activation.961machine # restarting sysinit-reactivation.target962machine # [ 28.262417] systemd[1]: Stopped target Reactivate sysinit units.963machine # [ 28.264314] systemd[1]: Stopping Reactivate sysinit units...964machine # [ 28.265140] systemd[1]: Reached target Reactivate sysinit units.965machine # reloading the following units: dbus-broker.service966machine # [ 28.268462] systemd[1]: Reloading D-Bus System Message Bus...967machine # [ 28.269643] dbus-broker-launch[479]: Caught SIGHUP, trigger reload.968machine # [ 28.290843] dbus-broker-launch[479]: Looking up NSS user entry for 'systemd-timesync'...969machine # [ 28.293186] dbus-broker-launch[479]: NSS returned no entry for 'systemd-timesync'970machine # [ 28.294463] dbus-broker-launch[479]: Invalid user-name in /nix/store/2dv32al6b55cd2v2cji73v467zc5wb0q-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"971machine # [ 28.330271] systemd[1]: Reloaded D-Bus System Message Bus.972machine # restarting the following units: network-addresses-eth1.service973machine # [ 28.337840] systemd[1]: Stopping Address configuration of eth1...974machine # [ 28.386374] network-addresses-eth1-pre-stop[1064]: deleting address 192.168.1.1/24... done975machine # [ 28.398020] network-addresses-eth1-pre-stop[1064]: deleting address 2001:db8:1::1/64... done976machine # [ 28.408280] systemd[1]: network-addresses-eth1.service: Deactivated successfully.977machine # [ 28.412305] systemd[1]: Stopped Address configuration of eth1.978machine # [ 28.415380] systemd[1]: Starting Address configuration of eth1...979machine # [ 28.490250] network-addresses-eth1-start[1069]: adding address 192.168.1.2/24... done980machine # [ 28.502323] network-addresses-eth1-start[1069]: adding address 2001:db8:1::2/64... done981machine # [ 28.518488] systemd[1]: Finished Address configuration of eth1.982machine # [ 28.521214] systemd[1]: Reached target Remote File Systems.983machine # [ 28.524472] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os984machine # [ 28.528623] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki985machine # [ 28.533694] systemd[1]: Starting linger-users.service...986machine # [ 28.538591] systemd[1]: Reached target Local File Systems.987machine # [ 28.550091] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore988machine # [ 28.555048] systemd[1]: Starting Create SUID/SGID Wrappers...989machine # [ 28.556371] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.990machine # [ 28.557767] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67991machine # [ 28.559904] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore992machine # [ 28.568069] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os993machine # [ 28.570232] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os994machine # [ 28.613744] linger-users-start[1083]: id: ‘usertest’: no such user995machine # [ 28.615905] linger-users-start[1080]: Removing linger for missing user usertest996machine # [ 28.627580] systemd[1]: linger-users.service: Deactivated successfully.997machine # [ 28.631982] systemd[1]: Finished linger-users.service.998machine # [ 28.962460] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.999machine # [ 28.965233] systemd[1]: Finished Create SUID/SGID Wrappers.1000machine # [ 29.224222] nixos[980]: finished switching to system configuration /nix/store/hqw8f8yyb0g9dij3s0k5akh8rx3bbqh4-nixos-system-other-test1001machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/hqw8f8yyb0g9dij3s0k5akh8rx3bbqh4-nixos-system-other-test/bin/switch-to-configuration test, in 4.18 seconds)1002machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&11003machine # [ 29.801388] nixos[1147]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test1004machine # [ 29.804189] systemd[1]: Stopped target Remote File Systems.1005machine # [ 29.806485] systemd[1]: Stopped target Local File Systems.1006machine # [ 30.279829] systemd[1]: Reload requested from client PID 1147 ('.switch-to-conf') (unit backdoor.service)...1007machine # [ 30.281671] systemd[1]: Reloading...1008machine # [ 32.478440] systemd[1]: Reloading finished in 2194 ms.1009machine # [ 32.655942] systemd[583]: Reexecution requested from client PID 1216 ('switch-to-confi')...1010machine # [ 32.658576] systemd[583]: Reexecuting.1011machine # [ 32.727648] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1012machine # [ 32.934315] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1013machine # [ 32.941067] systemd[583]: Reload requested from client PID 1216 ('switch-to-confi')...1014machine # [ 32.943362] systemd[583]: Reloading...1015machine # [ 33.148183] systemd[583]: Reloading finished in 203 ms.1016machine # [ 33.168511] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1017machine # [ 33.180381] systemd[583]: Stopped Run user-specific NixOS activation.1018machine # [ 33.183277] systemd[583]: Stopping Run user-specific NixOS activation...1019machine # [ 33.186725] systemd[583]: Starting Run user-specific NixOS activation...1020machine # [ 33.248993] systemd[583]: Finished Run user-specific NixOS activation.1021machine # [ 33.254198] systemd[1]: Stopped target Reactivate sysinit units.1022machine # [ 33.259617] systemd[1]: Stopping Reactivate sysinit units...1023machine # [ 33.261483] systemd[1]: Reached target Reactivate sysinit units.1024machine # [ 33.263234] dbus-broker-launch[479]: Caught SIGHUP, trigger reload.1025machine # [ 33.264888] systemd[1]: Reloading D-Bus System Message Bus...1026machine # [ 33.273094] dbus-broker-launch[479]: Looking up NSS user entry for 'systemd-timesync'...1027machine # [ 33.275898] dbus-broker-launch[479]: NSS returned no entry for 'systemd-timesync'1028machine # [ 33.277569] dbus-broker-launch[479]: Invalid user-name in /nix/store/wm92fkgjxj51rc2glagdzcf6qmv17jy0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1029machine # [ 33.301663] systemd[1]: Reloaded D-Bus System Message Bus.1030machine # [ 33.309706] systemd[1]: Stopping Address configuration of eth1...1031machine # [ 33.365324] network-addresses-eth1-pre-stop[1229]: deleting address 192.168.1.2/24... done1032machine # [ 33.377204] network-addresses-eth1-pre-stop[1229]: deleting address 2001:db8:1::2/64... done1033machine # [ 33.387886] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1034machine # [ 33.389986] systemd[1]: Stopped Address configuration of eth1.1035machine # [ 33.395683] systemd[1]: Starting Address configuration of eth1...1036machine # [ 33.484979] network-addresses-eth1-start[1234]: adding address 192.168.1.1/24... done1037machine # [ 33.498628] network-addresses-eth1-start[1234]: adding address 2001:db8:1::1/64... done1038machine # [ 33.518769] systemd[1]: Finished Address configuration of eth1.1039machine # [ 33.523402] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1040machine # [ 33.527678] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1041machine # [ 33.532276] systemd[1]: Reached target Local File Systems.1042machine # [ 33.537741] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1043machine # [ 33.546828] systemd[1]: Starting Create SUID/SGID Wrappers...1044machine # [ 33.547888] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1045machine # [ 33.550989] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671046machine # [ 33.561598] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1047machine # [ 33.563494] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1048machine # [ 33.566461] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1049machine # [ 33.567639] systemd[1]: Reached target Remote File Systems.1050machine # [ 33.957262] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1051machine # [ 33.963159] systemd[1]: Finished Create SUID/SGID Wrappers.1052machine # [ 33.967107] systemd[1]: Starting linger-users.service...1053machine # [ 34.050130] systemd[1]: linger-users.service: Deactivated successfully.1054machine # [ 34.054358] systemd[1]: Finished linger-users.service.1055machine # [ 34.311391] nixos[1147]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test1056machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 5.08 seconds)1057subtest: user services1058machine: waiting for unit user@1001.service1059machine: (finished: waiting for unit user@1001.service, in 0.26 seconds)1060machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11061machine # [ 35.225543] nixos[1308]: switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test1062machine # [ 35.229566] systemd[1]: Stopped target Local File Systems.1063machine # [ 35.231219] systemd[1]: Stopped target Remote File Systems.1064machine # [ 35.861035] systemd[1]: Reload requested from client PID 1308 ('.switch-to-conf') (unit backdoor.service)...1065machine # [ 35.861585] systemd[1]: Reloading...1066machine # [ 37.570225] systemd[1]: Reloading finished in 1703 ms.1067machine # [ 37.782258] systemd[583]: Reexecution requested from client PID 1377 ('switch-to-confi')...1068machine # [ 37.783613] systemd[583]: Reexecuting.1069machine # [ 37.850751] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1070machine # [ 38.081451] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1071machine # [ 38.088510] systemd[583]: Reload requested from client PID 1377 ('switch-to-confi')...1072machine # [ 38.090921] systemd[583]: Reloading...1073machine # [ 38.318300] systemd[583]: Reloading finished in 225 ms.1074machine # [ 38.332335] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1075machine # [ 38.345578] systemd[583]: Starting usertest.service...1076machine # [ 38.386581] systemd[583]: Finished usertest.service.1077machine # [ 38.389820] systemd[583]: Stopped Run user-specific NixOS activation.1078machine # [ 38.392195] systemd[583]: Stopping Run user-specific NixOS activation...1079machine # [ 38.395577] systemd[583]: Starting Run user-specific NixOS activation...1080machine # [ 38.448669] systemd[583]: Finished Run user-specific NixOS activation.1081machine # [ 38.453201] systemd[1]: Stopped target Reactivate sysinit units.1082machine # [ 38.455422] systemd[1]: Stopping Reactivate sysinit units...1083machine # [ 38.457270] systemd[1]: Reached target Reactivate sysinit units.1084machine # [ 38.459489] systemd[1]: Reached target Local File Systems.1085machine # [ 38.462863] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1086machine # [ 38.471255] systemd[1]: Starting Create SUID/SGID Wrappers...1087machine # [ 38.473428] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1088machine # [ 38.475467] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671089machine # [ 38.484541] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1090machine # [ 38.486203] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1091machine # [ 38.487328] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1092machine # [ 38.493696] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1093machine # [ 38.495071] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1094machine # [ 38.496282] systemd[1]: Reached target Remote File Systems.1095machine # [ 38.848992] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1096machine # [ 38.852498] systemd[1]: Finished Create SUID/SGID Wrappers.1097machine # [ 38.856822] systemd[1]: Starting linger-users.service...1098machine # [ 38.925105] systemd[1]: linger-users.service: Deactivated successfully.1099machine # [ 38.927810] systemd[1]: Finished linger-users.service.1100machine # [ 39.187030] nixos[1308]: finished switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test1101machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 4.61 seconds)1102machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1103machine # [ 39.307956] sudo[1448]: 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 # [ 39.326470] sudo[1448]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1105machine # [ 39.341862] sudo[1448]: 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.15 seconds)1107machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11108machine # [ 40.018578] nixos[1457]: switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test1109machine # [ 40.022314] systemd[1]: Stopped target Local File Systems.1110machine # [ 40.023796] systemd[1]: Stopped target Remote File Systems.1111machine # [ 40.582349] systemd[1]: Reload requested from client PID 1457 ('.switch-to-conf') (unit backdoor.service)...1112machine # [ 40.584518] systemd[1]: Reloading...1113machine # [ 42.083814] systemd[1]: Reloading finished in 1496 ms.1114machine # [ 42.291125] systemd[583]: Reexecution requested from client PID 1526 ('switch-to-confi')...1115machine # [ 42.295344] systemd[583]: Reexecuting.1116machine # [ 42.363310] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1117machine # [ 42.591834] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1118machine # [ 42.601504] systemd[583]: Reload requested from client PID 1526 ('switch-to-confi')...1119machine # [ 42.604053] systemd[583]: Reloading...1120machine # [ 42.825654] systemd[583]: Reloading finished in 220 ms.1121machine # [ 42.840187] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1122machine # [ 42.851288] systemd[583]: Stopped Run user-specific NixOS activation.1123machine # [ 42.852426] systemd[583]: Stopping Run user-specific NixOS activation...1124machine # [ 42.855780] systemd[583]: Starting Run user-specific NixOS activation...1125machine # [ 42.908875] systemd[583]: Finished Run user-specific NixOS activation.1126machine # [ 42.913642] systemd[1]: Stopped target Reactivate sysinit units.1127machine # [ 42.915755] systemd[1]: Stopping Reactivate sysinit units...1128machine # [ 42.917881] systemd[1]: Reached target Reactivate sysinit units.1129machine # [ 42.919630] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1130machine # [ 42.923347] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1131machine # [ 42.926771] systemd[1]: Reached target Remote File Systems.1132machine # [ 42.929556] systemd[1]: Reached target Local File Systems.1133machine # [ 42.939374] systemd[1]: Starting linger-users.service...1134machine # [ 42.960308] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1135machine # [ 42.967270] systemd[1]: Starting Create SUID/SGID Wrappers...1136machine # [ 42.969424] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1137machine # [ 42.972734] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671138machine # [ 42.976188] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1139machine # [ 42.977844] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1140machine # [ 42.979124] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1141machine # [ 43.062399] systemd[1]: linger-users.service: Deactivated successfully.1142machine # [ 43.063511] systemd[1]: Finished linger-users.service.1143machine # [ 43.405139] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1144machine # [ 43.409496] systemd[1]: Finished Create SUID/SGID Wrappers.1145machine # [ 43.668213] nixos[1457]: finished switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test1146machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 4.33 seconds)1147machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&11148machine # [ 44.259432] nixos[1598]: switching to system configuration /nix/store/62qs5zz4zk9549sivlzmakp3bk7lr62s-nixos-system-machine-test1149machine # [ 44.263345] systemd[1]: Stopped target Local File Systems.1150machine # [ 44.264915] systemd[1]: Stopped target Remote File Systems.1151machine # [ 44.826753] systemd[1]: Reload requested from client PID 1598 ('.switch-to-conf') (unit backdoor.service)...1152machine # [ 44.829022] systemd[1]: Reloading...1153machine # [ 45.809619] systemd[1]: Reloading finished in 978 ms.1154machine # [ 45.973337] systemd[583]: Stopped usertest.service.1155machine # [ 45.977401] systemd[583]: Reexecution requested from client PID 1668 ('switch-to-confi')...1156machine # [ 45.978853] systemd[583]: Reexecuting.1157machine # [ 46.033809] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1158machine # [ 46.241137] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1159machine # [ 46.249114] systemd[583]: Reload requested from client PID 1668 ('switch-to-confi')...1160machine # [ 46.252219] systemd[583]: Reloading...1161machine # [ 46.476842] systemd[583]: Reloading finished in 224 ms.1162machine # [ 46.498202] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1163machine # [ 46.509780] systemd[583]: Starting usertest.service...1164machine # [ 46.553112] systemd[583]: Finished usertest.service.1165machine # [ 46.556612] systemd[583]: Stopped Run user-specific NixOS activation.1166machine # [ 46.559510] systemd[583]: Stopping Run user-specific NixOS activation...1167machine # [ 46.562892] systemd[583]: Starting Run user-specific NixOS activation...1168machine # [ 46.621807] systemd[583]: Finished Run user-specific NixOS activation.1169machine # [ 46.627879] systemd[1]: Stopped target Reactivate sysinit units.1170machine # [ 46.631071] systemd[1]: Stopping Reactivate sysinit units...1171machine # [ 46.632690] systemd[1]: Reached target Reactivate sysinit units.1172machine # [ 46.636354] systemd[1]: Reached target Remote File Systems.1173machine # [ 46.642284] systemd[1]: Starting linger-users.service...1174machine # [ 46.646257] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1175machine # [ 46.647656] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1176machine # [ 46.664183] systemd[1]: Reached target Local File Systems.1177machine # [ 46.665346] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1178machine # [ 46.671645] systemd[1]: Starting Create SUID/SGID Wrappers...1179machine # [ 46.675661] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1180machine # [ 46.684897] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671181machine # [ 46.691617] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1182machine # [ 46.697636] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1183machine # [ 46.699737] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1184machine # [ 46.786545] systemd[1]: linger-users.service: Deactivated successfully.1185machine # [ 46.787636] systemd[1]: Finished linger-users.service.1186machine # [ 47.103385] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1187machine # [ 47.106398] systemd[1]: Finished Create SUID/SGID Wrappers.1188machine # [ 47.376607] nixos[1598]: finished switching to system configuration /nix/store/62qs5zz4zk9549sivlzmakp3bk7lr62s-nixos-system-machine-test1189machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 3.71 seconds)1190machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1191machine # [ 47.499124] sudo[1738]: 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 # [ 47.520511] sudo[1738]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1193machine # [ 47.537737] sudo[1738]: 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.15 seconds)1195machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&11196machine # [ 48.168493] nixos[1748]: switching to system configuration /nix/store/qxm4majszi2bsddayzqxh2z61a65mwyl-nixos-system-machine-test1197machine # [ 48.172451] systemd[1]: Stopped target Local File Systems.1198machine # [ 48.174306] systemd[1]: Stopped target Remote File Systems.1199machine # [ 48.831235] systemd[1]: Reload requested from client PID 1748 ('.switch-to-conf') (unit backdoor.service)...1200machine # [ 48.831949] systemd[1]: Reloading...1201machine # [ 49.562768] systemd[1]: Reloading finished in 726 ms.1202machine # [ 49.758593] systemd[583]: Reexecution requested from client PID 1816 ('switch-to-confi')...1203machine # [ 49.761111] systemd[583]: Reexecuting.1204machine # [ 49.831868] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1205machine # [ 50.090441] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1206machine # [ 50.098279] systemd[583]: Reload requested from client PID 1816 ('switch-to-confi')...1207machine # [ 50.101062] systemd[583]: Reloading...1208machine # [ 50.305953] systemd[583]: Reloading finished in 203 ms.1209machine # [ 50.324197] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1210machine # [ 50.332082] systemd[583]: Stopped usertest.service.1211machine # [ 50.334340] systemd[583]: Stopping usertest.service...1212machine # [ 50.337263] systemd[583]: Starting usertest.service...1213machine # [ 50.377001] systemd[583]: Finished usertest.service.1214machine # [ 50.382106] systemd[583]: Stopped Run user-specific NixOS activation.1215machine # [ 50.384311] systemd[583]: Stopping Run user-specific NixOS activation...1216machine # [ 50.387598] systemd[583]: Starting Run user-specific NixOS activation...1217machine # [ 50.434956] systemd[583]: Finished Run user-specific NixOS activation.1218machine # [ 50.439836] systemd[1]: Stopped target Reactivate sysinit units.1219machine # [ 50.442960] systemd[1]: Stopping Reactivate sysinit units...1220machine # [ 50.443773] systemd[1]: Reached target Reactivate sysinit units.1221machine # [ 50.446366] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1222machine # [ 50.449305] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1223machine # [ 50.452115] systemd[1]: Reached target Remote File Systems.1224machine # [ 50.457757] systemd[1]: Starting linger-users.service...1225machine # [ 50.460896] systemd[1]: Reached target Local File Systems.1226machine # [ 50.473278] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1227machine # [ 50.481826] systemd[1]: Starting Create SUID/SGID Wrappers...1228machine # [ 50.482820] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1229machine # [ 50.483977] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671230machine # [ 50.486871] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1231machine # [ 50.489370] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1232machine # [ 50.491114] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1233machine # [ 50.560872] systemd[1]: linger-users.service: Deactivated successfully.1234machine # [ 50.566116] systemd[1]: Finished linger-users.service.1235machine # [ 50.854680] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1236machine # [ 50.859603] systemd[1]: Finished Create SUID/SGID Wrappers.1237machine # [ 51.117404] nixos[1748]: finished switching to system configuration /nix/store/qxm4majszi2bsddayzqxh2z61a65mwyl-nixos-system-machine-test1238machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 3.59 seconds)1239machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1240machine # [ 51.207469] sudo[1887]: 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 # [ 51.225984] sudo[1887]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1242machine # [ 51.241326] sudo[1887]: 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.11 seconds)1244machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&11245machine # [ 51.852364] nixos[1896]: switching to system configuration /nix/store/88zw7imk4m3mgvy637694cq9mj06id90-nixos-system-machine-test1246machine # [ 51.855366] systemd[1]: Stopped target Local File Systems.1247machine # [ 51.857977] systemd[1]: Stopped target Remote File Systems.1248machine # [ 52.435436] systemd[1]: Reload requested from client PID 1896 ('.switch-to-conf') (unit backdoor.service)...1249machine # [ 52.437813] systemd[1]: Reloading...1250machine # [ 53.081258] systemd[1]: Reloading finished in 640 ms.1251machine # [ 53.255366] systemd[583]: Reexecution requested from client PID 1965 ('switch-to-confi')...1252machine # [ 53.257658] systemd[583]: Reexecuting.1253machine # [ 53.322018] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1254machine # [ 53.534487] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1255machine # [ 53.541027] systemd[583]: Reload requested from client PID 1965 ('switch-to-confi')...1256machine # [ 53.543493] systemd[583]: Reloading...1257machine # [ 53.746538] systemd[583]: Reloading finished in 201 ms.1258machine # [ 53.768216] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1259machine # [ 53.778364] systemd[583]: Reloading usertest.service...1260machine # [ 53.817483] systemd[583]: Reloaded usertest.service.1261machine # [ 53.823078] systemd[583]: Stopped Run user-specific NixOS activation.1262machine # [ 53.825977] systemd[583]: Stopping Run user-specific NixOS activation...1263machine # [ 53.829084] systemd[583]: Starting Run user-specific NixOS activation...1264machine # [ 53.875386] systemd[583]: Finished Run user-specific NixOS activation.1265machine # [ 53.880441] systemd[1]: Stopped target Reactivate sysinit units.1266machine # [ 53.881929] systemd[1]: Stopping Reactivate sysinit units...1267machine # [ 53.882790] systemd[1]: Reached target Reactivate sysinit units.1268machine # [ 53.889115] systemd[1]: Reached target Local File Systems.1269machine # [ 53.892616] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1270machine # [ 53.897704] systemd[1]: Starting Create SUID/SGID Wrappers...1271machine # [ 53.898676] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1272machine # [ 53.899877] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671273machine # [ 53.904317] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1274machine # [ 53.906334] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1275machine # [ 53.918229] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1276machine # [ 53.919512] systemd[1]: Reached target Remote File Systems.1277machine # [ 53.921344] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1278machine # [ 53.922702] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1279machine # [ 54.246990] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1280machine # [ 54.251418] systemd[1]: Finished Create SUID/SGID Wrappers.1281machine # [ 54.254430] systemd[1]: Starting linger-users.service...1282machine # [ 54.316964] systemd[1]: linger-users.service: Deactivated successfully.1283machine # [ 54.319320] systemd[1]: Finished linger-users.service.1284machine # [ 54.579227] nixos[1896]: finished switching to system configuration /nix/store/88zw7imk4m3mgvy637694cq9mj06id90-nixos-system-machine-test1285machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 3.34 seconds)1286machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1287machine # [ 54.664723] sudo[2035]: 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 # [ 54.686379] sudo[2035]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1289machine # [ 54.706107] sudo[2035]: 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.12 seconds)1291machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11292machine # [ 55.238072] nixos[2044]: switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test1293machine # [ 55.240616] systemd[1]: Stopped target Local File Systems.1294machine # [ 55.242796] systemd[1]: Stopped target Remote File Systems.1295machine # [ 55.664247] systemd[1]: Reload requested from client PID 2044 ('.switch-to-conf') (unit backdoor.service)...1296machine # [ 55.666316] systemd[1]: Reloading...1297machine # [ 56.238382] systemd[1]: Reloading finished in 570 ms.1298machine # [ 56.387737] systemd[583]: Stopped usertest.service.1299machine # [ 56.390903] systemd[583]: Reexecution requested from client PID 2113 ('switch-to-confi')...1300machine # [ 56.392898] systemd[583]: Reexecuting.1301machine # [ 56.457837] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1302machine # [ 56.646593] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1303machine # [ 56.653907] systemd[583]: Reload requested from client PID 2113 ('switch-to-confi')...1304machine # [ 56.656512] systemd[583]: Reloading...1305machine # [ 56.839990] systemd[583]: Reloading finished in 182 ms.1306machine # [ 56.856178] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1307machine # [ 56.867148] systemd[583]: Starting usertest.service...1308machine # [ 56.902177] systemd[583]: Finished usertest.service.1309machine # [ 56.905331] systemd[583]: Stopped Run user-specific NixOS activation.1310machine # [ 56.907539] systemd[583]: Stopping Run user-specific NixOS activation...1311machine # [ 56.910277] systemd[583]: Starting Run user-specific NixOS activation...1312machine # [ 56.951440] systemd[583]: Finished Run user-specific NixOS activation.1313machine # [ 56.955768] systemd[1]: Stopped target Reactivate sysinit units.1314machine # [ 56.958411] systemd[1]: Stopping Reactivate sysinit units...1315machine # [ 56.959243] systemd[1]: Reached target Reactivate sysinit units.1316machine # [ 56.963507] systemd[1]: Reached target Remote File Systems.1317machine # [ 56.966158] systemd[1]: Starting linger-users.service...1318machine # [ 56.978051] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1319machine # [ 56.985327] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1320machine # [ 56.986669] systemd[1]: Reached target Local File Systems.1321machine # [ 56.987449] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1322machine # [ 56.995293] systemd[1]: Starting Create SUID/SGID Wrappers...1323machine # [ 56.997591] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1324machine # [ 56.999274] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671325machine # [ 57.002113] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1326machine # [ 57.003984] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1327machine # [ 57.008247] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1328machine # [ 57.062920] systemd[1]: linger-users.service: Deactivated successfully.1329machine # [ 57.066373] systemd[1]: Finished linger-users.service.1330machine # [ 57.324956] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1331machine # [ 57.328318] systemd[1]: Finished Create SUID/SGID Wrappers.1332machine # [ 57.587236] nixos[2044]: finished switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test1333machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.89 seconds)1334machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1335machine # [ 57.671381] sudo[2183]: 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 # [ 57.690570] sudo[2183]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1337machine # [ 57.705530] sudo[2183]: 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.11 seconds)1339machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&11340machine # [ 58.231276] nixos[2192]: switching to system configuration /nix/store/6xm94nc2dfb5b0gwa60rq68ryj7c6s5f-nixos-system-machine-test1341machine # [ 58.234308] systemd[1]: Stopped target Remote File Systems.1342machine # [ 58.236908] systemd[1]: Stopped target Local File Systems.1343machine # [ 58.755738] systemd[1]: Reload requested from client PID 2192 ('.switch-to-conf') (unit backdoor.service)...1344machine # [ 58.758141] systemd[1]: Reloading...1345machine # [ 59.363863] systemd[1]: Reloading finished in 603 ms.1346machine # [ 59.524453] systemd[583]: Reexecution requested from client PID 2261 ('switch-to-confi')...1347machine # [ 59.528114] systemd[583]: Reexecuting.1348machine # [ 59.577561] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1349machine # [ 59.777441] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1350machine # [ 59.783954] systemd[583]: Reload requested from client PID 2261 ('switch-to-confi')...1351machine # [ 59.786805] systemd[583]: Reloading...1352machine # [ 59.977207] systemd[583]: Reloading finished in 189 ms.1353machine # [ 59.988180] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1354machine # [ 59.997390] systemd[583]: Reloading usertest.service...1355machine # [ 60.031147] systemd[583]: Reloaded usertest.service.1356machine # [ 60.036386] systemd[583]: Stopped Run user-specific NixOS activation.1357machine # [ 60.038724] systemd[583]: Stopping Run user-specific NixOS activation...1358machine # [ 60.041437] systemd[583]: Starting Run user-specific NixOS activation...1359machine # [ 60.087322] systemd[583]: Finished Run user-specific NixOS activation.1360machine # [ 60.091525] systemd[1]: Stopped target Reactivate sysinit units.1361machine # [ 60.093334] systemd[1]: Stopping Reactivate sysinit units...1362machine # [ 60.094204] systemd[1]: Reached target Reactivate sysinit units.1363machine # [ 60.102081] systemd[1]: Reached target Remote File Systems.1364machine # [ 60.105404] systemd[1]: Starting linger-users.service...1365machine # [ 60.107746] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1366machine # [ 60.119161] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1367machine # [ 60.121152] systemd[1]: Reached target Local File Systems.1368machine # [ 60.122498] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1369machine # [ 60.135700] systemd[1]: Starting Create SUID/SGID Wrappers...1370machine # [ 60.136909] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1371machine # [ 60.138223] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671372machine # [ 60.140495] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1373machine # [ 60.142072] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1374machine # [ 60.143337] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1375machine # [ 60.209413] systemd[1]: linger-users.service: Deactivated successfully.1376machine # [ 60.211901] systemd[1]: Finished linger-users.service.1377machine # [ 60.478083] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1378machine # [ 60.481206] systemd[1]: Finished Create SUID/SGID Wrappers.1379machine # [ 60.738397] nixos[2192]: finished switching to system configuration /nix/store/6xm94nc2dfb5b0gwa60rq68ryj7c6s5f-nixos-system-machine-test1380machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 3.04 seconds)1381machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1382machine # [ 60.823083] sudo[2331]: 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 # [ 60.841138] sudo[2331]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1384machine # [ 60.856260] sudo[2331]: 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/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&11387machine # [ 61.392473] nixos[2340]: switching to system configuration /nix/store/b2x9rdaj0226zkkkmzh4b5l9d7caxn6q-nixos-system-machine-test1388machine # [ 61.395549] systemd[1]: Stopped target Local File Systems.1389machine # [ 61.398515] systemd[1]: Stopped target Remote File Systems.1390machine # [ 61.893066] systemd[1]: Reload requested from client PID 2340 ('.switch-to-conf') (unit backdoor.service)...1391machine # [ 61.895322] systemd[1]: Reloading...1392machine # [ 62.482413] systemd[1]: Reloading finished in 585 ms.1393machine # [ 62.653387] systemd[583]: Stopped usertest.service.1394machine # [ 62.656640] systemd[583]: Reexecution requested from client PID 2409 ('switch-to-confi')...1395machine # [ 62.660362] systemd[583]: Reexecuting.1396machine # [ 62.726431] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1397machine # [ 62.936938] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1398machine # [ 62.944145] systemd[583]: Reload requested from client PID 2409 ('switch-to-confi')...1399machine # [ 62.945503] systemd[583]: Reloading...1400machine # [ 63.118163] systemd[583]: Reloading finished in 170 ms.1401machine # [ 63.136194] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1402machine # [ 63.147614] systemd[583]: Starting usertest.service...1403machine # [ 63.184761] systemd[583]: usertest.service: Main process exited, code=exited, status=1/FAILURE1404machine # [ 63.186363] systemd[583]: usertest.service: Failed with result 'exit-code'.1405machine # [ 63.189058] systemd[583]: Failed to start usertest.service.1406machine # [ 63.192202] systemd[583]: Stopped Run user-specific NixOS activation.1407machine # [ 63.193287] systemd[583]: Stopping Run user-specific NixOS activation...1408machine # [ 63.197277] systemd[583]: Starting Run user-specific NixOS activation...1409machine # [ 63.244931] systemd[583]: Finished Run user-specific NixOS activation.1410machine # [ 63.249613] systemd[1]: Stopped target Reactivate sysinit units.1411machine # [ 63.253003] systemd[1]: Stopping Reactivate sysinit units...1412machine # [ 63.254006] systemd[1]: Reached target Reactivate sysinit units.1413machine # [ 63.255674] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1414machine # [ 63.259057] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1415machine # [ 63.262063] systemd[1]: Reached target Local File Systems.1416machine # [ 63.267203] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1417machine # [ 63.274339] systemd[1]: Starting Create SUID/SGID Wrappers...1418machine # [ 63.275228] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1419machine # [ 63.280642] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671420machine # [ 63.284184] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1421machine # [ 63.285798] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1422machine # [ 63.287081] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1423machine # [ 63.291682] systemd[1]: Reached target Remote File Systems.1424machine # [ 63.618099] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1425machine # [ 63.622631] systemd[1]: Finished Create SUID/SGID Wrappers.1426machine # [ 63.626502] systemd[1]: Starting linger-users.service...1427machine # [ 63.690836] systemd[1]: linger-users.service: Deactivated successfully.1428machine # [ 63.693339] systemd[1]: Finished linger-users.service.1429machine # [ 63.949598] nixos[2340]: switching to system configuration /nix/store/b2x9rdaj0226zkkkmzh4b5l9d7caxn6q-nixos-system-machine-test failed (status 4)1430machine: (finished: must fail: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 3.10 seconds)1431machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11432machine # [ 64.523403] nixos[2482]: switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test1433machine # [ 64.528223] systemd[1]: Stopped target Remote File Systems.1434machine # [ 64.529602] systemd[1]: Stopped target Local File Systems.1435machine # [ 64.968767] systemd[1]: Reload requested from client PID 2482 ('.switch-to-conf') (unit backdoor.service)...1436machine # [ 64.970793] systemd[1]: Reloading...1437machine # [ 65.553463] systemd[1]: Reloading finished in 580 ms.1438machine # [ 65.699886] systemd[583]: Reexecution requested from client PID 2551 ('switch-to-confi')...1439machine # [ 65.703701] systemd[583]: Reexecuting.1440machine # [ 65.766018] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1441machine # [ 65.961427] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1442machine # [ 65.968262] systemd[583]: Reload requested from client PID 2551 ('switch-to-confi')...1443machine # [ 65.970787] systemd[583]: Reloading...1444machine # [ 66.158289] systemd[583]: Reloading finished in 186 ms.1445machine # [ 66.172158] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1446machine # [ 66.183055] systemd[583]: Starting usertest.service...1447machine # [ 66.218338] systemd[583]: Finished usertest.service.1448machine # [ 66.221256] systemd[583]: Stopped Run user-specific NixOS activation.1449machine # [ 66.223442] systemd[583]: Stopping Run user-specific NixOS activation...1450machine # [ 66.226113] systemd[583]: Starting Run user-specific NixOS activation...1451machine # [ 66.272388] systemd[583]: Finished Run user-specific NixOS activation.1452machine # [ 66.276493] systemd[1]: Stopped target Reactivate sysinit units.1453machine # [ 66.279136] systemd[1]: Stopping Reactivate sysinit units...1454machine # [ 66.280964] systemd[1]: Reached target Reactivate sysinit units.1455machine # [ 66.281874] systemd[1]: Reached target Local File Systems.1456machine # [ 66.285150] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1457machine # [ 66.290083] systemd[1]: Starting Create SUID/SGID Wrappers...1458machine # [ 66.290959] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1459machine # [ 66.293885] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671460machine # [ 66.299414] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1461machine # [ 66.304463] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1462machine # [ 66.305735] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1463machine # [ 66.306983] systemd[1]: Reached target Remote File Systems.1464machine # [ 66.307736] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1465machine # [ 66.315566] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1466machine # [ 66.626743] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1467machine # [ 66.630738] systemd[1]: Finished Create SUID/SGID Wrappers.1468machine # [ 66.634158] systemd[1]: Starting linger-users.service...1469machine # [ 66.693605] systemd[1]: linger-users.service: Deactivated successfully.1470machine # [ 66.696069] systemd[1]: Finished linger-users.service.1471machine # [ 66.953845] nixos[2482]: finished switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test1472machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.01 seconds)1473machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1474machine # [ 67.039218] sudo[2621]: 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 # [ 67.057826] sudo[2621]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1476machine # [ 67.072613] sudo[2621]: 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.11 seconds)1478machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&11479machine # [ 67.588723] nixos[2630]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test1480machine # [ 67.592464] systemd[1]: Stopped target Remote File Systems.1481machine # [ 67.593641] systemd[1]: Stopped target Local File Systems.1482machine # [ 68.030639] systemd[1]: Reload requested from client PID 2630 ('.switch-to-conf') (unit backdoor.service)...1483machine # [ 68.032778] systemd[1]: Reloading...1484machine # [ 68.609625] systemd[1]: Reloading finished in 575 ms.1485machine # [ 68.746877] systemd[583]: Stopped usertest.service.1486machine # [ 68.750397] systemd[583]: Reexecution requested from client PID 2699 ('switch-to-confi')...1487machine # [ 68.751702] systemd[583]: Reexecuting.1488machine # [ 68.817649] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1489machine # [ 69.007379] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1490machine # [ 69.014330] systemd[583]: Reload requested from client PID 2699 ('switch-to-confi')...1491machine # [ 69.016333] systemd[583]: Reloading...1492machine # [ 69.201585] systemd[583]: Reloading finished in 183 ms.1493machine # [ 69.216157] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1494machine # [ 69.226333] systemd[583]: Stopped Run user-specific NixOS activation.1495machine # [ 69.227409] systemd[583]: Stopping Run user-specific NixOS activation...1496machine # [ 69.230273] systemd[583]: Starting Run user-specific NixOS activation...1497machine # [ 69.276280] systemd[583]: Finished Run user-specific NixOS activation.1498machine # [ 69.280697] systemd[1]: Stopped target Reactivate sysinit units.1499machine # [ 69.281965] systemd[1]: Stopping Reactivate sysinit units...1500machine # [ 69.282776] systemd[1]: Reached target Reactivate sysinit units.1501machine # [ 69.286304] systemd[1]: Reached target Local File Systems.1502machine # [ 69.290004] systemd[1]: Reached target Remote File Systems.1503machine # [ 69.295297] systemd[1]: Starting linger-users.service...1504machine # [ 69.303106] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1505machine # [ 69.306639] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1506machine # [ 69.310501] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1507machine # [ 69.318279] systemd[1]: Starting Create SUID/SGID Wrappers...1508machine # [ 69.319150] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1509machine # [ 69.320784] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671510machine # [ 69.323501] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1511machine # [ 69.325907] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1512machine # [ 69.327587] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1513machine # [ 69.384357] systemd[1]: linger-users.service: Deactivated successfully.1514machine # [ 69.387378] systemd[1]: Finished linger-users.service.1515machine # [ 69.653788] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1516machine # [ 69.656935] systemd[1]: Finished Create SUID/SGID Wrappers.1517machine # [ 69.915380] nixos[2630]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test1518machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.85 seconds)1519machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1520machine # [ 69.974995] sudo[2767]: 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 # [ 69.990550] sudo[2767]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1522machine # [ 70.004645] sudo[2767]: 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.08 seconds)1524machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1525machine # [ 70.051326] sudo[2773]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1526machine # [ 70.066428] sudo[2773]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1527machine # [ 70.078911] sudo[2773]: 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.07 seconds)1529machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service1530machine # [ 70.123946] sudo[2779]: 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 # [ 70.137493] sudo[2779]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1532machine # [ 70.151643] sudo[2779]: 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.07 seconds)1534machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1535machine # [ 70.196520] sudo[2785]: 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 # [ 70.209323] sudo[2785]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1537machine # [ 70.221696] sudo[2785]: 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.07 seconds)1539machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1540machine # [ 70.266149] sudo[2791]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1541machine # [ 70.279010] sudo[2791]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1542machine # [ 70.289025] systemd[583]: Reload requested from client PID 2796 ('systemctl')...1543machine # [ 70.291685] systemd[583]: Reloading...1544machine # [ 70.484973] systemd[583]: Reloading finished in 191 ms.1545machine # [ 70.500178] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1546machine # [ 70.511970] sudo[2791]: 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.29 seconds)1548machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1549machine # [ 70.560641] sudo[2801]: 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 # [ 70.574487] sudo[2801]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1551machine # [ 70.592536] systemd[583]: Starting migrated.service...1552machine # [ 70.629874] systemd[583]: Finished migrated.service.1553machine # [ 70.634164] sudo[2801]: 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.12 seconds)1555machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1556machine # [ 70.680465] sudo[2809]: 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 # [ 70.693509] sudo[2809]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1558machine # [ 70.705119] sudo[2809]: 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.07 seconds)1560machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1561machine # [ 70.752329] sudo[2815]: 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 # [ 70.766352] sudo[2815]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1563machine # [ 70.780144] sudo[2815]: 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.08 seconds)1565machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1566machine # [ 70.827637] sudo[2821]: 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 # [ 70.840873] sudo[2821]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1568machine # [ 70.863812] sudo[2821]: 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.08 seconds)1570machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&11571machine # [ 71.388738] nixos[2830]: switching to system configuration /nix/store/mqzhhdz520dc3lx5rz5iqqi61a0k8025-nixos-system-machine-test1572machine # [ 71.394105] systemd[1]: Stopped target Remote File Systems.1573machine # [ 71.396079] systemd[1]: Stopped target Local File Systems.1574machine # [ 71.894260] systemd[1]: Reload requested from client PID 2830 ('.switch-to-conf') (unit backdoor.service)...1575machine # [ 71.896393] systemd[1]: Reloading...1576machine # [ 72.484529] systemd[1]: Reloading finished in 585 ms.1577machine # [ 72.646354] systemd[583]: Reexecution requested from client PID 2899 ('switch-to-confi')...1578machine # [ 72.650628] systemd[583]: Reexecuting.1579machine # [ 72.718021] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1580machine # [ 72.918744] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1581machine # [ 72.925428] systemd[583]: Reload requested from client PID 2899 ('switch-to-confi')...1582machine # [ 72.927717] systemd[583]: Reloading...1583machine # [ 73.116722] systemd[583]: Reloading finished in 187 ms.1584machine # [ 73.132170] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1585machine # [ 73.141321] systemd[583]: Stopped Run user-specific NixOS activation.1586machine # [ 73.143652] systemd[583]: Stopping Run user-specific NixOS activation...1587machine # [ 73.146445] systemd[583]: Starting Run user-specific NixOS activation...1588machine # [ 73.251932] systemd[583]: Reload requested from client PID 2911 ('systemctl') (unit nixos-activation.service)...1589machine # [ 73.253626] systemd[583]: Reloading...1590machine # [ 73.446214] systemd[583]: Reloading finished in 191 ms.1591machine # [ 73.460164] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1592machine # [ 73.487258] systemd[583]: Stopped migrated.service.1593machine # [ 73.500158] systemd[583]: Finished Run user-specific NixOS activation.1594machine # [ 73.502876] systemd[583]: Reload requested from client PID 2899 ('switch-to-confi')...1595machine # [ 73.506886] systemd[583]: Reloading...1596machine # [ 73.695724] systemd[583]: Reloading finished in 189 ms.1597machine # [ 73.708145] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1598machine # [ 73.720437] systemd[583]: Starting migrated.service...1599machine # [ 73.760745] systemd[583]: Finished migrated.service.1600machine # [ 73.764502] systemd[1]: Stopped target Reactivate sysinit units.1601machine # [ 73.766380] systemd[1]: Stopping Reactivate sysinit units...1602machine # [ 73.771195] systemd[1]: Reached target Reactivate sysinit units.1603machine # [ 73.772252] systemd[1]: Reached target Local File Systems.1604machine # [ 73.776863] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1605machine # [ 73.784770] systemd[1]: Starting Create SUID/SGID Wrappers...1606machine # [ 73.785753] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1607machine # [ 73.786987] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671608machine # [ 73.792181] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1609machine # [ 73.794537] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1610machine # [ 73.795836] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1611machine # [ 73.801425] systemd[1]: Reached target Remote File Systems.1612machine # [ 73.802716] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1613machine # [ 73.804585] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1614machine # [ 74.101092] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1615machine # [ 74.104487] systemd[1]: Finished Create SUID/SGID Wrappers.1616machine # [ 74.108691] systemd[1]: Starting linger-users.service...1617machine # [ 74.168376] systemd[1]: linger-users.service: Deactivated successfully.1618machine # [ 74.170974] systemd[1]: Finished linger-users.service.1619machine # [ 74.430915] nixos[2830]: finished switching to system configuration /nix/store/mqzhhdz520dc3lx5rz5iqqi61a0k8025-nixos-system-machine-test1620machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 3.58 seconds)1621machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1622machine # [ 74.520999] sudo[2981]: 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.539763] sudo[2981]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1624machine # [ 74.556964] sudo[2981]: pam_unix(sudo:session): session closed for user usertest1625machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user 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.609892] sudo[2987]: 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.624529] sudo[2987]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1629machine # [ 74.650889] sudo[2987]: 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.09 seconds)1631machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1632machine # [ 74.696498] sudo[2993]: 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.708655] sudo[2993]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1634machine # [ 74.720479] sudo[2993]: 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.07 seconds)1636machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&11637machine # [ 75.225096] nixos[3002]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test1638machine # [ 75.227783] systemd[1]: Stopped target Local File Systems.1639machine # [ 75.230133] systemd[1]: Stopped target Remote File Systems.1640machine # [ 75.665434] systemd[1]: Reload requested from client PID 3002 ('.switch-to-conf') (unit backdoor.service)...1641machine # [ 75.667060] systemd[1]: Reloading...1642machine # [ 76.235271] systemd[1]: Reloading finished in 565 ms.1643machine # [ 76.374959] systemd[583]: Stopped migrated.service.1644machine # [ 76.378391] systemd[583]: Reexecution requested from client PID 3070 ('switch-to-confi')...1645machine # [ 76.379704] systemd[583]: Reexecuting.1646machine # [ 76.450226] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1647machine # [ 76.637181] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1648machine # [ 76.644287] systemd[583]: Reload requested from client PID 3070 ('switch-to-confi')...1649machine # [ 76.646718] systemd[583]: Reloading...1650machine # [ 76.831304] systemd[583]: Reloading finished in 183 ms.1651machine # [ 76.844183] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1652machine # [ 76.855114] systemd[583]: Stopped Run user-specific NixOS activation.1653machine # [ 76.857515] systemd[583]: Stopping Run user-specific NixOS activation...1654machine # [ 76.860487] systemd[583]: Starting Run user-specific NixOS activation...1655machine # [ 76.908434] systemd[583]: Finished Run user-specific NixOS activation.1656machine # [ 76.912898] systemd[1]: Stopped target Reactivate sysinit units.1657machine # [ 76.916217] systemd[1]: Stopping Reactivate sysinit units...1658machine # [ 76.917096] systemd[1]: Reached target Reactivate sysinit units.1659machine # [ 76.921050] systemd[1]: Reached target Remote File Systems.1660machine # [ 76.924091] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1661machine # [ 76.925459] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1662machine # [ 76.929471] systemd[1]: Reached target Local File Systems.1663machine # [ 76.932663] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1664machine # [ 76.936955] systemd[1]: Starting Create SUID/SGID Wrappers...1665machine # [ 76.937873] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1666machine # [ 76.939092] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671667machine # [ 76.942174] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1668machine # [ 76.945863] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1669machine # [ 76.948861] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1670machine # [ 77.256706] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1671machine # [ 77.260635] systemd[1]: Finished Create SUID/SGID Wrappers.1672machine # [ 77.263683] systemd[1]: Starting linger-users.service...1673machine # [ 77.320617] systemd[1]: linger-users.service: Deactivated successfully.1674machine # [ 77.322979] systemd[1]: Finished linger-users.service.1675machine # [ 77.577981] nixos[3002]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test1676machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.86 seconds)1677machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1678machine # [ 77.634738] sudo[3139]: 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 # [ 77.648818] sudo[3139]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1680machine # [ 77.662343] sudo[3139]: 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.08 seconds)1682machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1683machine # [ 77.706291] sudo[3145]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1684machine # [ 77.718777] sudo[3145]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1685machine # [ 77.730299] sudo[3145]: 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.07 seconds)1687machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service1688machine # [ 77.774220] sudo[3151]: 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 # [ 77.787013] sudo[3151]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1690machine # [ 77.798999] sudo[3151]: 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 # [ 77.843521] sudo[3157]: 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 # [ 77.856371] sudo[3157]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1695machine # [ 77.867965] sudo[3157]: 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 # [ 77.910246] sudo[3163]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1699machine # [ 77.922757] sudo[3163]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1700machine # [ 77.932656] systemd[583]: Reload requested from client PID 3168 ('systemctl')...1701machine # [ 77.936595] systemd[583]: Reloading...1702machine # [ 78.119471] systemd[583]: Reloading finished in 183 ms.1703machine # [ 78.132171] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1704machine # [ 78.145950] sudo[3163]: 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.28 seconds)1706machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1707machine # [ 78.189387] sudo[3173]: 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.201925] sudo[3173]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1709machine # [ 78.218721] systemd[583]: Starting migrated.service...1710machine # [ 78.256555] systemd[583]: Finished migrated.service.1711machine # [ 78.261036] sudo[3173]: 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.11 seconds)1713machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1714machine # [ 78.304077] sudo[3181]: 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 # [ 78.316733] sudo[3181]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1716machine # [ 78.328184] sudo[3181]: 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 # [ 78.371936] sudo[3187]: 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 # [ 78.384686] sudo[3187]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1721machine # [ 78.396374] sudo[3187]: 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 # [ 78.440304] sudo[3193]: 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 # [ 78.453224] sudo[3193]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1726machine # [ 78.474877] sudo[3193]: 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.08 seconds)1728machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&11729machine # [ 78.981718] nixos[3202]: switching to system configuration /nix/store/13l69miwn0hkcfqwzj9kj9c27zww979i-nixos-system-machine-test1730machine # [ 78.984560] systemd[1]: Stopped target Remote File Systems.1731machine # [ 78.986985] systemd[1]: Stopped target Local File Systems.1732machine # [ 79.471995] systemd[1]: Reload requested from client PID 3202 ('.switch-to-conf') (unit backdoor.service)...1733machine # [ 79.474000] systemd[1]: Reloading...1734machine # [ 80.037994] systemd[1]: Reloading finished in 561 ms.1735machine # [ 80.192501] systemd[583]: Reexecution requested from client PID 3271 ('switch-to-confi')...1736machine # [ 80.195875] systemd[583]: Reexecuting.1737machine # [ 80.253773] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1738machine # [ 80.446678] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1739machine # [ 80.453946] systemd[583]: Reload requested from client PID 3271 ('switch-to-confi')...1740machine # [ 80.456386] systemd[583]: Reloading...1741machine # [ 80.640106] systemd[583]: Reloading finished in 182 ms.1742machine # [ 80.657639] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1743machine # [ 80.666619] systemd[583]: Stopped Run user-specific NixOS activation.1744machine # [ 80.668871] systemd[583]: Stopping Run user-specific NixOS activation...1745machine # [ 80.671697] systemd[583]: Starting Run user-specific NixOS activation...1746machine # [ 80.727308] systemd[583]: Reload requested from client PID 3283 ('systemctl') (unit nixos-activation.service)...1747machine # [ 80.731712] systemd[583]: Reloading...1748machine # [ 80.920885] systemd[583]: Reloading finished in 190 ms.1749machine # [ 80.936162] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1750machine # [ 80.955092] systemd[583]: Finished Run user-specific NixOS activation.1751machine # [ 80.958248] systemd[583]: Reload requested from client PID 3271 ('switch-to-confi')...1752machine # [ 80.961741] systemd[583]: Reloading...1753machine # [ 81.147119] systemd[583]: Reloading finished in 186 ms.1754machine # [ 81.160180] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1755machine # [ 81.170649] systemd[583]: Stopped migrated.service.1756machine # [ 81.172831] systemd[583]: Stopping migrated.service...1757machine # [ 81.175499] systemd[583]: Starting migrated.service...1758machine # [ 81.213775] systemd[583]: Finished migrated.service.1759machine # [ 81.220795] systemd[1]: Stopped target Reactivate sysinit units.1760machine # [ 81.222709] systemd[1]: Stopping Reactivate sysinit units...1761machine # [ 81.224522] systemd[1]: Reached target Reactivate sysinit units.1762machine # [ 81.227022] systemd[1]: Reached target Local File Systems.1763machine # [ 81.230995] systemd[1]: Reached target Remote File Systems.1764machine # [ 81.236584] systemd[1]: Starting linger-users.service...1765machine # [ 81.239550] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1766machine # [ 81.250958] systemd[1]: Starting Create SUID/SGID Wrappers...1767machine # [ 81.251837] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1768machine # [ 81.254600] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671769machine # [ 81.264572] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1770machine # [ 81.266154] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1771machine # [ 81.269717] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1772machine # [ 81.271730] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1773machine # [ 81.273521] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1774machine # [ 81.331035] systemd[1]: linger-users.service: Deactivated successfully.1775machine # [ 81.334092] systemd[1]: Finished linger-users.service.1776machine # [ 81.591746] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1777machine # [ 81.595066] systemd[1]: Finished Create SUID/SGID Wrappers.1778machine # [ 81.851713] nixos[3202]: finished switching to system configuration /nix/store/13l69miwn0hkcfqwzj9kj9c27zww979i-nixos-system-machine-test1779machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 3.39 seconds)1780machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1781machine # [ 81.937350] sudo[3352]: 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 # [ 81.955693] sudo[3352]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1783machine # [ 81.974017] sudo[3352]: pam_unix(sudo:session): session closed for user usertest1784machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds)1785machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1786machine # [ 82.028994] sudo[3358]: 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 # [ 82.042783] sudo[3358]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1788machine # [ 82.072564] sudo[3358]: 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.10 seconds)1790machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1791machine # [ 82.123186] sudo[3364]: 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 # [ 82.137149] sudo[3364]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1793machine # [ 82.149445] sudo[3364]: 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/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&11796machine # [ 82.655708] nixos[3373]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test1797machine # [ 82.659873] systemd[1]: Stopped target Remote File Systems.1798machine # [ 82.661107] systemd[1]: Stopped target Local File Systems.1799machine # [ 83.090151] systemd[1]: Reload requested from client PID 3373 ('.switch-to-conf') (unit backdoor.service)...1800machine # [ 83.092221] systemd[1]: Reloading...1801machine # [ 83.665541] systemd[1]: Reloading finished in 570 ms.1802machine # [ 83.809002] systemd[583]: Stopped migrated.service.1803machine # [ 83.812537] systemd[583]: Reexecution requested from client PID 3442 ('switch-to-confi')...1804machine # [ 83.813878] systemd[583]: Reexecuting.1805machine # [ 83.877694] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1806machine # [ 84.068951] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1807machine # [ 84.075349] systemd[583]: Reload requested from client PID 3442 ('switch-to-confi')...1808machine # [ 84.078163] systemd[583]: Reloading...1809machine # [ 84.263240] systemd[583]: Reloading finished in 184 ms.1810machine # [ 84.280176] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1811machine # [ 84.290397] systemd[583]: Stopped Run user-specific NixOS activation.1812machine # [ 84.291470] systemd[583]: Stopping Run user-specific NixOS activation...1813machine # [ 84.294456] systemd[583]: Starting Run user-specific NixOS activation...1814machine # [ 84.341128] systemd[583]: Finished Run user-specific NixOS activation.1815machine # [ 84.345163] systemd[1]: Stopped target Reactivate sysinit units.1816machine # [ 84.348127] systemd[1]: Stopping Reactivate sysinit units...1817machine # [ 84.348971] systemd[1]: Reached target Reactivate sysinit units.1818machine # [ 84.350276] systemd[1]: Reached target Local File Systems.1819machine # [ 84.354683] systemd[1]: Reached target Remote File Systems.1820machine # [ 84.360278] systemd[1]: Starting linger-users.service...1821machine # [ 84.370087] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1822machine # [ 84.375107] systemd[1]: Starting Create SUID/SGID Wrappers...1823machine # [ 84.378123] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1824machine # [ 84.384193] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671825machine # [ 84.386312] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1826machine # [ 84.387815] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1827machine # [ 84.392609] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1828machine # [ 84.394502] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1829machine # [ 84.396994] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1830machine # [ 84.453708] systemd[1]: linger-users.service: Deactivated successfully.1831machine # [ 84.455490] systemd[1]: Finished linger-users.service.1832machine # [ 84.707455] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1833machine # [ 84.710563] systemd[1]: Finished Create SUID/SGID Wrappers.1834machine # [ 84.968652] nixos[3373]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test1835machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.83 seconds)1836machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1837machine # [ 85.053166] sudo[3510]: 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 # [ 85.071511] sudo[3510]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1839machine # [ 85.087230] sudo[3510]: 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.11 seconds)1841machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1842machine # [ 85.134198] sudo[3516]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1843machine # [ 85.147947] sudo[3516]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1844machine # [ 85.161327] sudo[3516]: 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 # [ 85.205956] sudo[3522]: 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 # [ 85.218875] sudo[3522]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1849machine # [ 85.230917] sudo[3522]: 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.07 seconds)1851machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1852machine # [ 85.274085] sudo[3528]: 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 # [ 85.286822] sudo[3528]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1854machine # [ 85.298426] sudo[3528]: 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.07 seconds)1856machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1857machine # [ 85.341115] sudo[3534]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1858machine # [ 85.353473] sudo[3534]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1859machine # [ 85.364700] systemd[583]: Reload requested from client PID 3539 ('systemctl')...1860machine # [ 85.368641] systemd[583]: Reloading...1861machine # [ 85.553021] systemd[583]: Reloading finished in 185 ms.1862machine # [ 85.568179] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1863machine # [ 85.581184] sudo[3534]: 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.28 seconds)1865machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1866machine # [ 85.627157] sudo[3544]: 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 # [ 85.640879] sudo[3544]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1868machine # [ 85.656904] systemd[583]: Starting migrated.service...1869machine # [ 85.693479] systemd[583]: Finished migrated.service.1870machine # [ 85.698338] sudo[3544]: 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.12 seconds)1872machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1873machine # [ 85.742344] sudo[3552]: 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 # [ 85.755051] sudo[3552]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1875machine # [ 85.767583] sudo[3552]: 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 # [ 85.811543] sudo[3558]: 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 # [ 85.824785] sudo[3558]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1880machine # [ 85.835898] sudo[3558]: 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 # [ 85.879362] sudo[3564]: 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 # [ 85.892853] sudo[3564]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1885machine # [ 85.913846] sudo[3564]: 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.08 seconds)1887machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&11888machine # [ 86.414167] nixos[3573]: switching to system configuration /nix/store/lpbbdz3hihjq7b6jc616lpy0vq1wf6p1-nixos-system-machine-test1889machine # [ 86.416986] systemd[1]: Stopped target Remote File Systems.1890machine # [ 86.419247] systemd[1]: Stopped target Local File Systems.1891machine # [ 86.932255] systemd[1]: Reload requested from client PID 3573 ('.switch-to-conf') (unit backdoor.service)...1892machine # [ 86.934252] systemd[1]: Reloading...1893machine # [ 87.527905] systemd[1]: Reloading finished in 591 ms.1894machine # [ 87.688432] systemd[583]: Reexecution requested from client PID 3642 ('switch-to-confi')...1895machine # [ 87.691205] systemd[583]: Reexecuting.1896machine # [ 87.749741] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1897machine # [ 87.945221] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1898machine # [ 87.952096] systemd[583]: Reload requested from client PID 3642 ('switch-to-confi')...1899machine # [ 87.953385] systemd[583]: Reloading...1900machine # [ 88.141254] systemd[583]: Reloading finished in 185 ms.1901machine # [ 88.152178] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1902machine # [ 88.162260] systemd[583]: Stopped Run user-specific NixOS activation.1903machine # [ 88.163247] systemd[583]: Stopping Run user-specific NixOS activation...1904machine # [ 88.166055] systemd[583]: Starting Run user-specific NixOS activation...1905machine # [ 88.223897] systemd[583]: Reload requested from client PID 3654 ('systemctl') (unit nixos-activation.service)...1906machine # [ 88.228109] systemd[583]: Reloading...1907machine # [ 88.422721] systemd[583]: Reloading finished in 195 ms.1908machine # [ 88.436170] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1909machine # [ 88.455303] systemd[583]: Finished Run user-specific NixOS activation.1910machine # [ 88.458508] systemd[583]: Reload requested from client PID 3642 ('switch-to-confi')...1911machine # [ 88.459739] systemd[583]: Reloading...1912machine # [ 88.650981] systemd[583]: Reloading finished in 189 ms.1913machine # [ 88.664168] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1914machine # [ 88.677028] systemd[583]: Reloading migrated.service...1915machine # [ 88.710978] systemd[583]: Reloaded migrated.service.1916machine # [ 88.717733] systemd[1]: Stopped target Reactivate sysinit units.1917machine # [ 88.720718] systemd[1]: Stopping Reactivate sysinit units...1918machine # [ 88.721614] systemd[1]: Reached target Reactivate sysinit units.1919machine # [ 88.727277] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1920machine # [ 88.729690] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1921machine # [ 88.733366] systemd[1]: Reached target Remote File Systems.1922machine # [ 88.738328] systemd[1]: Starting linger-users.service...1923machine # [ 88.743203] systemd[1]: Reached target Local File Systems.1924machine # [ 88.752643] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1925machine # [ 88.754144] systemd[1]: Starting Create SUID/SGID Wrappers...1926machine # [ 88.754907] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1927machine # [ 88.763013] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671928machine # [ 88.765373] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1929machine # [ 88.766920] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1930machine # [ 88.768217] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1931machine # [ 88.830956] systemd[1]: linger-users.service: Deactivated successfully.1932machine # [ 88.834012] systemd[1]: Finished linger-users.service.1933machine # [ 89.096696] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1934machine # [ 89.099871] systemd[1]: Finished Create SUID/SGID Wrappers.1935machine # [ 89.356851] nixos[3573]: finished switching to system configuration /nix/store/lpbbdz3hihjq7b6jc616lpy0vq1wf6p1-nixos-system-machine-test1936machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 3.45 seconds)1937machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1938machine # [ 89.443595] sudo[3723]: 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 # [ 89.463897] sudo[3723]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1940machine # [ 89.481180] sudo[3723]: 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 # [ 89.533388] sudo[3729]: 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 # [ 89.547287] sudo[3729]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1945machine # [ 89.559543] sudo[3729]: 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/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&11948machine # [ 90.072615] nixos[3738]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test1949machine # [ 90.075298] systemd[1]: Stopped target Remote File Systems.1950machine # [ 90.077436] systemd[1]: Stopped target Local File Systems.1951machine # [ 90.509905] systemd[1]: Reload requested from client PID 3738 ('.switch-to-conf') (unit backdoor.service)...1952machine # [ 90.511865] systemd[1]: Reloading...1953machine # [ 91.098602] systemd[1]: Reloading finished in 584 ms.1954machine # [ 91.241273] systemd[583]: Stopped migrated.service.1955machine # [ 91.244513] systemd[583]: Reexecution requested from client PID 3807 ('switch-to-confi')...1956machine # [ 91.247264] systemd[583]: Reexecuting.1957machine # [ 91.301536] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1958machine # [ 91.489895] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1959machine # [ 91.497080] systemd[583]: Reload requested from client PID 3807 ('switch-to-confi')...1960machine # [ 91.499455] systemd[583]: Reloading...1961machine # [ 91.687490] systemd[583]: Reloading finished in 186 ms.1962machine # [ 91.700147] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1963machine # [ 91.710851] systemd[583]: Stopped Run user-specific NixOS activation.1964machine # [ 91.713184] systemd[583]: Stopping Run user-specific NixOS activation...1965machine # [ 91.716073] systemd[583]: Starting Run user-specific NixOS activation...1966machine # [ 91.760609] systemd[583]: Finished Run user-specific NixOS activation.1967machine # [ 91.765097] systemd[1]: Stopped target Reactivate sysinit units.1968machine # [ 91.767442] systemd[1]: Stopping Reactivate sysinit units...1969machine # [ 91.769067] systemd[1]: Reached target Reactivate sysinit units.1970machine # [ 91.772685] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1971machine # [ 91.775100] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1972machine # [ 91.778356] systemd[1]: Reached target Remote File Systems.1973machine # [ 91.779650] systemd[1]: Reached target Local File Systems.1974machine # [ 91.784705] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1975machine # [ 91.789956] systemd[1]: Starting Create SUID/SGID Wrappers...1976machine # [ 91.790872] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1977machine # [ 91.792546] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671978machine # [ 91.800715] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1979machine # [ 91.802379] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1980machine # [ 91.803631] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1981machine # [ 92.110748] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1982machine # [ 92.115565] systemd[1]: Finished Create SUID/SGID Wrappers.1983machine # [ 92.117858] systemd[1]: Starting linger-users.service...1984machine # [ 92.177960] systemd[1]: linger-users.service: Deactivated successfully.1985machine # [ 92.180820] systemd[1]: Finished linger-users.service.1986machine # [ 92.438536] nixos[3738]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test1987machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.89 seconds)1988machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1989machine # [ 92.522176] sudo[3875]: 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 # [ 92.541107] sudo[3875]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1991machine # [ 92.555896] sudo[3875]: 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 # [ 92.603501] sudo[3881]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1995machine # [ 92.617049] sudo[3881]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1996machine # [ 92.627145] sudo[3881]: 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 # [ 92.667594] sudo[3887]: 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 # [ 92.680537] sudo[3887]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2001machine # [ 92.692605] sudo[3887]: 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 # [ 92.738321] sudo[3893]: 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 # [ 92.751899] sudo[3893]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2006machine # [ 92.764849] sudo[3893]: 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 # [ 92.812736] sudo[3899]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2010machine # [ 92.825514] sudo[3899]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2011machine # [ 92.835566] systemd[583]: Reload requested from client PID 3904 ('systemctl')...2012machine # [ 92.839562] systemd[583]: Reloading...2013machine # [ 93.027273] systemd[583]: Reloading finished in 188 ms.2014machine # [ 93.040144] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2015machine # [ 93.052523] sudo[3899]: 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.29 seconds)2017machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2018machine # [ 93.097779] sudo[3909]: 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 # [ 93.112763] sudo[3909]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2020machine # [ 93.128702] systemd[583]: Starting migrated.service...2021machine # [ 93.161517] systemd[583]: Finished migrated.service.2022machine # [ 93.166067] sudo[3909]: 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.11 seconds)2024machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2025machine # [ 93.210942] sudo[3917]: 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 # [ 93.223883] sudo[3917]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2027machine # [ 93.235895] sudo[3917]: 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 # [ 93.280066] sudo[3923]: 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 # [ 93.292759] sudo[3923]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2032machine # [ 93.304472] sudo[3923]: 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 # [ 93.350393] sudo[3929]: 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 # [ 93.363025] sudo[3929]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2037machine # [ 93.384103] sudo[3929]: 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/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&12040machine # [ 93.876647] nixos[3938]: switching to system configuration /nix/store/2y8zkwdzgvvqg2qvlagzsnarhxzibnaq-nixos-system-machine-test2041machine # [ 93.880595] systemd[1]: Stopped target Local File Systems.2042machine # [ 93.881807] systemd[1]: Stopped target Remote File Systems.2043machine # [ 94.345368] systemd[1]: Reload requested from client PID 3938 ('.switch-to-conf') (unit backdoor.service)...2044machine # [ 94.347339] systemd[1]: Reloading...2045machine # [ 94.921304] systemd[1]: Reloading finished in 570 ms.2046machine # [ 95.080494] systemd[583]: Reexecution requested from client PID 4007 ('switch-to-confi')...2047machine # [ 95.081971] systemd[583]: Reexecuting.2048machine # [ 95.146095] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2049machine # [ 95.345004] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2050machine # [ 95.352763] systemd[583]: Reload requested from client PID 4007 ('switch-to-confi')...2051machine # [ 95.357255] systemd[583]: Reloading...2052machine # [ 95.539742] systemd[583]: Reloading finished in 183 ms.2053machine # [ 95.552144] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2054machine # [ 95.563241] systemd[583]: Stopped Run user-specific NixOS activation.2055machine # [ 95.564354] systemd[583]: Stopping Run user-specific NixOS activation...2056machine # [ 95.567207] systemd[583]: Starting Run user-specific NixOS activation...2057machine # [ 95.621236] systemd[583]: Reload requested from client PID 4019 ('systemctl') (unit nixos-activation.service)...2058machine # [ 95.623746] systemd[583]: Reloading...2059machine # [ 95.812880] systemd[583]: Reloading finished in 187 ms.2060machine # [ 95.828158] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2061machine # [ 95.847738] systemd[583]: Finished Run user-specific NixOS activation.2062machine # [ 95.850526] systemd[583]: Reload requested from client PID 4007 ('switch-to-confi')...2063machine # [ 95.852787] systemd[583]: Reloading...2064machine # [ 96.040069] systemd[583]: Reloading finished in 186 ms.2065machine # [ 96.052154] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2066machine # [ 96.066878] systemd[1]: Stopped target Reactivate sysinit units.2067machine # [ 96.069932] systemd[1]: Stopping Reactivate sysinit units...2068machine # [ 96.072069] systemd[1]: Reached target Reactivate sysinit units.2069machine # [ 96.072951] systemd[1]: Reached target Remote File Systems.2070machine # [ 96.074652] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2071machine # [ 96.075926] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2072machine # [ 96.079872] systemd[1]: Reached target Local File Systems.2073machine # [ 96.087583] systemd[1]: Starting linger-users.service...2074machine # [ 96.093933] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2075machine # [ 96.098957] systemd[1]: Starting Create SUID/SGID Wrappers...2076machine # [ 96.103508] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2077machine # [ 96.105219] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672078machine # [ 96.107765] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2079machine # [ 96.113148] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2080machine # [ 96.114479] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2081machine # [ 96.174917] systemd[1]: linger-users.service: Deactivated successfully.2082machine # [ 96.178207] systemd[1]: Finished linger-users.service.2083machine # [ 96.434959] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2084machine # [ 96.438200] systemd[1]: Finished Create SUID/SGID Wrappers.2085machine # [ 96.695135] nixos[3938]: finished switching to system configuration /nix/store/2y8zkwdzgvvqg2qvlagzsnarhxzibnaq-nixos-system-machine-test2086machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 3.32 seconds)2087machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2088machine # [ 96.775841] sudo[4086]: 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 # [ 96.796879] sudo[4086]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2090machine # [ 96.813406] sudo[4086]: 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.11 seconds)2092machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2093machine # [ 96.865024] sudo[4092]: 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 # [ 96.878431] sudo[4092]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2095machine # [ 96.890061] sudo[4092]: 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.07 seconds)2097machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&12098machine # [ 97.413919] nixos[4101]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test2099machine # [ 97.416759] systemd[1]: Stopped target Remote File Systems.2100machine # [ 97.419028] systemd[1]: Stopped target Local File Systems.2101machine # [ 97.853934] systemd[1]: Reload requested from client PID 4101 ('.switch-to-conf') (unit backdoor.service)...2102machine # [ 97.855929] systemd[1]: Reloading...2103machine # [ 98.427925] systemd[1]: Reloading finished in 569 ms.2104machine # [ 98.570338] systemd[583]: Stopped migrated.service.2105machine # [ 98.573708] systemd[583]: Reexecution requested from client PID 4170 ('switch-to-confi')...2106machine # [ 98.575006] systemd[583]: Reexecuting.2107machine # [ 98.641746] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2108machine # [ 98.829212] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2109machine # [ 98.835821] systemd[583]: Reload requested from client PID 4170 ('switch-to-confi')...2110machine # [ 98.837970] systemd[583]: Reloading...2111machine # [ 99.017389] systemd[583]: Reloading finished in 178 ms.2112machine # [ 99.032161] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2113machine # [ 99.041393] systemd[583]: Stopped Run user-specific NixOS activation.2114machine # [ 99.043585] systemd[583]: Stopping Run user-specific NixOS activation...2115machine # [ 99.046243] systemd[583]: Starting Run user-specific NixOS activation...2116machine # [ 99.091182] systemd[583]: Finished Run user-specific NixOS activation.2117machine # [ 99.095194] systemd[1]: Stopped target Reactivate sysinit units.2118machine # [ 99.096482] systemd[1]: Stopping Reactivate sysinit units...2119machine # [ 99.097280] systemd[1]: Reached target Reactivate sysinit units.2120machine # [ 99.102552] systemd[1]: Reached target Remote File Systems.2121machine # [ 99.108097] systemd[1]: Starting linger-users.service...2122machine # [ 99.110747] systemd[1]: Reached target Local File Systems.2123machine # [ 99.113108] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2124machine # [ 99.114361] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2125machine # [ 99.120396] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2126machine # [ 99.126928] systemd[1]: Starting Create SUID/SGID Wrappers...2127machine # [ 99.127818] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2128machine # [ 99.132371] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672129machine # [ 99.137901] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2130machine # [ 99.139496] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2131machine # [ 99.142405] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2132machine # [ 99.199640] systemd[1]: linger-users.service: Deactivated successfully.2133machine # [ 99.203059] systemd[1]: Finished linger-users.service.2134machine # [ 99.454720] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2135machine # [ 99.458019] systemd[1]: Finished Create SUID/SGID Wrappers.2136machine # [ 99.714720] nixos[4101]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test2137machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.84 seconds)2138machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2139machine # [ 99.796878] sudo[4238]: 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 # [ 99.813953] sudo[4238]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2141machine # [ 99.829723] sudo[4238]: 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.10 seconds)2143machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user2144machine # [ 99.877882] sudo[4244]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user2145machine # [ 99.891239] sudo[4244]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2146machine # [ 99.903064] sudo[4244]: pam_unix(sudo:session): session closed for user usertest2147machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.07 seconds)2148machine: must succeed: sudo -u usertest cp /nix/store/sjwrvqsv40pz3pq93zpd2s2k0jw0h8qf-migrated.service ~usertest/.local/share/systemd/user/migrated.service2149machine # [ 99.945948] sudo[4250]: 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 # [ 99.958682] sudo[4250]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2151machine # [ 99.970951] sudo[4250]: 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.07 seconds)2153machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2154machine # [ 100.013093] sudo[4256]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2155machine # [ 100.025495] sudo[4256]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2156machine # [ 100.035266] systemd[583]: Reload requested from client PID 4261 ('systemctl')...2157machine # [ 100.037530] systemd[583]: Reloading...2158machine # [ 100.219003] systemd[583]: Reloading finished in 179 ms.2159machine # [ 100.232207] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2160machine # [ 100.245305] sudo[4256]: 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.28 seconds)2162machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2163machine # [ 100.290911] sudo[4266]: 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 # [ 100.303527] sudo[4266]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2165machine # [ 100.318588] systemd[583]: Starting migrated.service...2166machine # [ 100.356981] systemd[583]: Finished migrated.service.2167machine # [ 100.361514] sudo[4266]: 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.11 seconds)2169machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2170machine # [ 100.404190] sudo[4274]: 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 # [ 100.416892] sudo[4274]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2172machine # [ 100.429113] sudo[4274]: 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 # [ 100.472832] sudo[4280]: 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 # [ 100.485783] sudo[4280]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2177machine # [ 100.497339] sudo[4280]: 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 # [ 100.539993] sudo[4286]: 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 # [ 100.552641] sudo[4286]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2182machine # [ 100.596230] sudo[4286]: 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.10 seconds)2184machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12185machine # [ 101.112256] nixos[4295]: switching to system configuration /nix/store/2q6v66pn7bsy861rkivfp4x66plzgmb9-nixos-system-machine-test2186machine # [ 101.114973] systemd[1]: Stopped target Local File Systems.2187machine # [ 101.116903] systemd[1]: Stopped target Remote File Systems.2188machine # [ 101.598051] systemd[1]: Reload requested from client PID 4295 ('.switch-to-conf') (unit backdoor.service)...2189machine # [ 101.600209] systemd[1]: Reloading...2190machine # [ 102.173613] systemd[1]: Reloading finished in 571 ms.2191machine # [ 102.325091] systemd[583]: Reexecution requested from client PID 4363 ('switch-to-confi')...2192machine # [ 102.329725] systemd[583]: Reexecuting.2193machine # [ 102.373206] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2194machine # [ 102.567763] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2195machine # [ 102.574387] systemd[583]: Reload requested from client PID 4363 ('switch-to-confi')...2196machine # [ 102.576917] systemd[583]: Reloading...2197machine # [ 102.764131] systemd[583]: Reloading finished in 186 ms.2198machine # [ 102.776150] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2199machine # [ 102.786096] systemd[583]: Stopped Run user-specific NixOS activation.2200machine # [ 102.787095] systemd[583]: Stopping Run user-specific NixOS activation...2201machine # [ 102.789832] systemd[583]: Starting Run user-specific NixOS activation...2202machine # [ 102.835713] systemd[583]: Finished Run user-specific NixOS activation.2203machine # [ 102.837829] systemd[583]: Reload requested from client PID 4363 ('switch-to-confi')...2204machine # [ 102.840162] systemd[583]: Reloading...2205machine # [ 103.027113] systemd[583]: Reloading finished in 185 ms.2206machine # [ 103.040156] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2207machine # [ 103.050639] systemd[583]: Stopped migrated.service.2208machine # [ 103.052728] systemd[583]: Stopping migrated.service...2209machine # [ 103.055371] systemd[583]: Starting migrated.service...2210machine # [ 103.093594] systemd[583]: Finished migrated.service.2211machine # [ 103.099917] systemd[1]: Stopped target Reactivate sysinit units.2212machine # [ 103.102067] systemd[1]: Stopping Reactivate sysinit units...2213machine # [ 103.102922] systemd[1]: Reached target Reactivate sysinit units.2214machine # [ 103.108226] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2215machine # [ 103.112317] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2216machine # [ 103.114163] systemd[1]: Reached target Remote File Systems.2217machine # [ 103.115005] systemd[1]: Reached target Local File Systems.2218machine # [ 103.118417] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2219machine # [ 103.125872] systemd[1]: Starting Create SUID/SGID Wrappers...2220machine # [ 103.126835] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2221machine # [ 103.129166] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672222machine # [ 103.133603] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2223machine # [ 103.136122] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2224machine # [ 103.140263] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2225machine # [ 103.429099] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2226machine # [ 103.431429] systemd[1]: Finished Create SUID/SGID Wrappers.2227machine # [ 103.436664] systemd[1]: Starting linger-users.service...2228machine # [ 103.497290] systemd[1]: linger-users.service: Deactivated successfully.2229machine # [ 103.500130] systemd[1]: Finished linger-users.service.2230machine # [ 103.758347] nixos[4295]: finished switching to system configuration /nix/store/2q6v66pn7bsy861rkivfp4x66plzgmb9-nixos-system-machine-test2231machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.17 seconds)2232machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2233machine # [ 103.842488] sudo[4438]: 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 # [ 103.859797] sudo[4438]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2235machine # [ 103.873873] sudo[4438]: pam_unix(sudo:session): session closed for user usertest2236machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)2237machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2238machine # [ 103.922470] sudo[4444]: 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 # [ 103.936706] sudo[4444]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2240machine # [ 103.963949] sudo[4444]: 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.09 seconds)2242machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2243machine # [ 104.007290] sudo[4450]: 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 # [ 104.019802] sudo[4450]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2245machine # [ 104.031372] sudo[4450]: 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.07 seconds)2247machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12248machine # [ 104.528679] nixos[4459]: switching to system configuration /nix/store/2q6v66pn7bsy861rkivfp4x66plzgmb9-nixos-system-machine-test2249machine # [ 104.531440] systemd[1]: Stopped target Local File Systems.2250machine # [ 104.533862] systemd[1]: Stopped target Remote File Systems.2251machine # [ 104.957987] systemd[1]: Reload requested from client PID 4459 ('.switch-to-conf') (unit backdoor.service)...2252machine # [ 104.959930] systemd[1]: Reloading...2253machine # [ 105.529308] systemd[1]: Reloading finished in 566 ms.2254machine # [ 105.682992] systemd[583]: Reexecution requested from client PID 4528 ('switch-to-confi')...2255machine # [ 105.685176] systemd[583]: Reexecuting.2256machine # [ 105.749943] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2257machine # [ 105.942537] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2258machine # [ 105.950054] systemd[583]: Reload requested from client PID 4528 ('switch-to-confi')...2259machine # [ 105.951361] systemd[583]: Reloading...2260machine # [ 106.138962] systemd[583]: Reloading finished in 186 ms.2261machine # [ 106.152170] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2262machine # [ 106.162207] systemd[583]: Stopped Run user-specific NixOS activation.2263machine # [ 106.164617] systemd[583]: Stopping Run user-specific NixOS activation...2264machine # [ 106.168363] systemd[583]: Starting Run user-specific NixOS activation...2265machine # [ 106.212075] systemd[583]: Finished Run user-specific NixOS activation.2266machine # [ 106.216455] systemd[1]: Stopped target Reactivate sysinit units.2267machine # [ 106.219332] systemd[1]: Stopping Reactivate sysinit units...2268machine # [ 106.222567] systemd[1]: Reached target Reactivate sysinit units.2269machine # [ 106.226521] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2270machine # [ 106.228135] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2271machine # [ 106.231008] systemd[1]: Reached target Remote File Systems.2272machine # [ 106.231851] systemd[1]: Starting linger-users.service...2273machine # [ 106.236984] systemd[1]: Reached target Local File Systems.2274machine # [ 106.240278] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2275machine # [ 106.246184] systemd[1]: Starting Create SUID/SGID Wrappers...2276machine # [ 106.249894] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2277machine # [ 106.257423] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672278machine # [ 106.259645] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2279machine # [ 106.263919] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2280machine # [ 106.267021] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2281machine # [ 106.327772] systemd[1]: linger-users.service: Deactivated successfully.2282machine # [ 106.331338] systemd[1]: Finished linger-users.service.2283machine # [ 106.590128] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2284machine # [ 106.593408] systemd[1]: Finished Create SUID/SGID Wrappers.2285machine # [ 106.851352] nixos[4459]: finished switching to system configuration /nix/store/2q6v66pn7bsy861rkivfp4x66plzgmb9-nixos-system-machine-test2286machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 2.83 seconds)2287machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd2288machine # [ 106.934394] sudo[4596]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd2289machine # [ 106.951768] sudo[4596]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2290machine # [ 106.966362] sudo[4596]: 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 # [ 107.016674] sudo[4602]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2294machine # [ 107.030009] sudo[4602]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2295machine # [ 107.040161] systemd[583]: Reload requested from client PID 4607 ('systemctl')...2296machine # [ 107.042837] systemd[583]: Reloading...2297machine # [ 107.234887] systemd[583]: Reloading finished in 191 ms.2298machine # [ 107.248152] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2299machine # [ 107.259528] sudo[4602]: 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.29 seconds)2301machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&12302machine # [ 107.773575] nixos[4615]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test2303machine # [ 107.776251] systemd[1]: Stopped target Remote File Systems.2304machine # [ 107.778463] systemd[1]: Stopped target Local File Systems.2305machine # [ 108.211588] systemd[1]: Reload requested from client PID 4615 ('.switch-to-conf') (unit backdoor.service)...2306machine # [ 108.213791] systemd[1]: Reloading...2307machine # [ 108.781736] systemd[1]: Reloading finished in 565 ms.2308machine # [ 108.921555] systemd[583]: Stopped migrated.service.2309machine # [ 108.924497] systemd[583]: Reexecution requested from client PID 4683 ('switch-to-confi')...2310machine # [ 108.927481] systemd[583]: Reexecuting.2311machine # [ 108.981774] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2312machine # [ 109.165495] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2313machine # [ 109.172686] systemd[583]: Reload requested from client PID 4683 ('switch-to-confi')...2314machine # [ 109.175132] systemd[583]: Reloading...2315machine # [ 109.359523] systemd[583]: Reloading finished in 183 ms.2316machine # [ 109.376180] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2317machine # [ 109.386505] systemd[583]: Stopped Run user-specific NixOS activation.2318machine # [ 109.389090] systemd[583]: Stopping Run user-specific NixOS activation...2319machine # [ 109.392090] systemd[583]: Starting Run user-specific NixOS activation...2320machine # [ 109.437926] systemd[583]: Finished Run user-specific NixOS activation.2321machine # [ 109.442140] systemd[1]: Stopped target Reactivate sysinit units.2322machine # [ 109.443351] systemd[1]: Stopping Reactivate sysinit units...2323machine # [ 109.445971] systemd[1]: Reached target Reactivate sysinit units.2324machine # [ 109.448274] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2325machine # [ 109.449565] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2326machine # [ 109.453162] systemd[1]: Reached target Local File Systems.2327machine # [ 109.456296] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2328machine # [ 109.460578] systemd[1]: Starting Create SUID/SGID Wrappers...2329machine # [ 109.461455] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2330machine # [ 109.462621] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672331machine # [ 109.466217] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2332machine # [ 109.468620] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2333machine # [ 109.478010] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2334machine # [ 109.481747] systemd[1]: Reached target Remote File Systems.2335machine # [ 109.782990] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2336machine # [ 109.785173] systemd[1]: Finished Create SUID/SGID Wrappers.2337machine # [ 109.790105] systemd[1]: Starting linger-users.service...2338machine # [ 109.850166] systemd[1]: linger-users.service: Deactivated successfully.2339machine # [ 109.853008] systemd[1]: Finished linger-users.service.2340machine # [ 110.110967] nixos[4615]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test2341machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.86 seconds)2342machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2343machine # [ 110.191480] sudo[4752]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2344machine # [ 110.208655] sudo[4752]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2345machine # [ 110.222104] sudo[4752]: 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.10 seconds)2347machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service2348machine # [ 110.270919] sudo[4758]: 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 # [ 110.285605] sudo[4758]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2350machine # [ 110.298467] sudo[4758]: 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.08 seconds)2352machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2353machine # [ 110.343048] sudo[4764]: 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 # [ 110.356358] sudo[4764]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2355machine # [ 110.368232] sudo[4764]: 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.07 seconds)2357machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2358machine # [ 110.411099] sudo[4770]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2359machine # [ 110.423720] sudo[4770]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2360machine # [ 110.433439] systemd[583]: Reload requested from client PID 4775 ('systemctl')...2361machine # [ 110.435999] systemd[583]: Reloading...2362machine # [ 110.625236] systemd[583]: Reloading finished in 188 ms.2363machine # [ 110.640147] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2364machine # [ 110.653985] sudo[4770]: 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.29 seconds)2366machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2367machine # [ 110.698774] sudo[4780]: 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 # [ 110.711272] sudo[4780]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2369machine # [ 110.727083] systemd[583]: Starting migrated.service...2370machine # [ 110.760524] systemd[583]: Finished migrated.service.2371machine # [ 110.764713] sudo[4780]: 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.11 seconds)2373machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2374machine # [ 110.808578] sudo[4788]: 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 # [ 110.822012] sudo[4788]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2376machine # [ 110.835045] sudo[4788]: 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 # [ 110.883093] sudo[4794]: 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 # [ 110.895889] sudo[4794]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2381machine # [ 110.908124] sudo[4794]: 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 # [ 110.952768] sudo[4800]: 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 # [ 110.965900] sudo[4800]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2386machine # [ 110.987702] sudo[4800]: 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/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12389machine # [ 111.500540] nixos[4809]: switching to system configuration /nix/store/2q6v66pn7bsy861rkivfp4x66plzgmb9-nixos-system-machine-test2390machine # [ 111.503279] systemd[1]: Stopped target Local File Systems.2391machine # [ 111.505510] systemd[1]: Stopped target Remote File Systems.2392machine # [ 111.931863] systemd[1]: Reload requested from client PID 4809 ('.switch-to-conf') (unit backdoor.service)...2393machine # [ 111.934114] systemd[1]: Reloading...2394machine # [ 112.501201] systemd[1]: Reloading finished in 565 ms.2395machine # [ 112.633143] systemd[583]: Reexecution requested from client PID 4877 ('switch-to-confi')...2396machine # [ 112.636683] systemd[583]: Reexecuting.2397machine # [ 112.681094] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2398machine # [ 112.865035] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2399machine # [ 112.871671] systemd[583]: Reload requested from client PID 4877 ('switch-to-confi')...2400machine # [ 112.875073] systemd[583]: Reloading...2401machine # [ 113.055793] systemd[583]: Reloading finished in 181 ms.2402machine # [ 113.068140] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2403machine # [ 113.077605] systemd[583]: Stopped Run user-specific NixOS activation.2404machine # [ 113.078572] systemd[583]: Stopping Run user-specific NixOS activation...2405machine # [ 113.081225] systemd[583]: Starting Run user-specific NixOS activation...2406machine # [ 113.128248] systemd[583]: Finished Run user-specific NixOS activation.2407machine # [ 113.130210] systemd[583]: Reload requested from client PID 4877 ('switch-to-confi')...2408machine # [ 113.132886] systemd[583]: Reloading...2409machine # [ 113.315340] systemd[583]: Reloading finished in 182 ms.2410machine # [ 113.328148] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2411machine # [ 113.342064] systemd[1]: Stopped target Reactivate sysinit units.2412machine # [ 113.344845] systemd[1]: Stopping Reactivate sysinit units...2413machine # [ 113.346245] systemd[1]: Reached target Reactivate sysinit units.2414machine # [ 113.347990] systemd[1]: Reached target Remote File Systems.2415machine # [ 113.351663] systemd[1]: Reached target Local File Systems.2416machine # [ 113.354299] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2417machine # [ 113.360932] systemd[1]: Starting Create SUID/SGID Wrappers...2418machine # [ 113.361818] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2419machine # [ 113.362997] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672420machine # [ 113.365935] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2421machine # [ 113.376456] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2422machine # [ 113.377794] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2423machine # [ 113.378948] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2424machine # [ 113.380331] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2425machine # [ 113.677679] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2426machine # [ 113.680682] systemd[1]: Finished Create SUID/SGID Wrappers.2427machine # [ 113.684684] systemd[1]: Starting linger-users.service...2428machine # [ 113.741257] systemd[1]: linger-users.service: Deactivated successfully.2429machine # [ 113.743682] systemd[1]: Finished linger-users.service.2430machine # [ 114.003016] nixos[4809]: finished switching to system configuration /nix/store/2q6v66pn7bsy861rkivfp4x66plzgmb9-nixos-system-machine-test2431machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.02 seconds)2432machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2433machine # [ 114.081251] sudo[4950]: 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 # [ 114.098781] sudo[4950]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2435machine # [ 114.123891] sudo[4950]: 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.11 seconds)2437machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2438machine # [ 114.169053] sudo[4956]: 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 # [ 114.182363] sudo[4956]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2440machine # [ 114.193834] sudo[4956]: 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.07 seconds)2442machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd2443machine # [ 114.235209] sudo[4962]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd2444machine # [ 114.247646] sudo[4962]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2445machine # [ 114.259146] sudo[4962]: pam_unix(sudo:session): session closed for user usertest2446machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.07 seconds)2447machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2448machine # [ 114.300761] sudo[4968]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2449machine # [ 114.312993] sudo[4968]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2450machine # [ 114.322496] systemd[583]: Reload requested from client PID 4973 ('systemctl')...2451machine # [ 114.324998] systemd[583]: Reloading...2452machine # [ 114.508512] systemd[583]: Reloading finished in 181 ms.2453machine # [ 114.524199] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2454machine # [ 114.537711] sudo[4968]: 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.28 seconds)2456machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service2457machine # [ 114.581505] sudo[4978]: 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 # [ 114.594021] sudo[4978]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2459machine # [ 114.607218] systemd[583]: Stopped migrated.service.2460machine # [ 114.611205] sudo[4978]: 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.07 seconds)2462machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&12463machine # [ 115.110466] nixos[4987]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test2464machine # [ 115.113179] systemd[1]: Stopped target Local File Systems.2465machine # [ 115.115285] systemd[1]: Stopped target Remote File Systems.2466machine # [ 115.539734] systemd[1]: Reload requested from client PID 4987 ('.switch-to-conf') (unit backdoor.service)...2467machine # [ 115.541991] systemd[1]: Reloading...2468machine # [ 116.109275] systemd[1]: Reloading finished in 565 ms.2469machine # [ 116.242507] systemd[583]: Reexecution requested from client PID 5056 ('switch-to-confi')...2470machine # [ 116.246184] systemd[583]: Reexecuting.2471machine # [ 116.285371] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2472machine # [ 116.467027] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2473machine # [ 116.473921] systemd[583]: Reload requested from client PID 5056 ('switch-to-confi')...2474machine # [ 116.476097] systemd[583]: Reloading...2475machine # [ 116.655017] systemd[583]: Reloading finished in 177 ms.2476machine # [ 116.672159] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2477machine # [ 116.682592] systemd[583]: Stopped Run user-specific NixOS activation.2478machine # [ 116.684864] systemd[583]: Stopping Run user-specific NixOS activation...2479machine # [ 116.687634] systemd[583]: Starting Run user-specific NixOS activation...2480machine # [ 116.730532] systemd[583]: Finished Run user-specific NixOS activation.2481machine # [ 116.734861] systemd[1]: Stopped target Reactivate sysinit units.2482machine # [ 116.736683] systemd[1]: Stopping Reactivate sysinit units...2483machine # [ 116.737899] systemd[1]: Reached target Reactivate sysinit units.2484machine # [ 116.740477] systemd[1]: Reached target Remote File Systems.2485machine # [ 116.744802] systemd[1]: Starting linger-users.service...2486machine # [ 116.754044] systemd[1]: Reached target Local File Systems.2487machine # [ 116.754862] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2488machine # [ 116.760177] systemd[1]: Starting Create SUID/SGID Wrappers...2489machine # [ 116.761049] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2490machine # [ 116.762228] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672491machine # [ 116.773737] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2492machine # [ 116.778124] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2493machine # [ 116.780218] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2494machine # [ 116.781563] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2495machine # [ 116.783275] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2496machine # [ 116.839925] systemd[1]: linger-users.service: Deactivated successfully.2497machine # [ 116.841148] systemd[1]: Finished linger-users.service.2498machine # [ 117.094414] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2499machine # [ 117.097531] systemd[1]: Finished Create SUID/SGID Wrappers.2500machine # [ 117.353985] nixos[4987]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test2501machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.75 seconds)2502(finished: subtest: user services, in 83.04 seconds)2503(finished: run the VM test script, in 118.29 seconds)2504test script finished in 118.36s2505cleanup2506kill 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.17 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