vm-test-run-switch-test-user
checks.aarch64-linux.switchTest-user
· build #24
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7machine: waiting for unit multi-user.target8machine: waiting for the VM to finish booting9machine: starting vm10machine # Disk image does not exist, creating the virtualisation disk image...11machine: QEMU running (pid 45)12machine # Formatting '/build/vm-state-machine/tmp.oQtMXCUsw5', 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: b2c75f72-a26d-4b24-b1be-9520782f21b017machine # Superblock backups stored on blocks:18machine # 32768, 98304, 163840, 22937619machine # 20machine # Allocating group tables: 0/8 done21machine # Writing inode tables: 0/8 done22machine # Creating journal (8192 blocks): done23machine # Writing superblocks and filesystem accounting information: 0/8 done24machine # 25machine # Virtualisation disk image created.26machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]27machine # [ 0.000000] Linux version 6.18.49 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Wed Sep 2 12:31:51 UTC 202628machine # [ 0.000000] KASLR enabled29machine # [ 0.000000] random: crng init done30machine # [ 0.000000] Machine model: linux,dummy-virt31machine # [ 0.000000] efi: UEFI not found.32machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT33machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]34machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]35machine # [ 0.000000] Zone ranges:36machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]37machine # [ 0.000000] DMA32 empty38machine # [ 0.000000] Normal empty39machine # [ 0.000000] Device empty40machine # [ 0.000000] Movable zone start for each node41machine # [ 0.000000] Early memory node ranges42machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]43machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]44machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000045machine # [ 0.000000] psci: probing for conduit method from DT.46machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.47machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs48machine # [ 0.000000] psci: Trusted OS migration not required49machine # [ 0.000000] psci: SMC Calling Convention v1.150machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)51machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u31129652machine # [ 0.000000] Detected PIPT I-cache on CPU053machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)54machine # [ 0.000000] CPU features: detected: GICv3 CPU interface55machine # [ 0.000000] CPU features: detected: Spectre-v456machine # [ 0.000000] CPU features: detected: Spectre-BHB57machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_3858machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_2359machine # [ 0.000000] alternatives: applying boot alternatives60machine # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/init regInfo=/nix/store/x77z8qw5cas4sz1l8bmmlfy1kkaqf9hc-closure-info/registration console=ttyAMA0,115200n8 console=tty061machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/x77z8qw5cas4sz1l8bmmlfy1kkaqf9hc-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 @0x0000000044d1000098machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d2000099machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.100machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns101machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).102machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns103machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns104machine # [ 0.000028] arm-pv: using stolen time PV105machine # [ 0.000395] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)106machine # [ 0.000561] Console: colour dummy device 80x25107machine # [ 0.000568] printk: legacy console [tty0] enabled108machine # [ 0.000747] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)109machine # [ 0.000754] pid_max: default: 32768 minimum: 301110machine # [ 0.000853] LSM: initializing lsm=capability,landlock,yama,bpf,ima111machine # [ 0.001015] landlock: Up and running.112machine # [ 0.001018] Yama: becoming mindful.113machine # [ 0.001462] LSM support for eBPF active114machine # [ 0.001584] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)115machine # [ 0.001603] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)116machine # [ 0.002635] cacheinfo: Unable to detect cache hierarchy for CPU 0117machine # [ 0.003290] rcu: Hierarchical SRCU implementation.118machine # [ 0.003294] rcu: Max phase no-delay instances is 1000.119machine # [ 0.004446] fsl-mc MSI: its@8080000 domain created120machine # [ 0.004533] EFI services will not be available.121machine # [ 0.004596] smp: Bringing up secondary CPUs ...122machine # [ 0.004603] smp: Brought up 1 node, 1 CPU123machine # [ 0.004606] SMP: Total of 1 processors activated.124machine # [ 0.004609] CPU: All CPU(s) started at EL1125machine # [ 0.004620] CPU features: detected: Branch Target Identification126machine # [ 0.004624] CPU features: detected: ARMv8.4 Translation Table Level127machine # [ 0.004627] CPU features: detected: Instruction cache invalidation not required for I/D coherence128machine # [ 0.004631] CPU features: detected: Data cache clean to the PoU not required for I/D coherence129machine # [ 0.004635] CPU features: detected: Common not Private translations130machine # [ 0.004638] CPU features: detected: CRC32 instructions131machine # [ 0.004640] CPU features: detected: Data cache clean to Point of Deep Persistence132machine # [ 0.004644] CPU features: detected: Data cache clean to Point of Persistence133machine # [ 0.004647] CPU features: detected: Data independent timing control (DIT)134machine # [ 0.004650] CPU features: detected: E0PD135machine # [ 0.004653] CPU features: detected: Enhanced Counter Virtualization136machine # [ 0.004656] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)137machine # [ 0.004660] CPU features: detected: Enhanced Virtualization Traps138machine # [ 0.004663] CPU features: detected: Fine Grained Traps139machine # [ 0.004666] CPU features: detected: Generic authentication (architected QARMA5 algorithm)140machine # [ 0.004671] CPU features: detected: RCpc load-acquire (LDAPR)141machine # [ 0.004673] CPU features: detected: LSE atomic instructions142machine # [ 0.004676] CPU features: detected: Privileged Access Never143machine # [ 0.004679] CPU features: detected: PMUv3144machine # [ 0.004681] CPU features: detected: RAS Extension Support145machine # [ 0.004684] CPU features: detected: RASv1p1 Extension Support146machine # [ 0.004687] CPU features: detected: Random Number Generator147machine # [ 0.004689] CPU features: detected: Speculation barrier (SB)148machine # [ 0.004692] CPU features: detected: Stage-2 Force Write-Back149machine # [ 0.004694] CPU features: detected: TLB range maintenance instructions150machine # [ 0.004698] CPU features: detected: Speculative Store Bypassing Safe (SSBS)151machine # [ 0.004734] alternatives: applying system-wide alternatives152machine # [ 0.007619] CPU features: detected: BBM Level 2 without TLB conflict abort153machine # [ 0.007782] Memory: 894292K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 112964K reserved, 32768K cma-reserved)154machine # [ 0.008099] devtmpfs: initialized155machine # [ 0.009777] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)156machine # [ 0.009798] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).157machine # [ 0.009980] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL158machine # [ 0.009985] 0 pages in range for non-PLT usage159machine # [ 0.009985] 508288 pages in range for PLT usage160machine # [ 0.010073] pinctrl core: initialized pinctrl subsystem161machine # [ 0.010806] DMI not present or invalid.162machine # [ 0.013813] NET: Registered PF_NETLINK/PF_ROUTE protocol family163machine # [ 0.015975] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations164machine # [ 0.016142] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations165machine # [ 0.016296] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations166machine # [ 0.016316] audit: initializing netlink subsys (disabled)167machine # [ 0.016866] thermal_sys: Registered thermal governor 'fair_share'168machine # [ 0.016868] thermal_sys: Registered thermal governor 'bang_bang'169machine # [ 0.016872] thermal_sys: Registered thermal governor 'step_wise'170machine # [ 0.016874] thermal_sys: Registered thermal governor 'user_space'171machine # [ 0.016879] thermal_sys: Registered thermal governor 'power_allocator'172machine # [ 0.016913] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1173machine # [ 0.016923] cpuidle: using governor ladder174machine # [ 0.016929] cpuidle: using governor menu175machine # [ 0.017115] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.176machine # [ 0.017130] ASID allocator initialised with 65536 entries177machine # [ 0.018223] Serial: AMBA PL011 UART driver178machine # [ 0.023280] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1179machine # [ 0.023415] printk: console [ttyAMA0] enabled180machine # [ 0.146535] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages181machine # [ 0.146551] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page182machine # [ 0.146557] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages183machine # [ 0.146561] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page184machine # [ 0.146566] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages185machine # [ 0.146570] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page186machine # [ 0.146574] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages187machine # [ 0.146578] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page188machine # [ 0.154098] fbcon: Taking over console189machine # [ 0.154112] ACPI: Interpreter disabled.190machine # [ 0.155948] iommu: Default domain type: Translated191machine # [ 0.155958] iommu: DMA domain TLB invalidation policy: strict mode192machine # [ 0.157599] SCSI subsystem initialized193machine # [ 0.162861] usbcore: registered new interface driver usbfs194machine # [ 0.162891] usbcore: registered new interface driver hub195machine # [ 0.162907] usbcore: registered new device driver usb196machine # [ 0.163167] pps_core: LinuxPPS API ver. 1 registered197machine # [ 0.163173] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>198machine # [ 0.163183] PTP clock support registered199machine # [ 0.163229] EDAC MC: Ver: 3.0.0200machine # [ 0.167763] scmi_core: SCMI protocol bus registered201machine # [ 0.168687] FPGA manager framework202machine # [ 0.169603] vgaarb: loaded203machine # [ 0.170191] clocksource: Switched to clocksource arch_sys_counter204machine # [ 0.173929] VFS: Disk quotas dquot_6.6.0205machine # [ 0.173963] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)206machine # [ 0.177445] netfs: FS-Cache loaded207machine # [ 0.177560] pnp: PnP ACPI: disabled208machine # [ 0.181397] NET: Registered PF_INET protocol family209machine # [ 0.181545] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)210machine # [ 0.209949] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)211machine # [ 0.209985] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)212machine # [ 0.210008] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)213machine # [ 0.210051] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)214machine # [ 0.210126] TCP: Hash tables configured (established 8192 bind 8192)215machine # [ 0.214783] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)216machine # [ 0.214816] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)217machine # [ 0.214840] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)218machine # [ 0.214916] NET: Registered PF_UNIX/PF_LOCAL protocol family219machine # [ 0.214948] NET: Registered PF_XDP protocol family220machine # [ 0.214967] PCI: CLS 0 bytes, default 64221machine # [ 0.215192] Trying to unpack rootfs image as initramfs...222machine # [ 0.227107] kvm [1]: HYP mode not available223machine # [ 0.318678] Initialise system trusted keyrings224machine # [ 0.319390] workingset: timestamp_bits=42 max_order=18 bucket_order=0225machine # [ 0.320606] squashfs: version 4.0 (2009/01/31) Phillip Lougher226machine # [ 0.321361] 9p: Installing v9fs 9p2000 file system support227machine # [ 0.341852] Key type asymmetric registered228machine # [ 0.341867] Asymmetric key parser 'x509' registered229machine # [ 0.341931] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)230machine # [ 0.350244] io scheduler mq-deadline registered231machine # [ 0.350259] io scheduler kyber registered232machine # [ 0.355060] pl061_gpio 9030000.pl061: PL061 GPIO chip registered233machine # [ 0.362216] ledtrig-cpu: registered to indicate activity on CPUs234machine # [ 0.362620] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:235machine # [ 0.362639] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000236machine # [ 0.362654] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000237machine # [ 0.362662] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000238machine # [ 0.362689] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits239machine # [ 0.362723] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]240machine # [ 0.362793] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00241machine # [ 0.362803] pci_bus 0000:00: root bus resource [bus 00-ff]242machine # [ 0.362809] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]243machine # [ 0.362814] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]244machine # [ 0.362820] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]245machine # [ 0.362895] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint246machine # [ 0.363337] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint247machine # [ 0.363520] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]248machine # [ 0.363537] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]249machine # [ 0.363566] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]250machine # [ 0.363582] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]251machine # [ 0.364035] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint252machine # [ 0.364216] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]253machine # [ 0.364231] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]254machine # [ 0.364261] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]255machine # [ 0.364701] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint256machine # [ 0.364880] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]257machine # [ 0.364895] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]258machine # [ 0.364925] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]259machine # [ 0.365363] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint260machine # [ 0.365540] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]261machine # [ 0.365556] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]262machine # [ 0.365585] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]263machine # [ 0.366032] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint264machine # [ 0.366233] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]265machine # [ 0.366249] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]266machine # [ 0.366278] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]267machine # [ 0.366719] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint268machine # [ 0.366898] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]269machine # [ 0.366914] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]270machine # [ 0.366943] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]271machine # [ 0.367402] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint272machine # [ 0.367582] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]273machine # [ 0.367597] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]274machine # [ 0.367627] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275machine # [ 0.367642] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]276machine # [ 0.368093] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint277machine # [ 0.368276] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]278machine # [ 0.368306] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279machine # [ 0.368744] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint280machine # [ 0.368927] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.368957] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.369333] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint283machine # [ 0.369512] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]284machine # [ 0.369751] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint285machine # [ 0.370016] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]286machine # [ 0.370033] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]287machine # [ 0.370062] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]288machine # [ 0.418572] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint289machine # [ 0.418768] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]290machine # [ 0.418786] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]291machine # [ 0.418815] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]292machine # [ 0.419392] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned293machine # [ 0.419404] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned294machine # [ 0.419409] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned295machine # [ 0.419452] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned296machine # [ 0.419498] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned297machine # [ 0.419543] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned298machine # [ 0.419589] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned299machine # [ 0.419635] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned300machine # [ 0.419681] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned301machine # [ 0.419727] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned302machine # [ 0.419774] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned303machine # [ 0.419820] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned304machine # [ 0.419915] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned305machine # [ 0.419968] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned306machine # [ 0.419989] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned307machine # [ 0.420010] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned308machine # [ 0.420031] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned309machine # [ 0.420052] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned310machine # [ 0.420076] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned311machine # [ 0.420098] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned312machine # [ 0.420120] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned313machine # [ 0.420141] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned314machine # [ 0.420163] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned315machine # [ 0.420186] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned316machine # [ 0.420208] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned317machine # [ 0.420229] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned318machine # [ 0.420249] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned319machine # [ 0.420270] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned320machine # [ 0.420291] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned321machine # [ 0.420312] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned322machine # [ 0.420333] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned323machine # [ 0.420355] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned324machine # [ 0.420376] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned325machine # [ 0.420398] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned326machine # [ 0.420423] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]327machine # [ 0.420432] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]328machine # [ 0.420438] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]329machine # [ 0.421567] pci 0000:00:0a.0: enabling device (0000 -> 0002)330machine # [ 0.480990] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)331machine # [ 0.487134] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)332machine # [ 0.489045] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)333machine # [ 0.499368] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)334machine # [ 0.501191] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)335machine # [ 0.511101] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)336machine # [ 0.513269] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)337machine # [ 0.517001] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)338machine # [ 0.523333] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)339machine # [ 0.530343] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)340machine # [ 0.532434] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)341machine # [ 0.551028] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled342machine # [ 0.553424] msm_serial: driver initialized343machine # [ 0.553554] SuperH (H)SCI(F) driver initialized344machine # [ 0.553606] STM32 USART driver initialized345machine # [ 0.583685] loop: module loaded346machine # [ 0.583860] virtio_blk virtio5: 1/0/0 default/read/poll queues347machine # [ 0.584548] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)348machine # [ 0.590734] megasas: 07.734.00.00-rc1349machine # [ 0.591476] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]350machine # [ 0.593242] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000351machine # [ 0.593265] Intel/Sharp Extended Query Table at 0x0031352machine # [ 0.598030] Using buffer write method353machine # [ 0.598094] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]354machine # [ 0.607267] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000355machine # [ 0.607296] Intel/Sharp Extended Query Table at 0x0031356machine # [ 0.608810] Using buffer write method357machine # [ 0.608836] Concatenating MTD devices:358machine # [ 0.608840] (0): "0.flash"359machine # [ 0.608844] (1): "0.flash"360machine # [ 0.608848] into device "0.flash"361machine # [ 0.821884] Freeing initrd memory: 26104K362machine # [ 0.827826] tun: Universal TUN/TAP device driver, 1.6363machine # [ 0.831380] thunder_xcv, ver 1.0364machine # [ 0.831417] thunder_bgx, ver 1.0365machine # [ 0.831439] nicpf, ver 1.0366machine # [ 0.831969] e1000: Intel(R) PRO/1000 Network Driver367machine # [ 0.831975] e1000: Copyright (c) 1999-2006 Intel Corporation.368machine # [ 0.832005] e1000e: Intel(R) PRO/1000 Network Driver369machine # [ 0.832013] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.370machine # [ 0.832037] igb: Intel(R) Gigabit Ethernet Network Driver371machine # [ 0.832043] igb: Copyright (c) 2007-2014 Intel Corporation.372machine # [ 0.832064] igbvf: Intel(R) Gigabit Virtual Function Network Driver373machine # [ 0.832070] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.374machine # [ 0.832201] sky2: driver version 1.30375machine # [ 0.833719] usbcore: registered new interface driver usb-storage376machine # [ 0.833797] usbcore: registered new interface driver usbserial_generic377machine # [ 0.833810] usbserial: USB Serial support registered for generic378machine # [ 0.834824] ehci-pci 0000:00:0a.0: EHCI Host Controller379machine # [ 0.834848] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1380machine # [ 0.835042] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000381machine # [ 0.846655] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00382machine # [ 0.846958] hub 1-0:1.0: USB hub found383machine # [ 0.846972] hub 1-0:1.0: 6 ports detected384machine # [ 0.849169] hv_vmbus: registering driver hyperv_keyboard385machine # [ 0.850647] rtc-pl031 9010000.pl031: registered as rtc0386machine # [ 0.850671] rtc-pl031 9010000.pl031: setting system clock to 2026-09-06T17:54:04 UTC (1788717244)387machine # [ 0.850966] i2c_dev: i2c /dev entries driver388machine # [ 0.855840] sdhci: Secure Digital Host Controller Interface driver389machine # [ 0.855852] sdhci: Copyright(c) Pierre Ossman390machine # [ 0.856118] Synopsys Designware Multimedia Card Interface Driver391machine # [ 0.856482] sdhci-pltfm: SDHCI platform and OF driver helper392machine # [ 0.858086] hid: raw HID events driver (C) Jiri Kosina393machine # [ 0.861626] usbcore: registered new interface driver usbhid394machine # [ 0.861637] usbhid: USB HID core driver395machine # [ 0.863887] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available396machine # [ 0.865390] drop_monitor: Initializing network drop monitor service397machine # [ 0.865533] NET: Registered PF_INET6 protocol family398machine # [ 0.868532] Segment Routing with IPv6399machine # [ 0.868549] In-situ OAM (IOAM) with IPv6400machine # [ 0.868586] NET: Registered PF_PACKET protocol family401machine # [ 0.870377] 9pnet: Installing 9P2000 support402machine # [ 0.872452] Key type dns_resolver registered403machine # [ 0.879180] registered taskstats version 1404machine # [ 0.879330] Loading compiled-in X.509 certificates405machine # [ 0.887540] Demotion targets for Node 0: null406machine # [ 0.887635] Key type .fscrypt registered407machine # [ 0.887642] Key type fscrypt-provisioning registered408machine # [ 0.887729] ima: No TPM chip found, activating TPM-bypass!409machine # [ 0.887748] ima: Allocated hash algorithm: sha1410machine # [ 0.887769] ima: No architecture policies found411machine # [ 0.891694] input: gpio-keys as /devices/platform/gpio-keys/input/input0412machine # [ 0.909124] clk: Disabling unused clocks413machine # [ 0.909148] PM: genpd: Disabling unused power domains414machine # [ 0.913324] Freeing unused kernel memory: 4736K415machine # [ 0.913545] Run /init as init process416machine # [ 0.928320] systemd[1]: Successfully made /usr/ read-only.417machine # [ 1.094263] usb 1-1: new high-speed USB device number 2 using ehci-pci418machine # [ 1.246389] 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.263067] systemd[1]: systemd 262-9457f81 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)420machine # [ 1.274977] systemd[1]: Detected virtualization qemu.421machine # [ 1.276925] systemd[1]: Detected architecture arm64.422machine # [ 1.278858] systemd[1]: Running in initrd.423machine # [ 1.281402] systemd[1]: Initializing machine ID from random generator.424machine # [ 1.284271] systemd[1]: Hostname set to <machine>.425machine # [ 1.334437] 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.425571] systemd[1]: bpf-restrict-fs: LSM BPF program attached427machine # [ 1.458252] usb 1-2: new high-speed USB device number 3 using ehci-pci428machine # [ 1.477259] systemd[1]: Queued start job for default target Initrd Default Target.429machine # [ 1.484359] systemd[1]: Created slice Slice /system/modprobe.430machine # [ 1.485412] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.431machine # [ 1.486619] systemd[1]: Expecting device /dev/disk/by-label/nixos...432machine # [ 1.487631] systemd[1]: Reached target Path Units.433machine # [ 1.488361] systemd[1]: Reached target Slice Units.434machine # [ 1.489103] systemd[1]: Reached target Swaps.435machine # [ 1.489772] systemd[1]: Reached target Timer Units.436machine # [ 1.490693] systemd[1]: Listening on D-Bus System Message Bus Socket.437machine # [ 1.491866] systemd[1]: Listening on Journal Socket (/dev/log).438machine # [ 1.492881] systemd[1]: Listening on Journal Sockets.439machine # [ 1.493725] systemd[1]: Listening on udev Kernel Socket.440machine # [ 1.494573] systemd[1]: Reached target Socket Units.441machine # [ 1.496734] systemd[1]: Starting Create List of Static Device Nodes...442machine # [ 1.506884] systemd[1]: Starting Load Kernel Module 9pnet_virtio...443machine # [ 1.507017] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs444machine # [ 1.518442] systemd[1]: Mounting Kernel Configuration File System...445machine # [ 1.538365] systemd[1]: Starting Journal Service...446machine # [ 1.540452] systemd[1]: Starting Load Kernel Modules...447machine # [ 1.541236] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os448machine # [ 1.551001] systemd[1]: Starting Coldplug All udev Devices...449machine # [ 1.570317] systemd[1]: Finished Create List of Static Device Nodes.450machine # [ 1.571510] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.451machine # [ 1.571794] systemd[1]: Finished Load Kernel Module 9pnet_virtio.452machine # [ 1.572013] systemd[1]: Mounted Kernel Configuration File System.453machine # [ 1.579198] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...454machine # [ 1.613376] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.455machine # [ 1.618962] systemd-journald[73]: Collecting audit messages is disabled.456machine # [ 1.620981] 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/input2457machine # [ 1.624175] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0458machine # [ 1.634283] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev459machine # [ 1.638646] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0460machine # [ 1.638880] [drm] features: -virgl +edid -resource_blob -host_visible461machine # [ 1.638890] [drm] features: -context_init462machine # [ 1.639580] [drm] number of scanouts: 1463machine # [ 1.639598] [drm] number of cap sets: 0464machine # [ 1.644489] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.465machine # [ 1.651520] systemd[1]: Starting Create Static Device Nodes in /dev...466machine # [ 1.658887] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic467machine # [ 1.658905] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0468machine # [ 1.692924] Console: switching to colour frame buffer device 160x50469machine # [ 1.702740] systemd[1]: Finished Create Static Device Nodes in /dev.470machine # [ 1.702895] systemd[1]: Reached target Preparation for Local File Systems.471machine # [ 1.702915] systemd[1]: Reached target Local File Systems.472machine # [ 1.710505] systemd[1]: Starting Rule-based Manager for Device Events and Files...473machine # [ 1.727420] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device474machine # [ 1.743329] systemd[1]: Finished Load Kernel Modules.475machine # [ 1.745340] systemd[1]: Starting Apply Kernel Variables...476machine # [ 1.781818] systemd[1]: Started Journal Service.477machine # [ 1.780400] systemd-modules-load[74]: Inserted module 'dm_mod'478machine # [ 1.781614] systemd-modules-load[74]: Module 'virtio_balloon' is built in479machine # [ 1.791319] systemd-modules-load[74]: Module 'virtio_console' is built in480machine # [ 1.800790] systemd-modules-load[74]: Inserted module 'virtio_gpu'481machine # [ 1.801806] systemd-modules-load[74]: Module 'virtio_rng' is built in482machine # [ 1.802843] systemd[1]: Starting Create System Files and Directories...483machine # [ 1.803860] systemd[1]: Finished Apply Kernel Variables.484machine # [ 1.816673] systemd-udevd[80]: Using default interface naming scheme 'v261'.485machine # [ 1.820840] systemd[1]: Finished Create System Files and Directories.486machine # [ 1.841190] systemd[1]: Started Rule-based Manager for Device Events and Files.487machine # [ 1.915855] systemd[1]: Starting Virtual Console Setup...488machine # [ 1.969095] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.489machine # [ 1.977045] systemd[1]: Finished Virtual Console Setup.490machine # [ 2.593875] systemd[1]: Finished Coldplug All udev Devices.491machine # [ 2.594852] systemd[1]: Reached target System Initialization.492machine # [ 2.595663] systemd[1]: Reached target Basic System.493machine # [ 2.734697] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.494machine # [ 2.768419] (udev-worker)[102]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.495machine # [ 2.772897] (udev-worker)[102]: Network interface NamePolicy= disabled on kernel command line.496machine # [ 2.812504] systemd[1]: Found device /dev/disk/by-label/nixos.497machine # [ 2.815663] systemd[1]: Reached target Initrd Root Device.498machine # [ 2.820112] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...499machine # [ 2.862292] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks500machine # [ 2.869888] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.501machine # [ 2.879759] systemd[1]: Mounting /sysroot...502machine # [ 2.929185] EXT4-fs (vda): mounted filesystem b2c75f72-a26d-4b24-b1be-9520782f21b0 r/w with ordered data mode. Quota mode: none.503machine # [ 2.916843] systemd[1]: Mounted /sysroot.504machine # [ 2.918751] systemd[1]: Reached target Initrd Root File System.505machine # [ 2.924664] systemd[1]: Starting Mountpoints Configured in the Real Root...506machine # [ 2.947851] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.507machine # [ 2.954003] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...508machine # [ 2.958296] systemd[1]: Reloading...509machine # [ 3.138766] systemd[1]: Reloading finished in 181 ms.510machine # [ 3.171405] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...511machine # [ 3.176750] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...512machine # [ 3.183939] systemd[1]: Starting Load Kernel Module 9pnet_virtio...513machine # [ 3.190714] systemd[1]: initrd-parse-etc.service: Deactivated successfully.514machine # [ 3.198692] systemd[1]: Finished Mountpoints Configured in the Real Root.515machine # [ 3.200951] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.516machine # [ 3.209874] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.517machine # [ 3.212085] systemd[1]: Finished Load Kernel Module 9pnet_virtio.518machine # [ 3.545492] systemd[1]: Mounting /sysroot/nix/.ro-store...519machine # [ 3.562226] systemd[1]: Mounting /sysroot/nix/.rw-store...520machine # [ 3.568604] systemd[1]: Mounting /sysroot/run...521machine # [ 3.598593] systemd[1]: Mounting /sysroot/tmp/shared...522machine # [ 3.609680] systemd[1]: Mounting /sysroot/tmp/xchg...523machine # [ 3.623732] systemd[1]: Mounted /sysroot/nix/.rw-store.524machine # [ 3.638086] systemd[1]: Mounted /sysroot/nix/.ro-store.525machine # [ 3.657036] systemd[1]: Mounted /sysroot/run.526machine # [ 3.665563] systemd[1]: Starting rw-sysroot-nix-store.service...527machine # [ 3.679167] systemd[1]: Mounted /sysroot/tmp/shared.528machine # [ 3.689185] systemd[1]: Mounted /sysroot/tmp/xchg.529machine # [ 3.696589] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.530machine # [ 3.698612] systemd[1]: Finished rw-sysroot-nix-store.service.531machine # [ 3.702628] systemd[1]: Mounting /sysroot/nix/store...532machine # [ 3.734841] systemd[1]: Mounted /sysroot/nix/store.533machine # [ 3.735903] systemd[1]: Reached target Initrd File Systems.534machine # [ 3.740081] systemd[1]: Starting Find NixOS closure...535machine # [ 3.747245] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...536machine # [ 3.778675] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.537machine # [ 3.798915] systemd[1]: Finished Find NixOS closure.538machine # [ 3.799847] systemd[1]: Reached target Initrd Default Target.539machine # [ 3.802054] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...540machine # [ 3.830478] systemd[1]: Stopped target Initrd Default Target.541machine # [ 3.832173] systemd[1]: Stopped target Basic System.542machine # [ 3.833196] systemd[1]: Stopped target Initrd Root Device.543machine # [ 3.836092] systemd[1]: Stopped target Path Units.544machine # [ 3.837596] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.545machine # [ 3.840169] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.546machine # [ 3.843299] systemd[1]: Stopped target Slice Units.547machine # [ 3.846124] systemd[1]: Stopped target Socket Units.548machine # [ 3.848119] systemd[1]: Stopped target System Initialization.549machine # [ 3.853147] systemd[1]: Stopped target Swaps.550machine # [ 3.854907] systemd[1]: Stopped target Timer Units.551machine # [ 3.856850] systemd[1]: dbus.socket: Deactivated successfully.552machine # [ 3.857703] systemd[1]: Closed D-Bus System Message Bus Socket.553machine # [ 3.864732] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.554machine # [ 3.869627] systemd[1]: Stopped Find NixOS closure.555machine # [ 3.871383] systemd[1]: Starting Load Kernel Module 9pnet_virtio...556machine # [ 3.876103] systemd[1]: Starting rw-sysroot-nix-store.service...557machine # [ 3.878113] systemd[1]: systemd-sysctl.service: Deactivated successfully.558machine # [ 3.881061] systemd[1]: Stopped Apply Kernel Variables.559machine # [ 3.883900] systemd[1]: systemd-modules-load.service: Deactivated successfully.560machine # [ 3.885989] systemd[1]: Stopped Load Kernel Modules.561machine # [ 3.887652] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.562machine # [ 3.895776] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.563machine # [ 3.900298] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.564machine # [ 3.901435] systemd[1]: Stopped Create System Files and Directories.565machine # [ 3.902294] systemd[1]: Stopped target Local File Systems.566machine # [ 3.903052] systemd[1]: Stopped target Preparation for Local File Systems.567machine # [ 3.903963] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.568machine # [ 3.915684] systemd[1]: Stopped Coldplug All udev Devices.569machine # [ 3.918056] systemd[1]: Stopping Rule-based Manager for Device Events and Files...570machine # [ 3.919063] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.571machine # [ 3.927765] systemd[1]: Stopped Virtual Console Setup.572machine # [ 3.928738] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.573machine # [ 3.929725] systemd[1]: Finished rw-sysroot-nix-store.service.574machine # [ 3.930527] systemd[1]: initrd-cleanup.service: Deactivated successfully.575machine # [ 3.931441] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.576machine # [ 3.932778] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.577machine # [ 3.933781] systemd[1]: Finished Load Kernel Module 9pnet_virtio.578machine # [ 3.934619] systemd[1]: systemd-udevd.service: Deactivated successfully.579machine # [ 3.935531] systemd[1]: Stopped Rule-based Manager for Device Events and Files.580machine # [ 3.936637] systemd[1]: systemd-udevd.service: Consumed 1.108s CPU time over 2.230s wall clock time, 17.7M memory peak.581machine # [ 3.938030] systemd[1]: Starting Cleanup udev Database...582machine # [ 3.938785] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.583machine # [ 3.939822] systemd[1]: Stopped Create Static Device Nodes in /dev.584machine # [ 3.952244] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.585machine # [ 3.953399] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.586machine # [ 3.954602] systemd[1]: kmod-static-nodes.service: Deactivated successfully.587machine # [ 3.955571] systemd[1]: Stopped Create List of Static Device Nodes.588machine # [ 3.975587] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.589machine # [ 3.980171] systemd[1]: Finished Cleanup udev Database.590machine # [ 3.980923] systemd[1]: Reached target Switch Root.591machine # [ 3.981801] systemd[1]: Starting NixOS Activation...592machine # [ 4.137525] initrd-nixos-activation-start[173]: booting system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test593machine # [ 4.198981] initrd-nixos-activation-start[173]: running activation script...594machine # [ 4.607802] initrd-nixos-activation-start[196]: setting up /etc...595machine # [ 4.863157] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.596machine # [ 4.866107] systemd[1]: Finished NixOS Activation.597machine # [ 4.868139] systemd[1]: Starting Switch Root...598machine # [ 4.892079] systemd[1]: Switching root.599machine # [ 5.082910] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).600machine # [ 5.673171] systemd[1]: systemd 262-9457f81 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)601machine # [ 5.685638] systemd[1]: Detected virtualization qemu.602machine # [ 5.688750] systemd[1]: Detected architecture arm64.603machine # [ 5.692942] systemd[1]: Detected first boot.604machine # [ 5.700105] systemd[1]: Initializing machine ID from random generator.605machine # [ 5.869124] systemd[1]: bpf-restrict-fs: LSM BPF program attached606machine # [ 6.025765] systemd[1]: Applying preset policy.607machine # [ 6.505216] systemd[1]: Populated /etc with preset unit settings.608machine # [ 6.984684] systemd[1]: initrd-switch-root.service: Deactivated successfully.609machine # [ 6.985932] systemd[1]: Stopped initrd-switch-root.service.610machine # [ 6.989981] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.611machine # [ 6.994091] systemd[1]: Created slice Slice /system/getty.612machine # [ 6.995730] systemd[1]: Created slice User and Session Slice.613machine # [ 6.997986] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.614machine # [ 7.000443] systemd[1]: Started Forward Password Requests to Wall Directory Watch.615machine # [ 7.001781] systemd[1]: Expecting device /dev/hvc0...616machine # [ 7.003444] systemd[1]: Expecting device /dev/ttyAMA0...617machine # [ 7.005048] systemd[1]: Reached target Local Encrypted Volumes.618machine # [ 7.006821] systemd[1]: Stopped target initrd-fs.target.619machine # [ 7.009288] systemd[1]: Stopped target initrd-root-fs.target.620machine # [ 7.011394] systemd[1]: Stopped target initrd-switch-root.target.621machine # [ 7.013428] systemd[1]: Reached target Virtual Machines and Containers.622machine # [ 7.015639] systemd[1]: Reached target Path Units.623machine # [ 7.017498] systemd[1]: Reached target Remote File Systems.624machine # [ 7.019520] systemd[1]: Reached target Slice Units.625machine # [ 7.021369] systemd[1]: Reached target Swaps.626machine # [ 7.025285] systemd[1]: Listening on Query the User Interactively for a Password.627machine # [ 7.029975] systemd[1]: Listening on Process Core Dump Socket.628machine # [ 7.033821] systemd[1]: Listening on Credential Encryption/Decryption.629machine # [ 7.037576] systemd[1]: Listening on Factory Reset Management.630machine # [ 7.037980] systemd[1]: Listening on Hostname Service Socket.631machine # [ 7.043655] systemd[1]: Starting Journal Log Access Socket...632machine # [ 7.045608] systemd[1]: Listening on Journal Audit Socket.633machine # [ 7.049370] systemd[1]: Listening on Console Output Muting Service Socket.634machine # [ 7.050978] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.635machine # [ 7.052706] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os636machine # [ 7.055638] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki637machine # [ 7.066365] systemd[1]: Listening on Disk Repartitioning Service Socket.638machine # [ 7.067750] systemd[1]: Listening on udev Varlink Socket.639machine # [ 7.071847] systemd[1]: Mounting Huge Pages File System...640machine # [ 7.075906] systemd[1]: Mounting POSIX Message Queue File System...641machine # [ 7.086390] systemd[1]: Mounting Kernel Debug File System...642machine # [ 7.091745] systemd[1]: Mounting Kernel Trace File System...643machine # [ 7.103169] systemd[1]: Starting Create List of Static Device Nodes...644machine # [ 7.122275] systemd[1]: Starting Load Kernel Module 9pnet_virtio...645machine # [ 7.125049] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs646machine # [ 7.136630] systemd[1]: Mounting Kernel Configuration File System...647machine # [ 7.138149] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm648machine # [ 7.141131] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore649machine # [ 7.153245] systemd[1]: Starting Load Kernel Module fuse...650machine # [ 7.154805] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67651machine # [ 7.190479] systemd[1]: Starting Journal Service...652machine # [ 7.228605] systemd[1]: Starting Load Kernel Modules...653machine # [ 7.253271] fuse: init (API version 7.45)654machine # [ 7.261155] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...655machine # [ 7.286401] systemd[1]: Starting Remount Root and Kernel File Systems...656machine # [ 7.293946] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os657machine # [ 7.311907] systemd[1]: Starting Coldplug All udev Devices...658machine # [ 7.324002] systemd[1]: Listening on Journal Log Access Socket.659machine # [ 7.334003] systemd[1]: Mounted Huge Pages File System.660machine # [ 7.339480] systemd[1]: Mounted POSIX Message Queue File System.661machine # [ 7.344397] systemd[1]: Mounted Kernel Debug File System.662machine # [ 7.347747] systemd[1]: Mounted Kernel Trace File System.663machine # [ 7.354117] systemd[1]: Finished Create List of Static Device Nodes.664machine # [ 7.357285] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.665machine # [ 7.364972] systemd[1]: Finished Load Kernel Module 9pnet_virtio.666machine # [ 7.368771] systemd[1]: Mounted Kernel Configuration File System.667machine # [ 7.373499] systemd-journald[269]: Collecting audit messages is enabled.668machine # [ 7.375969] systemd[1]: modprobe@fuse.service: Deactivated successfully.669machine # [ 7.378890] systemd[1]: Finished Load Kernel Module fuse.670machine # [ 7.369876] systemd[1]: Queued start job for default target Multi-User System.671machine # [ 7.389081] systemd[1]: Finished Load Kernel Modules.672machine # [ 7.389662] systemd[1]: Started Journal Service.673machine # [ 7.376712] systemd[1]: systemd-journald.service: Deactivated successfully.674machine # [ 7.384566] systemd-modules-load[270]: Module 'atkbd' is built in675machine # [ 7.406393] EXT4-fs (vda): re-mounted b2c75f72-a26d-4b24-b1be-9520782f21b0.676machine # [ 7.391559] systemd-modules-load[270]: Module 'loop' is built in677machine # [ 7.400173] systemd[1]: Mounting FUSE Control File System...678machine # [ 7.404471] systemd[1]: Starting Firewall...679machine # [ 7.428063] systemd[1]: Starting Apply Kernel Variables...680machine # [ 7.435353] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...681machine # [ 7.447064] systemd-oomd[271]: No swap; memory pressure usage will be degraded682machine # [ 7.467776] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.683machine # [ 7.472663] systemd[1]: Finished Remount Root and Kernel File Systems.684machine # [ 7.546156] systemd[1]: Listening on Disk Image Download Service Socket.685machine # [ 7.560123] systemd[1]: Starting Flush Journal to Persistent Storage...686machine # [ 7.561469] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore687machine # [ 7.570045] systemd[1]: Starting Load/Save OS Random Seed...688machine # [ 7.573790] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os689machine # [ 7.575027] systemd[1]: Mounted FUSE Control File System.690machine # [ 7.595838] systemd[1]: Finished Apply Kernel Variables.691machine # [ 7.627342] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.692machine # [ 7.642022] systemd[1]: Starting Create Static Device Nodes in /dev...693machine # [ 7.660468] systemd[1]: Finished Load/Save OS Random Seed.694machine # [ 7.667041] systemd[1]: Reached target First Boot Complete.695machine # [ 7.690758] systemd-journald[269]: Received client request to flush runtime journal.696machine # [ 7.735041] systemd[1]: Finished Flush Journal to Persistent Storage.697machine # [ 7.755239] systemd[1]: Finished Create Static Device Nodes in /dev.698machine # [ 7.756325] systemd[1]: Reached target Preparation for Local File Systems.699machine # [ 7.762206] systemd[1]: Starting Rule-based Manager for Device Events and Files...700machine # [ 7.972214] systemd[1]: Mounting /run/wrappers...701machine # [ 7.988210] systemd-udevd[311]: Using default interface naming scheme 'v261'.702machine # [ 8.025541] systemd[1]: Mounted /run/wrappers.703machine # [ 8.026694] systemd[1]: Reached target Local File Systems.704machine # [ 8.030254] systemd[1]: Listening on Boot Loader Control Service Socket.705machine # [ 8.039700] systemd[1]: Starting register-nix-paths.service...706machine # [ 8.047972] systemd[1]: Starting Create SUID/SGID Wrappers...707machine # [ 8.059796] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.708machine # [ 8.066791] systemd[1]: Starting Save Transient machine-id to Disk...709machine # [ 8.081720] systemd[1]: Starting Create System Files and Directories...710machine # [ 8.214164] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.711machine # [ 8.227934] systemd[1]: Finished Save Transient machine-id to Disk.712machine # [ 8.282967] systemd[1]: Started Rule-based Manager for Device Events and Files.713machine # [ 8.291704] systemd[1]: Finished Create System Files and Directories.714machine # [ 8.317160] systemd[1]: Starting Rebuild Journal Catalog...715machine # [ 8.323285] systemd[1]: Starting Record System Boot/Shutdown in UTMP...716machine # [ 8.454490] systemd[1]: Finished Record System Boot/Shutdown in UTMP.717machine # [ 8.542733] systemd[1]: Finished Rebuild Journal Catalog.718machine # [ 8.557337] systemd[1]: Starting Update is Completed...719machine # [ 8.648848] systemd[1]: Finished Update is Completed.720machine # [ 9.071355] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.721machine # [ 9.075497] systemd[1]: Finished Create SUID/SGID Wrappers.722machine # [ 9.545553] systemd[1]: Finished register-nix-paths.service.723machine # [ 9.600736] systemd[1]: Finished Firewall.724machine # [ 9.653282] systemd[1]: Finished Coldplug All udev Devices.725machine # [ 9.654405] systemd[1]: Reached target System Initialization.726machine # [ 9.656201] systemd[1]: Started Discard unused filesystem blocks once a week.727machine # [ 9.660177] systemd[1]: Started Daily Cleanup of Temporary Directories.728machine # [ 9.661266] systemd[1]: Reached target Timer Units.729machine # [ 9.664503] systemd[1]: Listening on D-Bus System Message Bus Socket.730machine # [ 9.669108] systemd[1]: Listening on Nix Daemon Socket.731machine # [ 9.670339] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.732machine # [ 9.680216] systemd[1]: Reached target Socket Units.733machine # [ 9.680979] systemd[1]: Reached target Basic System.734machine # [ 9.681705] systemd[1]: Starting Import lastlog data into lastlog2 database...735machine # [ 9.682694] systemd[1]: Starting Name Service Cache Daemon (nsncd)...736machine # [ 9.685708] systemd[1]: Starting Post-Boot Actions...737machine # [ 9.709547] systemd[1]: Started Reset console on configuration changes.738machine # [ 9.718247] systemd[1]: Starting resolvconf update...739machine # [ 9.750414] systemd[1]: Starting D-Bus System Message Bus...740machine # [ 9.784986] systemd[1]: Finished Post-Boot Actions.741machine # [ 9.805414] nsncd[480]: Sep 06 17:54:13.472 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"742machine # [ 9.818534] systemd[1]: Started Name Service Cache Daemon (nsncd).743machine # [ 9.821426] systemd[1]: Reached target Host and Network Name Lookups.744machine # [ 9.825659] systemd[1]: Reached target User and Group Name Lookups.745machine # [ 9.844082] systemd[1]: Starting User Login Management...746machine # [ 9.869474] systemd[1]: Finished Import lastlog data into lastlog2 database.747machine # [ 9.937290] dbus-broker-launch[485]: Looking up NSS user entry for 'systemd-timesync'...748machine # [ 9.949666] dbus-broker-launch[485]: NSS returned no entry for 'systemd-timesync'749machine # [ 9.951182] dbus-broker-launch[485]: Invalid user-name in /nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"[ 9.973321] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3750machine # 751machine # [ 9.991594] systemd[1]: Started D-Bus System Message Bus.752machine # [ 10.029975] systemd[1]: Stopped target Host and Network Name Lookups.753machine # [ 10.033818] systemd[1]: Stopping Host and Network Name Lookups...754machine # [ 10.039547] systemd[1]: Stopped target User and Group Name Lookups.755machine # [ 10.042894] systemd[1]: Stopping User and Group Name Lookups...756machine # [ 10.048216] systemd-logind[501]: New seat seat0.757machine # [ 10.049828] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...758machine # [ 10.051818] dbus-broker-launch[485]: Ready759machine # [ 10.055715] systemd[1]: nscd.service: Deactivated successfully.760machine # [ 10.057491] systemd[1]: Stopped Name Service Cache Daemon (nsncd).761machine # [ 10.061003] systemd[1]: Started User Login Management.762machine # [ 10.076226] systemd[1]: Starting linger-users.service...763machine # [ 10.083032] systemd[1]: Starting Name Service Cache Daemon (nsncd)...764machine # [ 10.161747] systemd[1]: Started Name Service Cache Daemon (nsncd).765machine # [ 10.163439] systemd[1]: Reached target Host and Network Name Lookups.766machine # [ 10.166107] systemd[1]: Reached target User and Group Name Lookups.767machine # [ 10.170258] nsncd[550]: Sep 06 17:54:13.829 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"768machine # [ 10.189280] systemd[1]: Created slice Slice /user/1001.769machine # [ 10.192137] systemd[1]: Starting User Runtime Directory /run/user/1001...770machine # [ 10.216083] systemd[1]: linger-users.service: Deactivated successfully.771machine # [ 10.219243] systemd[1]: Finished linger-users.service.772machine # [ 10.259226] systemd[1]: Finished resolvconf update.773machine # [ 10.260218] systemd[1]: Reached target Preparation for Network.774machine # [ 10.265955] systemd[1]: Starting DHCP Client...775machine # [ 10.268835] systemd[1]: Starting Extra networking commands....776machine # [ 10.290045] systemd[1]: Finished User Runtime Directory /run/user/1001.777machine # [ 10.334066] systemd[1]: Starting User Manager for UID 1001...778machine # [ 10.473084] (systemd)[581]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)779machine # [ 10.490859] systemd-logind[501]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.780machine # [ 10.569154] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy781machine # [ 10.852967] systemd[1]: Starting Virtual Console Setup...782machine # [ 10.904540] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs783machine # [ 10.946083] systemd[581]: Queued start job for default target Main User Target.784machine # [ 10.970594] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy785machine # [ 10.985373] systemd[581]: Created slice User Application Slice.786machine # [ 10.995845] systemd[581]: Started Daily Cleanup of User's Temporary Directories.787machine # [ 11.016152] systemd[581]: Reached target Paths.788machine # [ 11.022397] systemd[581]: Reached target Timers.789machine # [ 11.035738] systemd[581]: Starting D-Bus User Message Bus Socket...790machine # [ 11.044910] systemd[581]: Starting Create User Files and Directories...791machine # [ 11.051863] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.792machine # [ 11.067499] systemd[1]: Stopped Virtual Console Setup.793machine # [ 11.080848] systemd[1]: Starting Virtual Console Setup...794machine # [ 11.095708] systemd[581]: Finished Create User Files and Directories.795machine # [ 11.110707] systemd[581]: Listening on D-Bus User Message Bus Socket.796machine # [ 11.118149] systemd[581]: Reached target Sockets.797machine # [ 11.122520] systemd[581]: Reached target Basic System.798machine # [ 11.126111] systemd[1]: Started User Manager for UID 1001.799machine # [ 11.134574] systemd[581]: Starting Run user-specific NixOS activation...800machine # [ 11.140281] systemd[581]: Finished Run user-specific NixOS activation.801machine # [ 11.146067] systemd[581]: Reached target Main User Target.802machine # [ 11.152591] systemd[581]: Startup finished in 580ms.803machine # [ 11.260475] dhcpcd[600]: dhcpcd-10.3.2 starting804machine # [ 11.304969] dhcpcd[655]: dev: loaded udev805machine # [ 11.321627] dhcpcd[655]: no valid interfaces found806machine # [ 11.339364] dhcpcd[655]: no valid interfaces found807machine # [ 11.347152] dhcpcd[655]: libudev: received NULL device808machine # [ 11.356481] dhcpcd[655]: libudev: received NULL device809machine # [ 11.393383] systemd[1]: Finished Extra networking commands..810machine # [ 11.410457] systemd[1]: Reached target Network.811machine # [ 11.428219] systemd[1]: Starting Permit User Sessions...812machine # [ 11.449587] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.813machine # [ 11.472822] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.814machine # [ 11.497081] systemd[1]: Started backdoor.service.815machine # [ 11.536604] (udev-worker)[656]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.816machine # [ 11.578358] (udev-worker)[656]: Network interface NamePolicy= disabled on kernel command line.817machine # [ 11.600881] 8021q: 802.1Q VLAN Support v1.8818machine # [ 11.614789] (udev-worker)[658]: Network interface NamePolicy= disabled on kernel command line.819machine # [ 11.707100] cfg80211: Loading compiled-in X.509 certificates for regulatory database820machine # [ 11.745296] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'821machine # [ 11.745918] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'822machine # [ 11.752201] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2823machine # [ 11.752553] cfg80211: failed to load regulatory.db824machine # [ 11.756598] systemd[1]: Finished Permit User Sessions.825machine # [ 11.769541] systemd[1]: Started Getty on tty1.826machine # [ 11.781267] systemd[1]: Reached target Login Prompts.827machine # [ 11.861327] systemd[1]: Condition check resulted in Virtio network device being skipped.828machine # [ 11.886859] systemd[1]: Starting Address configuration of eth1...829machine # [ 12.019346] systemd-logind[501]: Watching system buttons on /dev/input/event0 (gpio-keys)830machine # connecting to host...831machine: Guest shell says: b'Spawning backdoor root shell...\n'832machine # [ 12.266788] mousedev: PS/2 mouse device common for all mice833machine: connected to guest root shell834machine: (connecting took 12.56 seconds)835machine: (finished: waiting for the VM to finish booting, in 12.98 seconds)836machine # [ 12.376642] systemd-logind[501]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)837machine # [ 12.428425] 8021q: adding VLAN 0 to HW filter on device eth1838machine # [ 12.467180] 8021q: adding VLAN 0 to HW filter on device eth0839machine # [ 12.461727] dhcpcd[655]: eth0: waiting for carrier840machine # [ 12.462543] dhcpcd[655]: eth0: carrier acquired841machine # [ 12.556481] network-addresses-eth1-start[681]: adding address 192.168.1.1/24... done842machine # [ 12.583462] dhcpcd[655]: DUID 00:01:00:01:32:30:65:48:52:54:00:12:34:56843machine # [ 12.600674] dhcpcd[655]: eth0: IAID 00:12:34:56844machine # [ 12.601457] dhcpcd[655]: eth0: adding address fe80::5054:ff:fe12:3456845machine # [ 12.644930] network-addresses-eth1-start[681]: adding address 2001:db8:1::1/64... done846machine # [ 12.788704] systemd[1]: Finished Address configuration of eth1.847machine # [ 12.991639] dhcpcd[655]: eth0: soliciting a DHCP lease848machine # [ 13.006248] dhcpcd[655]: eth0: offered 10.0.2.15 from 10.0.2.2849machine # [ 13.021238] dhcpcd[655]: eth0: probing address 10.0.2.15/24850machine # [ 13.136441] systemd-vconsole-setup[599]: Configuration of first virtual console was skipped, ignoring remaining ones.851machine # [ 13.163965] systemd[1]: Finished Virtual Console Setup.852machine # [ 13.695134] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.853machine # [ 13.702212] systemd[1]: Stopped Virtual Console Setup.854machine # [ 13.729503] systemd[1]: Stopping Virtual Console Setup...855machine # [ 13.730354] systemd[1]: Starting Virtual Console Setup...856machine # [ 13.731117] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.857machine # [ 13.757899] systemd-logind[501]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)858machine # [ 13.767741] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.859machine # [ 13.771738] systemd[1]: Stopped Virtual Console Setup.860machine # [ 13.774440] systemd[1]: Starting Virtual Console Setup...861machine # [ 14.131359] systemd-vconsole-setup[719]: Configuration of first virtual console was skipped, ignoring remaining ones.862machine # [ 14.140231] systemd[1]: Finished Virtual Console Setup.863machine # [ 15.052958] dhcpcd[655]: eth0: soliciting an IPv6 router864machine # [ 15.057136] dhcpcd[655]: eth0: Router Advertisement from fe80::2865machine # [ 15.059906] dhcpcd[655]: eth0: adding address fec0::5054:ff:fe12:3456/64866machine # [ 15.063345] dhcpcd[655]: eth0: adding route to fec0::/64867machine # [ 15.065947] dhcpcd[655]: eth0: adding default route via fe80::2868machine # [ 18.648615] dhcpcd[655]: eth0: leased 10.0.2.15 for 86400 seconds869machine # [ 18.650939] dhcpcd[655]: eth0: adding route to 10.0.2.0/24870machine # [ 18.653017] dhcpcd[655]: eth0: adding default route via 10.0.2.2871machine # [ 18.782371] systemd[1]: Started DHCP Client.872machine # [ 18.784133] systemd[1]: Reached target Multi-User System.873machine # [ 18.785595] systemd[1]: Startup finished in 899ms (kernel) + 4.285s (initrd) + 13.600s (userspace) = 18.785s.874machine: (finished: waiting for unit multi-user.target, in 20.43 seconds)875machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test876machine # Checking switch inhibitors... done877machine # [ 20.314175] nixos[836]: switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test878machine # [ 20.316839] systemd[1]: Stopped target Local File Systems.879machine # activating the configuration...880machine # [ 20.319436] systemd[1]: Stopped target Remote File Systems.881machine # setting up /etc...882machine # [ 20.779608] systemd[1]: Reload requested from client PID 836 ('.switch-to-conf') (unit backdoor.service)...883machine # [ 20.781689] systemd[1]: Reloading...884machine # [ 21.358609] systemd[1]: Reloading finished in 574 ms.885machine # reloading user units for usertest...886machine # [ 21.406856] systemd[581]: Created slice User Core Session Slice.887machine # [ 21.410393] systemd[581]: Starting D-Bus User Message Bus...888machine # [ 21.472822] dbus-broker-launch[906]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.import1.service'889machine # [ 21.475733] dbus-broker-launch[906]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.machine1.service'890machine # [ 21.479246] dbus-broker-launch[906]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.portable1.service'891machine # [ 21.482829] dbus-broker-launch[906]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'892machine # [ 21.486612] dbus-broker-launch[906]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored893machine # [ 21.489820] dbus-broker-launch[906]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored894machine # [ 21.492163] systemd[581]: Started D-Bus User Message Bus.895machine # [ 21.499146] dbus-broker-launch[906]: Ready896machine # [ 21.611617] systemd[581]: Reexecution requested from client PID 904 ('switch-to-confi')...897machine # [ 21.613778] systemd[581]: Reexecuting.898machine # [ 21.665939] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy899machine # [ 21.883482] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy900machine # [ 21.892254] systemd[581]: Reload requested from client PID 904 ('switch-to-confi')...901machine # [ 21.893603] systemd[581]: Reloading...902machine # [ 22.081412] systemd[581]: Reloading finished in 185 ms.903machine # [ 22.096171] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy904machine # restarting the following user units: nixos-activation.service905machine # [ 22.106169] systemd[581]: Stopped Run user-specific NixOS activation.906machine # [ 22.108740] systemd[581]: Stopping Run user-specific NixOS activation...907machine # [ 22.111828] systemd[581]: Starting Run user-specific NixOS activation...908machine # [ 22.157989] systemd[581]: Finished Run user-specific NixOS activation.909machine # restarting sysinit-reactivation.target910machine # [ 22.177699] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....911machine # [ 22.292160] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..912machine # [ 22.295749] systemd[1]: Reached target Reactivate sysinit units.913machine # [ 22.298324] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os914machine # [ 22.302473] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki915machine # [ 22.304589] systemd[1]: Reached target Local File Systems.916machine # [ 22.306501] systemd[1]: Reached target Remote File Systems.917machine # [ 22.310233] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore918machine # [ 22.317691] systemd[1]: Starting Create SUID/SGID Wrappers...919machine # [ 22.318612] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.920machine # [ 22.319807] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67921machine # [ 22.326399] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore922machine # [ 22.332463] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os923machine # [ 22.333816] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os924machine # [ 22.647664] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.925machine # [ 22.652259] systemd[1]: Finished Create SUID/SGID Wrappers.926machine # [ 22.654644] systemd[1]: Starting linger-users.service...927machine # [ 22.712953] systemd[1]: linger-users.service: Deactivated successfully.928machine # [ 22.715197] systemd[1]: Finished linger-users.service.929machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service930machine # [ 22.978282] nixos[836]: finished switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test931machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test, in 3.28 seconds)932machine: must succeed: rm /etc/NIXOS933machine: (finished: must succeed: rm /etc/NIXOS, in 0.05 seconds)934machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/fkrnrx1kb4pvplbfnsgm5jx77m1hvwvw-nixos-system-other-test/bin/switch-to-configuration test935machine # this should succeed (config: /nix/store/fkrnrx1kb4pvplbfnsgm5jx77m1hvwvw-nixos-system-other-test, action: test)936machine # Checking switch inhibitors... done937machine # [ 23.632584] nixos[984]: switching to system configuration /nix/store/fkrnrx1kb4pvplbfnsgm5jx77m1hvwvw-nixos-system-other-test938machine # [ 23.634982] systemd[1]: Stopped target Remote File Systems.939machine # activating the configuration...940machine # [ 23.637582] systemd[1]: Stopped target Local File Systems.941machine # removing user ‘usertest’942machine # setting up /etc...943machine # [ 24.099762] systemd[1]: Reload requested from client PID 984 ('.switch-to-conf') (unit backdoor.service)...944machine # [ 24.101833] systemd[1]: Reloading...945machine # [ 24.713061] systemd[1]: Reloading finished in 609 ms.946machine # reloading user units for usertest...947machine # [ 24.865561] systemd[581]: Reexecution requested from client PID 1055 ('switch-to-confi')...948machine # [ 24.869233] systemd[581]: Reexecuting.949machine # [ 24.933668] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy950machine # [ 25.152213] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy951machine # [ 25.156407] systemd[581]: Reload requested from client PID 1055 ('switch-to-confi')...952machine # [ 25.158564] systemd[581]: Reloading...953machine # [ 25.339308] systemd[581]: Reloading finished in 179 ms.954machine # [ 25.356138] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy955machine # restarting the following user units: nixos-activation.service956machine # [ 25.366027] systemd[581]: Stopped Run user-specific NixOS activation.957machine # [ 25.366947] systemd[581]: Stopping Run user-specific NixOS activation...958machine # [ 25.369539] systemd[581]: Starting Run user-specific NixOS activation...959machine # [ 25.410399] systemd[581]: Finished Run user-specific NixOS activation.960machine # restarting sysinit-reactivation.target961machine # [ 25.414552] systemd[1]: Stopped target Reactivate sysinit units.962machine # reloading the following units: dbus-broker.service963machine # [ 25.417829] systemd[1]: Stopping Reactivate sysinit units...964machine # [ 25.418642] systemd[1]: Reached target Reactivate sysinit units.965machine # [ 25.419476] systemd[1]: Reloading D-Bus System Message Bus...966machine # [ 25.421818] dbus-broker-launch[485]: Caught SIGHUP, trigger reload.967machine # [ 25.442011] dbus-broker-launch[485]: Looking up NSS user entry for 'systemd-timesync'...968machine # [ 25.443985] dbus-broker-launch[485]: NSS returned no entry for 'systemd-timesync'969machine # [ 25.445087] dbus-broker-launch[485]: Invalid user-name in /nix/store/2d37a0ag8593x6bsc14irprkj2vpg80c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"970machine # [ 25.473375] systemd[1]: Reloaded D-Bus System Message Bus.971machine # restarting the following units: network-addresses-eth1.service972machine # [ 25.479176] systemd[1]: Stopping Address configuration of eth1...973machine # [ 25.525599] network-addresses-eth1-pre-stop[1068]: deleting address 192.168.1.1/24... done974machine # [ 25.535955] network-addresses-eth1-pre-stop[1068]: deleting address 2001:db8:1::1/64... done975machine # [ 25.545254] systemd[1]: network-addresses-eth1.service: Deactivated successfully.976machine # [ 25.547026] systemd[1]: Stopped Address configuration of eth1.977machine # [ 25.551029] systemd[1]: Starting Address configuration of eth1...978machine # [ 25.617449] network-addresses-eth1-start[1073]: adding address 192.168.1.2/24... done979machine # [ 25.628174] network-addresses-eth1-start[1073]: adding address 2001:db8:1::2/64... done980machine # [ 25.643306] systemd[1]: Finished Address configuration of eth1.981machine # [ 25.648970] systemd[1]: Reached target Remote File Systems.982machine # [ 25.652802] systemd[1]: Starting linger-users.service...983machine # [ 25.655291] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os984machine # [ 25.660356] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki985machine # [ 25.665342] systemd[1]: Reached target Local File Systems.986machine # [ 25.668934] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore987machine # [ 25.677353] systemd[1]: Starting Create SUID/SGID Wrappers...988machine # [ 25.678204] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.989machine # [ 25.679390] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67990machine # [ 25.682511] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore991machine # [ 25.684513] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os992machine # [ 25.686032] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os993machine # [ 25.725156] linger-users-start[1087]: id: ‘usertest’: no such user994machine # [ 25.727230] linger-users-start[1084]: Removing linger for missing user usertest995machine # [ 25.739391] systemd[1]: linger-users.service: Deactivated successfully.996machine # [ 25.740457] systemd[1]: Finished linger-users.service.997machine # [ 26.053652] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.998machine # [ 26.056505] systemd[1]: Finished Create SUID/SGID Wrappers.999machine # [ 26.314614] nixos[984]: finished switching to system configuration /nix/store/fkrnrx1kb4pvplbfnsgm5jx77m1hvwvw-nixos-system-other-test1000machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/fkrnrx1kb4pvplbfnsgm5jx77m1hvwvw-nixos-system-other-test/bin/switch-to-configuration test, in 3.29 seconds)1001machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&11002machine # [ 26.884468] nixos[1151]: switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test1003machine # [ 26.887219] systemd[1]: Stopped target Remote File Systems.1004machine # [ 26.889549] systemd[1]: Stopped target Local File Systems.1005machine # [ 27.323664] systemd[1]: Reload requested from client PID 1151 ('.switch-to-conf') (unit backdoor.service)...1006machine # [ 27.325811] systemd[1]: Reloading...1007machine # [ 27.904114] systemd[1]: Reloading finished in 576 ms.1008machine # [ 28.037793] systemd[581]: Reexecution requested from client PID 1220 ('switch-to-confi')...1009machine # [ 28.042093] systemd[581]: Reexecuting.1010machine # [ 28.093444] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1011machine # [ 28.274174] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1012machine # [ 28.280072] systemd[581]: Reload requested from client PID 1220 ('switch-to-confi')...1013machine # [ 28.282356] systemd[581]: Reloading...1014machine # [ 28.483443] systemd[581]: Reloading finished in 200 ms.1015machine # [ 28.505558] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1016machine # [ 28.513511] systemd[581]: Stopped Run user-specific NixOS activation.1017machine # [ 28.515823] systemd[581]: Stopping Run user-specific NixOS activation...1018machine # [ 28.518623] systemd[581]: Starting Run user-specific NixOS activation...1019machine # [ 28.563334] systemd[581]: Finished Run user-specific NixOS activation.1020machine # [ 28.567157] systemd[1]: Stopped target Reactivate sysinit units.1021machine # [ 28.568799] systemd[1]: Stopping Reactivate sysinit units...1022machine # [ 28.569649] systemd[1]: Reached target Reactivate sysinit units.1023machine # [ 28.572413] systemd[1]: Reloading D-Bus System Message Bus...1024machine # [ 28.574481] dbus-broker-launch[485]: Caught SIGHUP, trigger reload.1025machine # [ 28.587353] dbus-broker-launch[485]: Looking up NSS user entry for 'systemd-timesync'...1026machine # [ 28.589417] dbus-broker-launch[485]: NSS returned no entry for 'systemd-timesync'1027machine # [ 28.590592] dbus-broker-launch[485]: Invalid user-name in /nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1028machine # [ 28.621140] systemd[1]: Reloaded D-Bus System Message Bus.1029machine # [ 28.626694] systemd[1]: Stopping Address configuration of eth1...1030machine # [ 28.673253] network-addresses-eth1-pre-stop[1233]: deleting address 192.168.1.2/24... done1031machine # [ 28.683750] network-addresses-eth1-pre-stop[1233]: deleting address 2001:db8:1::2/64... done1032machine # [ 28.692857] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1033machine # [ 28.696190] systemd[1]: Stopped Address configuration of eth1.1034machine # [ 28.700216] systemd[1]: Starting Address configuration of eth1...1035machine # [ 28.768201] network-addresses-eth1-start[1238]: adding address 192.168.1.1/24... done1036machine # [ 28.779081] network-addresses-eth1-start[1238]: adding address 2001:db8:1::1/64... done1037machine # [ 28.793412] systemd[1]: Finished Address configuration of eth1.1038machine # [ 28.799181] systemd[1]: Reached target Local File Systems.1039machine # [ 28.803579] systemd[1]: Reached target Remote File Systems.1040machine # [ 28.808477] systemd[1]: Starting linger-users.service...1041machine # [ 28.812849] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1042machine # [ 28.824560] systemd[1]: Starting Create SUID/SGID Wrappers...1043machine # [ 28.825431] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1044machine # [ 28.826611] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671045machine # [ 28.833840] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1046machine # [ 28.835408] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1047machine # [ 28.836698] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1048machine # [ 28.837877] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1049machine # [ 28.839104] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1050machine # [ 28.882876] systemd[1]: linger-users.service: Deactivated successfully.1051machine # [ 28.885675] systemd[1]: Finished linger-users.service.1052machine # [ 29.156744] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1053machine # [ 29.160085] systemd[1]: Finished Create SUID/SGID Wrappers.1054machine # [ 29.416629] nixos[1151]: finished switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test1055machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.10 seconds)1056subtest: user services1057machine: waiting for unit user@1001.service1058machine: (finished: waiting for unit user@1001.service, in 0.20 seconds)1059machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11060machine # [ 30.156620] nixos[1311]: switching to system configuration /nix/store/9x10lc8hcpl6a6vabi6fxcr4ivwbazp3-nixos-system-machine-test1061machine # [ 30.159867] systemd[1]: Stopped target Local File Systems.1062machine # [ 30.161285] systemd[1]: Stopped target Remote File Systems.1063machine # [ 30.641096] systemd[1]: Reload requested from client PID 1311 ('.switch-to-conf') (unit backdoor.service)...1064machine # [ 30.642992] systemd[1]: Reloading...1065machine # [ 31.208979] systemd[1]: Reloading finished in 563 ms.1066machine # [ 31.362044] systemd[581]: Reexecution requested from client PID 1380 ('switch-to-confi')...1067machine # [ 31.365766] systemd[581]: Reexecuting.1068machine # [ 31.409551] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1069machine # [ 31.600913] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1070machine # [ 31.607371] systemd[581]: Reload requested from client PID 1380 ('switch-to-confi')...1071machine # [ 31.609383] systemd[581]: Reloading...1072machine # [ 31.815634] systemd[581]: Reloading finished in 202 ms.1073machine # [ 31.836150] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1074machine # [ 31.846385] systemd[581]: Starting usertest.service...1075machine # [ 31.881621] systemd[581]: Finished usertest.service.1076machine # [ 31.884078] systemd[581]: Stopped Run user-specific NixOS activation.1077machine # [ 31.886032] systemd[581]: Stopping Run user-specific NixOS activation...1078machine # [ 31.888479] systemd[581]: Starting Run user-specific NixOS activation...1079machine # [ 31.925243] systemd[581]: Finished Run user-specific NixOS activation.1080machine # [ 31.929148] systemd[1]: Stopped target Reactivate sysinit units.1081machine # [ 31.931732] systemd[1]: Stopping Reactivate sysinit units...1082machine # [ 31.933245] systemd[1]: Reached target Reactivate sysinit units.1083machine # [ 31.934073] systemd[1]: Reached target Remote File Systems.1084machine # [ 31.939334] systemd[1]: Starting linger-users.service...1085machine # [ 31.948376] systemd[1]: Reached target Local File Systems.1086machine # [ 31.949191] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1087machine # [ 31.954997] systemd[1]: Starting Create SUID/SGID Wrappers...1088machine # [ 31.959600] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1089machine # [ 31.964642] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671090machine # [ 31.971491] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1091machine # [ 31.975238] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1092machine # [ 31.976704] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1093machine # [ 31.978117] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1094machine # [ 31.979432] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1095machine # [ 32.026659] systemd[1]: linger-users.service: Deactivated successfully.1096machine # [ 32.028678] systemd[1]: Finished linger-users.service.1097machine # [ 32.291172] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1098machine # [ 32.294207] systemd[1]: Finished Create SUID/SGID Wrappers.1099machine # [ 32.550976] nixos[1311]: finished switching to system configuration /nix/store/9x10lc8hcpl6a6vabi6fxcr4ivwbazp3-nixos-system-machine-test1100machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.93 seconds)1101machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1102machine # [ 32.659369] sudo[1451]: 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.service1103machine # [ 32.677796] sudo[1451]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1104machine # [ 32.692109] sudo[1451]: pam_unix(sudo:session): session closed for user usertest1105machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.13 seconds)1106machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11107machine # [ 33.211966] nixos[1460]: switching to system configuration /nix/store/9x10lc8hcpl6a6vabi6fxcr4ivwbazp3-nixos-system-machine-test1108machine # [ 33.215292] systemd[1]: Stopped target Local File Systems.1109machine # [ 33.216519] systemd[1]: Stopped target Remote File Systems.1110machine # [ 33.636659] systemd[1]: Reload requested from client PID 1460 ('.switch-to-conf') (unit backdoor.service)...1111machine # [ 33.638489] systemd[1]: Reloading...1112machine # [ 34.203752] systemd[1]: Reloading finished in 563 ms.1113machine # [ 34.344195] systemd[581]: Reexecution requested from client PID 1529 ('switch-to-confi')...1114machine # [ 34.348260] systemd[581]: Reexecuting.1115machine # [ 34.410826] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1116machine # [ 34.596927] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1117machine # [ 34.603366] systemd[581]: Reload requested from client PID 1529 ('switch-to-confi')...1118machine # [ 34.605506] systemd[581]: Reloading...1119machine # [ 34.789943] systemd[581]: Reloading finished in 183 ms.1120machine # [ 34.804141] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1121machine # [ 34.812632] systemd[581]: Stopped Run user-specific NixOS activation.1122machine # [ 34.814829] systemd[581]: Stopping Run user-specific NixOS activation...1123machine # [ 34.818693] systemd[581]: Starting Run user-specific NixOS activation...1124machine # [ 34.862586] systemd[581]: Finished Run user-specific NixOS activation.1125machine # [ 34.866775] systemd[1]: Stopped target Reactivate sysinit units.1126machine # [ 34.869927] systemd[1]: Stopping Reactivate sysinit units...1127machine # [ 34.870982] systemd[1]: Reached target Reactivate sysinit units.1128machine # [ 34.873415] systemd[1]: Reached target Remote File Systems.1129machine # [ 34.877278] systemd[1]: Starting linger-users.service...1130machine # [ 34.879914] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1131machine # [ 34.888821] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1132machine # [ 34.890166] systemd[1]: Reached target Local File Systems.1133machine # [ 34.890924] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1134machine # [ 34.896395] systemd[1]: Starting Create SUID/SGID Wrappers...1135machine # [ 34.897355] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1136machine # [ 34.898522] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671137machine # [ 34.905816] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1138machine # [ 34.907829] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1139machine # [ 34.910214] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1140machine # [ 34.961860] systemd[1]: linger-users.service: Deactivated successfully.1141machine # [ 34.964867] systemd[1]: Finished linger-users.service.1142machine # [ 35.263034] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1143machine # [ 35.266170] systemd[1]: Finished Create SUID/SGID Wrappers.1144machine # [ 35.523160] nixos[1460]: finished switching to system configuration /nix/store/9x10lc8hcpl6a6vabi6fxcr4ivwbazp3-nixos-system-machine-test1145machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.84 seconds)1146machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&11147machine # [ 36.089309] nixos[1601]: switching to system configuration /nix/store/j18w3s30sfn9q0kszb5vd8xk7bx1acqa-nixos-system-machine-test1148machine # [ 36.092970] systemd[1]: Stopped target Local File Systems.1149machine # [ 36.094116] systemd[1]: Stopped target Remote File Systems.1150machine # [ 36.568623] systemd[1]: Reload requested from client PID 1601 ('.switch-to-conf') (unit backdoor.service)...1151machine # [ 36.570496] systemd[1]: Reloading...1152machine # [ 37.133258] systemd[1]: Reloading finished in 559 ms.1153machine # [ 37.296644] systemd[581]: Stopped usertest.service.1154machine # [ 37.299945] systemd[581]: Reexecution requested from client PID 1670 ('switch-to-confi')...1155machine # [ 37.302435] systemd[581]: Reexecuting.1156machine # [ 37.370044] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1157machine # [ 37.566033] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1158machine # [ 37.572793] systemd[581]: Reload requested from client PID 1670 ('switch-to-confi')...1159machine # [ 37.575198] systemd[581]: Reloading...1160machine # [ 37.758519] systemd[581]: Reloading finished in 182 ms.1161machine # [ 37.772145] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1162machine # [ 37.780705] systemd[581]: Starting usertest.service...1163machine # [ 37.813922] systemd[581]: Finished usertest.service.1164machine # [ 37.816398] systemd[581]: Stopped Run user-specific NixOS activation.1165machine # [ 37.818456] systemd[581]: Stopping Run user-specific NixOS activation...1166machine # [ 37.821007] systemd[581]: Starting Run user-specific NixOS activation...1167machine # [ 37.861994] systemd[581]: Finished Run user-specific NixOS activation.1168machine # [ 37.865985] systemd[1]: Stopped target Reactivate sysinit units.1169machine # [ 37.866906] systemd[1]: Stopping Reactivate sysinit units...1170machine # [ 37.867677] systemd[1]: Reached target Reactivate sysinit units.1171machine # [ 37.871244] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1172machine # [ 37.873988] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1173machine # [ 37.882391] systemd[1]: Reached target Local File Systems.1174machine # [ 37.883694] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1175machine # [ 37.887963] systemd[1]: Starting Create SUID/SGID Wrappers...1176machine # [ 37.890302] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1177machine # [ 37.893833] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671178machine # [ 37.895999] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1179machine # [ 37.901904] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1180machine # [ 37.903146] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1181machine # [ 37.906545] systemd[1]: Reached target Remote File Systems.1182machine # [ 38.203040] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1183machine # [ 38.207095] systemd[1]: Finished Create SUID/SGID Wrappers.1184machine # [ 38.209499] systemd[1]: Starting linger-users.service...1185machine # [ 38.259184] systemd[1]: linger-users.service: Deactivated successfully.1186machine # [ 38.261192] systemd[1]: Finished linger-users.service.1187machine # [ 38.516152] nixos[1601]: finished switching to system configuration /nix/store/j18w3s30sfn9q0kszb5vd8xk7bx1acqa-nixos-system-machine-test1188machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 2.99 seconds)1189machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1190machine # [ 38.574773] sudo[1741]: 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.service1191machine # [ 38.588903] sudo[1741]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1192machine # [ 38.600086] sudo[1741]: pam_unix(sudo:session): session closed for user usertest1193machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1194machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&11195machine # [ 39.114715] nixos[1750]: switching to system configuration /nix/store/wpal8812yxsz5g9az79i75k2rkangmcb-nixos-system-machine-test1196machine # [ 39.117352] systemd[1]: Stopped target Local File Systems.1197machine # [ 39.118962] systemd[1]: Stopped target Remote File Systems.1198machine # [ 39.605394] systemd[1]: Reload requested from client PID 1750 ('.switch-to-conf') (unit backdoor.service)...1199machine # [ 39.607302] systemd[1]: Reloading...1200machine # [ 40.169032] systemd[1]: Reloading finished in 559 ms.1201machine # [ 40.332322] systemd[581]: Reexecution requested from client PID 1819 ('switch-to-confi')...1202machine # [ 40.333646] systemd[581]: Reexecuting.1203machine # [ 40.402252] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1204machine # [ 40.598614] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1205machine # [ 40.604527] systemd[581]: Reload requested from client PID 1819 ('switch-to-confi')...1206machine # [ 40.606603] systemd[581]: Reloading...1207machine # [ 40.791448] systemd[581]: Reloading finished in 183 ms.1208machine # [ 40.808135] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1209machine # [ 40.814827] systemd[581]: Stopped usertest.service.1210machine # [ 40.815648] systemd[581]: Stopping usertest.service...1211machine # [ 40.818179] systemd[581]: Starting usertest.service...1212machine # [ 40.849861] systemd[581]: Finished usertest.service.1213machine # [ 40.855027] systemd[581]: Stopped Run user-specific NixOS activation.1214machine # [ 40.857196] systemd[581]: Stopping Run user-specific NixOS activation...1215machine # [ 40.859835] systemd[581]: Starting Run user-specific NixOS activation...1216machine # [ 40.902799] systemd[581]: Finished Run user-specific NixOS activation.1217machine # [ 40.906863] systemd[1]: Stopped target Reactivate sysinit units.1218machine # [ 40.909420] systemd[1]: Stopping Reactivate sysinit units...1219machine # [ 40.910640] systemd[1]: Reached target Reactivate sysinit units.1220machine # [ 40.911458] systemd[1]: Reached target Remote File Systems.1221machine # [ 40.914268] systemd[1]: Reached target Local File Systems.1222machine # [ 40.917515] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1223machine # [ 40.921789] systemd[1]: Starting Create SUID/SGID Wrappers...1224machine # [ 40.922639] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1225machine # [ 40.923831] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671226machine # [ 40.933085] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1227machine # [ 40.936724] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1228machine # [ 40.941954] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1229machine # [ 40.943161] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1230machine # [ 40.944558] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1231machine # [ 41.243503] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1232machine # [ 41.247591] systemd[1]: Finished Create SUID/SGID Wrappers.1233machine # [ 41.250181] systemd[1]: Starting linger-users.service...1234machine # [ 41.302761] systemd[1]: linger-users.service: Deactivated successfully.1235machine # [ 41.305111] systemd[1]: Finished linger-users.service.1236machine # [ 41.562621] nixos[1750]: finished switching to system configuration /nix/store/wpal8812yxsz5g9az79i75k2rkangmcb-nixos-system-machine-test1237machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 2.97 seconds)1238machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1239machine # [ 41.641833] sudo[1889]: 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.service1240machine # [ 41.659599] sudo[1889]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1241machine # [ 41.672890] sudo[1889]: pam_unix(sudo:session): session closed for user usertest1242machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds)1243machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&11244machine # [ 42.220965] nixos[1898]: switching to system configuration /nix/store/fjj44hy7pvsv7y03xlp6xmykb7l10mj8-nixos-system-machine-test1245machine # [ 42.223498] systemd[1]: Stopped target Local File Systems.1246machine # [ 42.225591] systemd[1]: Stopped target Remote File Systems.1247machine # [ 42.709911] systemd[1]: Reload requested from client PID 1898 ('.switch-to-conf') (unit backdoor.service)...1248machine # [ 42.711831] systemd[1]: Reloading...1249machine # [ 43.279361] systemd[1]: Reloading finished in 565 ms.1250machine # [ 43.441641] systemd[581]: Reexecution requested from client PID 1968 ('switch-to-confi')...1251machine # [ 43.442961] systemd[581]: Reexecuting.1252machine # [ 43.506034] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1253machine # [ 43.701910] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1254machine # [ 43.707985] systemd[581]: Reload requested from client PID 1968 ('switch-to-confi')...1255machine # [ 43.710214] systemd[581]: Reloading...1256machine # [ 43.893999] systemd[581]: Reloading finished in 182 ms.1257machine # [ 43.912139] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1258machine # [ 43.920666] systemd[581]: Reloading usertest.service...1259machine # [ 43.953529] systemd[581]: Reloaded usertest.service.1260machine # [ 43.958222] systemd[581]: Stopped Run user-specific NixOS activation.1261machine # [ 43.960282] systemd[581]: Stopping Run user-specific NixOS activation...1262machine # [ 43.962714] systemd[581]: Starting Run user-specific NixOS activation...1263machine # [ 44.007181] systemd[581]: Finished Run user-specific NixOS activation.1264machine # [ 44.010942] systemd[1]: Stopped target Reactivate sysinit units.1265machine # [ 44.013468] systemd[1]: Stopping Reactivate sysinit units...1266machine # [ 44.014617] systemd[1]: Reached target Reactivate sysinit units.1267machine # [ 44.017858] systemd[1]: Reached target Local File Systems.1268machine # [ 44.021385] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1269machine # [ 44.024166] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1270machine # [ 44.027390] systemd[1]: Reached target Remote File Systems.1271machine # [ 44.030336] systemd[1]: Starting linger-users.service...1272machine # [ 44.034168] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1273machine # [ 44.042856] systemd[1]: Starting Create SUID/SGID Wrappers...1274machine # [ 44.044526] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1275machine # [ 44.047146] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671276machine # [ 44.052240] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1277machine # [ 44.053793] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1278machine # [ 44.055008] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1279machine # [ 44.111269] systemd[1]: linger-users.service: Deactivated successfully.1280machine # [ 44.114115] systemd[1]: Finished linger-users.service.1281machine # [ 44.366212] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1282machine # [ 44.368978] systemd[1]: Finished Create SUID/SGID Wrappers.1283machine # [ 44.626915] nixos[1898]: finished switching to system configuration /nix/store/fjj44hy7pvsv7y03xlp6xmykb7l10mj8-nixos-system-machine-test1284machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 2.96 seconds)1285machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1286machine # [ 44.706382] sudo[2038]: 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.service1287machine # [ 44.723923] sudo[2038]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1288machine # [ 44.737252] sudo[2038]: pam_unix(sudo:session): session closed for user usertest1289machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds)1290machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11291machine # [ 45.294249] nixos[2047]: switching to system configuration /nix/store/9x10lc8hcpl6a6vabi6fxcr4ivwbazp3-nixos-system-machine-test1292machine # [ 45.296793] systemd[1]: Stopped target Local File Systems.1293machine # [ 45.298991] systemd[1]: Stopped target Remote File Systems.1294machine # [ 45.772214] systemd[1]: Reload requested from client PID 2047 ('.switch-to-conf') (unit backdoor.service)...1295machine # [ 45.774134] systemd[1]: Reloading...1296machine # [ 46.388494] systemd[1]: Reloading finished in 612 ms.1297machine # [ 46.544709] systemd[581]: Stopped usertest.service.1298machine # [ 46.548097] systemd[581]: Reexecution requested from client PID 2117 ('switch-to-confi')...1299machine # [ 46.549438] systemd[581]: Reexecuting.1300machine # [ 46.614400] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1301machine # [ 46.808874] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1302machine # [ 46.815239] systemd[581]: Reload requested from client PID 2117 ('switch-to-confi')...1303machine # [ 46.817790] systemd[581]: Reloading...1304machine # [ 47.010097] systemd[581]: Reloading finished in 192 ms.1305machine # [ 47.028163] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1306machine # [ 47.037214] systemd[581]: Starting usertest.service...1307machine # [ 47.076300] systemd[581]: Finished usertest.service.1308machine # [ 47.078844] systemd[581]: Stopped Run user-specific NixOS activation.1309machine # [ 47.080916] systemd[581]: Stopping Run user-specific NixOS activation...1310machine # [ 47.083533] systemd[581]: Starting Run user-specific NixOS activation...1311machine # [ 47.128462] systemd[581]: Finished Run user-specific NixOS activation.1312machine # [ 47.132325] systemd[1]: Stopped target Reactivate sysinit units.1313machine # [ 47.135759] systemd[1]: Stopping Reactivate sysinit units...1314machine # [ 47.137898] systemd[1]: Reached target Reactivate sysinit units.1315machine # [ 47.141418] systemd[1]: Reached target Local File Systems.1316machine # [ 47.145469] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1317machine # [ 47.152631] systemd[1]: Starting Create SUID/SGID Wrappers...1318machine # [ 47.153506] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1319machine # [ 47.154684] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671320machine # [ 47.164433] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1321machine # [ 47.167284] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1322machine # [ 47.169004] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1323machine # [ 47.170166] systemd[1]: Reached target Remote File Systems.1324machine # [ 47.170928] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1325machine # [ 47.172261] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1326machine # [ 47.470863] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1327machine # [ 47.475228] systemd[1]: Finished Create SUID/SGID Wrappers.1328machine # [ 47.477865] systemd[1]: Starting linger-users.service...1329machine # [ 47.535606] systemd[1]: linger-users.service: Deactivated successfully.1330machine # [ 47.537423] systemd[1]: Finished linger-users.service.1331machine # [ 47.795936] nixos[2047]: finished switching to system configuration /nix/store/9x10lc8hcpl6a6vabi6fxcr4ivwbazp3-nixos-system-machine-test1332machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.07 seconds)1333machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1334machine # [ 47.879468] sudo[2187]: 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.service1335machine # [ 47.898411] sudo[2187]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1336machine # [ 47.914798] sudo[2187]: pam_unix(sudo:session): session closed for user usertest1337machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds)1338machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&11339machine # [ 48.439082] nixos[2196]: switching to system configuration /nix/store/rhnyidnq4dpb616kdv0lkxr0v1a23f3d-nixos-system-machine-test1340machine # [ 48.441692] systemd[1]: Stopped target Local File Systems.1341machine # [ 48.443754] systemd[1]: Stopped target Remote File Systems.1342machine # [ 48.921739] systemd[1]: Reload requested from client PID 2196 ('.switch-to-conf') (unit backdoor.service)...1343machine # [ 48.923609] systemd[1]: Reloading...1344machine # [ 49.484776] systemd[1]: Reloading finished in 558 ms.1345machine # [ 49.647505] systemd[581]: Reexecution requested from client PID 2265 ('switch-to-confi')...1346machine # [ 49.650892] systemd[581]: Reexecuting.1347machine # [ 49.714564] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1348machine # [ 49.908648] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1349machine # [ 49.915690] systemd[581]: Reload requested from client PID 2265 ('switch-to-confi')...1350machine # [ 49.917746] systemd[581]: Reloading...1351machine # [ 50.101692] systemd[581]: Reloading finished in 181 ms.1352machine # [ 50.116130] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1353machine # [ 50.124180] systemd[581]: Reloading usertest.service...1354machine # [ 50.157125] systemd[581]: Reloaded usertest.service.1355machine # [ 50.162188] systemd[581]: Stopped Run user-specific NixOS activation.1356machine # [ 50.164107] systemd[581]: Stopping Run user-specific NixOS activation...1357machine # [ 50.166609] systemd[581]: Starting Run user-specific NixOS activation...1358machine # [ 50.209887] systemd[581]: Finished Run user-specific NixOS activation.1359machine # [ 50.213506] systemd[1]: Stopped target Reactivate sysinit units.1360machine # [ 50.215278] systemd[1]: Stopping Reactivate sysinit units...1361machine # [ 50.216750] systemd[1]: Reached target Reactivate sysinit units.1362machine # [ 50.219958] systemd[1]: Reached target Local File Systems.1363machine # [ 50.221834] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1364machine # [ 50.229711] systemd[1]: Starting Create SUID/SGID Wrappers...1365machine # [ 50.233004] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1366machine # [ 50.234194] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671367machine # [ 50.240365] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1368machine # [ 50.242325] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1369machine # [ 50.243553] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1370machine # [ 50.248263] systemd[1]: Reached target Remote File Systems.1371machine # [ 50.249424] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1372machine # [ 50.251177] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1373machine # [ 50.544116] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1374machine # [ 50.548107] systemd[1]: Finished Create SUID/SGID Wrappers.1375machine # [ 50.550455] systemd[1]: Starting linger-users.service...1376machine # [ 50.606659] systemd[1]: linger-users.service: Deactivated successfully.1377machine # [ 50.608353] systemd[1]: Finished linger-users.service.1378machine # [ 50.866412] nixos[2196]: finished switching to system configuration /nix/store/rhnyidnq4dpb616kdv0lkxr0v1a23f3d-nixos-system-machine-test1379machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 2.96 seconds)1380machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1381machine # [ 50.947772] sudo[2335]: 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.service1382machine # [ 50.968753] sudo[2335]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1383machine # [ 50.984242] sudo[2335]: pam_unix(sudo:session): session closed for user usertest1384machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds)1385machine: must fail: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&11386machine # [ 51.506312] nixos[2344]: switching to system configuration /nix/store/39a3bgjmbfsn655bnscg74dscpg9qr4m-nixos-system-machine-test1387machine # [ 51.508712] systemd[1]: Stopped target Local File Systems.1388machine # [ 51.510887] systemd[1]: Stopped target Remote File Systems.1389machine # [ 51.994060] systemd[1]: Reload requested from client PID 2344 ('.switch-to-conf') (unit backdoor.service)...1390machine # [ 51.995891] systemd[1]: Reloading...1391machine # [ 52.554706] systemd[1]: Reloading finished in 556 ms.1392machine # [ 52.714214] systemd[581]: Stopped usertest.service.1393machine # [ 52.717334] systemd[581]: Reexecution requested from client PID 2413 ('switch-to-confi')...1394machine # [ 52.718642] systemd[581]: Reexecuting.1395machine # [ 52.782504] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1396machine # [ 52.975491] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1397machine # [ 52.982940] systemd[581]: Reload requested from client PID 2413 ('switch-to-confi')...1398machine # [ 52.984877] systemd[581]: Reloading...1399machine # [ 53.165836] systemd[581]: Reloading finished in 180 ms.1400machine # [ 53.180145] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1401machine # [ 53.190588] systemd[581]: Starting usertest.service...1402machine # [ 53.225387] systemd[581]: usertest.service: Main process exited, code=exited, status=1/FAILURE1403machine # [ 53.228396] systemd[581]: usertest.service: Failed with result 'exit-code'.1404machine # [ 53.229694] systemd[581]: Failed to start usertest.service.1405machine # [ 53.231713] systemd[581]: Stopped Run user-specific NixOS activation.1406machine # [ 53.233813] systemd[581]: Stopping Run user-specific NixOS activation...1407machine # [ 53.236472] systemd[581]: Starting Run user-specific NixOS activation...1408machine # [ 53.277658] systemd[581]: Finished Run user-specific NixOS activation.1409machine # [ 53.281579] systemd[1]: Stopped target Reactivate sysinit units.1410machine # [ 53.285007] systemd[1]: Stopping Reactivate sysinit units...1411machine # [ 53.285852] systemd[1]: Reached target Reactivate sysinit units.1412machine # [ 53.286657] systemd[1]: Reached target Local File Systems.1413machine # [ 53.289013] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1414machine # [ 53.295821] systemd[1]: Starting Create SUID/SGID Wrappers...1415machine # [ 53.298044] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1416machine # [ 53.299261] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671417machine # [ 53.309025] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1418machine # [ 53.311584] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1419machine # [ 53.314518] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1420machine # [ 53.315930] systemd[1]: Reached target Remote File Systems.1421machine # [ 53.318049] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1422machine # [ 53.319507] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1423machine # [ 53.611311] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1424machine # [ 53.613620] systemd[1]: Finished Create SUID/SGID Wrappers.1425machine # [ 53.618452] systemd[1]: Starting linger-users.service...1426machine # [ 53.675687] systemd[1]: linger-users.service: Deactivated successfully.1427machine # [ 53.677964] systemd[1]: Finished linger-users.service.1428machine # [ 53.935363] nixos[2344]: switching to system configuration /nix/store/39a3bgjmbfsn655bnscg74dscpg9qr4m-nixos-system-machine-test failed (status 4)1429machine: (finished: must fail: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 2.96 seconds)1430machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11431machine # [ 54.495371] nixos[2486]: switching to system configuration /nix/store/9x10lc8hcpl6a6vabi6fxcr4ivwbazp3-nixos-system-machine-test1432machine # [ 54.498110] systemd[1]: Stopped target Local File Systems.1433machine # [ 54.500246] systemd[1]: Stopped target Remote File Systems.1434machine # [ 54.919029] systemd[1]: Reload requested from client PID 2486 ('.switch-to-conf') (unit backdoor.service)...1435machine # [ 54.921047] systemd[1]: Reloading...1436machine # [ 55.479755] systemd[1]: Reloading finished in 556 ms.1437machine # [ 55.615368] systemd[581]: Reexecution requested from client PID 2555 ('switch-to-confi')...1438machine # [ 55.618069] systemd[581]: Reexecuting.1439machine # [ 55.682963] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1440machine # [ 55.869542] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1441machine # [ 55.876113] systemd[581]: Reload requested from client PID 2555 ('switch-to-confi')...1442machine # [ 55.878297] systemd[581]: Reloading...1443machine # [ 56.061190] systemd[581]: Reloading finished in 181 ms.1444machine # [ 56.076131] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1445machine # [ 56.086307] systemd[581]: Starting usertest.service...1446machine # [ 56.121184] systemd[581]: Finished usertest.service.1447machine # [ 56.123667] systemd[581]: Stopped Run user-specific NixOS activation.1448machine # [ 56.125742] systemd[581]: Stopping Run user-specific NixOS activation...1449machine # [ 56.128110] systemd[581]: Starting Run user-specific NixOS activation...1450machine # [ 56.169103] systemd[581]: Finished Run user-specific NixOS activation.1451machine # [ 56.173119] systemd[1]: Stopped target Reactivate sysinit units.1452machine # [ 56.174260] systemd[1]: Stopping Reactivate sysinit units...1453machine # [ 56.175069] systemd[1]: Reached target Reactivate sysinit units.1454machine # [ 56.179670] systemd[1]: Reached target Local File Systems.1455machine # [ 56.183088] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1456machine # [ 56.187215] systemd[1]: Starting Create SUID/SGID Wrappers...1457machine # [ 56.189891] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1458machine # [ 56.193529] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671459machine # [ 56.195637] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1460machine # [ 56.204885] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1461machine # [ 56.206133] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1462machine # [ 56.207297] systemd[1]: Reached target Remote File Systems.1463machine # [ 56.208103] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1464machine # [ 56.209348] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1465machine # [ 56.506320] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1466machine # [ 56.510751] systemd[1]: Finished Create SUID/SGID Wrappers.1467machine # [ 56.513179] systemd[1]: Starting linger-users.service...1468machine # [ 56.567301] systemd[1]: linger-users.service: Deactivated successfully.1469machine # [ 56.569835] systemd[1]: Finished linger-users.service.1470machine # [ 56.827248] nixos[2486]: finished switching to system configuration /nix/store/9x10lc8hcpl6a6vabi6fxcr4ivwbazp3-nixos-system-machine-test1471machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.89 seconds)1472machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1473machine # [ 56.908704] sudo[2625]: 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.service1474machine # [ 56.925590] sudo[2625]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1475machine # [ 56.939319] sudo[2625]: pam_unix(sudo:session): session closed for user usertest1476machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds)1477machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&11478machine # [ 57.458287] nixos[2634]: switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test1479machine # [ 57.462291] systemd[1]: Stopped target Local File Systems.1480machine # [ 57.463337] systemd[1]: Stopped target Remote File Systems.1481machine # [ 57.881865] systemd[1]: Reload requested from client PID 2634 ('.switch-to-conf') (unit backdoor.service)...1482machine # [ 57.883777] systemd[1]: Reloading...1483machine # [ 58.457657] systemd[1]: Reloading finished in 571 ms.1484machine # [ 58.590705] systemd[581]: Stopped usertest.service.1485machine # [ 58.594719] systemd[581]: Reexecution requested from client PID 2703 ('switch-to-confi')...1486machine # [ 58.596767] systemd[581]: Reexecuting.1487machine # [ 58.658035] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1488machine # [ 58.858836] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1489machine # [ 58.866873] systemd[581]: Reload requested from client PID 2703 ('switch-to-confi')...1490machine # [ 58.870021] systemd[581]: Reloading...1491machine # [ 59.075615] systemd[581]: Reloading finished in 205 ms.1492machine # [ 59.090571] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1493machine # [ 59.099701] systemd[581]: Stopped Run user-specific NixOS activation.1494machine # [ 59.101098] systemd[581]: Stopping Run user-specific NixOS activation...1495machine # [ 59.105253] systemd[581]: Starting Run user-specific NixOS activation...1496machine # [ 59.156810] systemd[581]: Finished Run user-specific NixOS activation.1497machine # [ 59.162081] systemd[1]: Stopped target Reactivate sysinit units.1498machine # [ 59.165059] systemd[1]: Stopping Reactivate sysinit units...1499machine # [ 59.166075] systemd[1]: Reached target Reactivate sysinit units.1500machine # [ 59.170745] systemd[1]: Reached target Local File Systems.1501machine # [ 59.172813] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1502machine # [ 59.177873] systemd[1]: Starting Create SUID/SGID Wrappers...1503machine # [ 59.182629] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1504machine # [ 59.186627] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671505machine # [ 59.192527] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1506machine # [ 59.194280] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1507machine # [ 59.195568] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1508machine # [ 59.202651] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1509machine # [ 59.207103] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1510machine # [ 59.209121] systemd[1]: Reached target Remote File Systems.1511machine # [ 59.552969] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1512machine # [ 59.558329] systemd[1]: Finished Create SUID/SGID Wrappers.1513machine # [ 59.561700] systemd[1]: Starting linger-users.service...1514machine # [ 59.631801] systemd[1]: linger-users.service: Deactivated successfully.1515machine # [ 59.635643] systemd[1]: Finished linger-users.service.1516machine # [ 59.895211] nixos[2634]: finished switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test1517machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.97 seconds)1518machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1519machine # [ 59.996813] sudo[2771]: 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.service1520machine # [ 60.018263] sudo[2771]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1521machine # [ 60.038832] sudo[2771]: pam_unix(sudo:session): session closed for user usertest1522machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.13 seconds)1523machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1524machine # [ 60.103910] sudo[2777]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1525machine # [ 60.119952] sudo[2777]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1526machine # [ 60.134281] sudo[2777]: pam_unix(sudo:session): session closed for user usertest1527machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.09 seconds)1528machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service1529machine # [ 60.190020] sudo[2783]: 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.service1530machine # [ 60.206672] sudo[2783]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1531machine # [ 60.223820] sudo[2783]: pam_unix(sudo:session): session closed for user usertest1532machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.09 seconds)1533machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1534machine # [ 60.278480] sudo[2789]: 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.service1535machine # [ 60.294435] sudo[2789]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1536machine # [ 60.309672] sudo[2789]: pam_unix(sudo:session): session closed for user usertest1537machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.08 seconds)1538machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1539machine # [ 60.366348] sudo[2795]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1540machine # [ 60.382448] sudo[2795]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1541machine # [ 60.394726] systemd[581]: Reload requested from client PID 2800 ('systemctl')...1542machine # [ 60.397453] systemd[581]: Reloading...1543machine # [ 60.615672] systemd[581]: Reloading finished in 216 ms.1544machine # [ 60.628360] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1545machine # [ 60.639949] sudo[2795]: pam_unix(sudo:session): session closed for user usertest1546machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.33 seconds)1547machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1548machine # [ 60.692758] sudo[2805]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1549machine # [ 60.709912] sudo[2805]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1550machine # [ 60.731783] systemd[581]: Starting migrated.service...1551machine # [ 60.779229] systemd[581]: Finished migrated.service.1552machine # [ 60.785716] sudo[2805]: pam_unix(sudo:session): session closed for user usertest1553machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.15 seconds)1554machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1555machine # [ 60.840759] sudo[2813]: 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.service1556machine # [ 60.856885] sudo[2813]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1557machine # [ 60.871431] sudo[2813]: pam_unix(sudo:session): session closed for user usertest1558machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.09 seconds)1559machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1560machine # [ 60.926782] sudo[2819]: 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-owner1561machine # [ 60.943512] sudo[2819]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1562machine # [ 60.958524] sudo[2819]: pam_unix(sudo:session): session closed for user usertest1563machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.09 seconds)1564machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1565machine # [ 61.013755] sudo[2825]: 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.service1566machine # [ 61.030306] sudo[2825]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1567machine # [ 61.057606] sudo[2825]: pam_unix(sudo:session): session closed for user usertest1568machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds)1569machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&11570machine # [ 61.663339] nixos[2834]: switching to system configuration /nix/store/3xy4pd5xl8kgr8ai1dz2w4453pi14zw0-nixos-system-machine-test1571machine # [ 61.666366] systemd[1]: Stopped target Local File Systems.1572machine # [ 61.668834] systemd[1]: Stopped target Remote File Systems.1573machine # [ 62.212633] systemd[1]: Reload requested from client PID 2834 ('.switch-to-conf') (unit backdoor.service)...1574machine # [ 62.214721] systemd[1]: Reloading...1575machine # [ 62.853322] systemd[1]: Reloading finished in 635 ms.1576machine # [ 63.026934] systemd[581]: Reexecution requested from client PID 2903 ('switch-to-confi')...1577machine # [ 63.030558] systemd[581]: Reexecuting.1578machine # [ 63.078167] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1579machine # [ 63.286073] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1580machine # [ 63.293266] systemd[581]: Reload requested from client PID 2903 ('switch-to-confi')...1581machine # [ 63.296244] systemd[581]: Reloading...1582machine # [ 63.498350] systemd[581]: Reloading finished in 201 ms.1583machine # [ 63.512182] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1584machine # [ 63.522377] systemd[581]: Stopped Run user-specific NixOS activation.1585machine # [ 63.524946] systemd[581]: Stopping Run user-specific NixOS activation...1586machine # [ 63.528103] systemd[581]: Starting Run user-specific NixOS activation...1587machine # [ 63.624194] systemd[581]: Reload requested from client PID 2915 ('systemctl') (unit nixos-activation.service)...1588machine # [ 63.627262] systemd[581]: Reloading...1589machine # [ 63.827710] systemd[581]: Reloading finished in 199 ms.1590machine # [ 63.841926] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1591machine # [ 63.865224] systemd[581]: Stopped migrated.service.1592machine # [ 63.877721] systemd[581]: Finished Run user-specific NixOS activation.1593machine # [ 63.880382] systemd[581]: Reload requested from client PID 2903 ('switch-to-confi')...1594machine # [ 63.882912] systemd[581]: Reloading...1595machine # [ 64.073455] systemd[581]: Reloading finished in 189 ms.1596machine # [ 64.088157] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1597machine # [ 64.101504] systemd[581]: Starting migrated.service...1598machine # [ 64.137250] systemd[581]: Finished migrated.service.1599machine # [ 64.141404] systemd[1]: Stopped target Reactivate sysinit units.1600machine # [ 64.143933] systemd[1]: Stopping Reactivate sysinit units...1601machine # [ 64.145497] systemd[1]: Reached target Reactivate sysinit units.1602machine # [ 64.151261] systemd[1]: Reached target Remote File Systems.1603machine # [ 64.156216] systemd[1]: Starting linger-users.service...1604machine # [ 64.164120] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1605machine # [ 64.167545] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1606machine # [ 64.170742] systemd[1]: Reached target Local File Systems.1607machine # [ 64.171522] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1608machine # [ 64.178379] systemd[1]: Starting Create SUID/SGID Wrappers...1609machine # [ 64.179723] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1610machine # [ 64.181669] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671611machine # [ 64.184371] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1612machine # [ 64.186453] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1613machine # [ 64.191293] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1614machine # [ 64.242141] systemd[1]: linger-users.service: Deactivated successfully.1615machine # [ 64.244865] systemd[1]: Finished linger-users.service.1616machine # [ 64.517004] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1617machine # [ 64.520261] systemd[1]: Finished Create SUID/SGID Wrappers.1618machine # [ 64.777348] nixos[2834]: finished switching to system configuration /nix/store/3xy4pd5xl8kgr8ai1dz2w4453pi14zw0-nixos-system-machine-test1619machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 3.73 seconds)1620machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1621machine # [ 64.867745] sudo[2985]: 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.service1622machine # [ 64.887139] sudo[2985]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1623machine # [ 64.902218] sudo[2985]: pam_unix(sudo:session): session closed for user usertest1624machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds)1625machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1626machine # [ 64.955374] sudo[2991]: 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.service1627machine # [ 64.970842] sudo[2991]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1628machine # [ 65.001711] sudo[2991]: pam_unix(sudo:session): session closed for user usertest1629machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds)1630machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1631machine # [ 65.054493] sudo[2997]: 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-owner1632machine # [ 65.069018] sudo[2997]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1633machine # [ 65.082561] sudo[2997]: pam_unix(sudo:session): session closed for user usertest1634machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)1635machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&11636machine # [ 65.711302] nixos[3006]: switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test1637machine # [ 65.715148] systemd[1]: Stopped target Local File Systems.1638machine # [ 65.716769] systemd[1]: Stopped target Remote File Systems.1639machine # [ 66.220947] systemd[1]: Reload requested from client PID 3006 ('.switch-to-conf') (unit backdoor.service)...1640machine # [ 66.223043] systemd[1]: Reloading...1641machine # [ 66.839123] systemd[1]: Reloading finished in 613 ms.1642machine # [ 66.985190] systemd[581]: Stopped migrated.service.1643machine # [ 66.988314] systemd[581]: Reexecution requested from client PID 3074 ('switch-to-confi')...1644machine # [ 66.991002] systemd[581]: Reexecuting.1645machine # [ 67.046540] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1646machine # [ 67.241651] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1647machine # [ 67.248089] systemd[581]: Reload requested from client PID 3074 ('switch-to-confi')...1648machine # [ 67.250567] systemd[581]: Reloading...1649machine # [ 67.445716] systemd[581]: Reloading finished in 194 ms.1650machine # [ 67.461560] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1651machine # [ 67.470558] systemd[581]: Stopped Run user-specific NixOS activation.1652machine # [ 67.472908] systemd[581]: Stopping Run user-specific NixOS activation...1653machine # [ 67.475857] systemd[581]: Starting Run user-specific NixOS activation...1654machine # [ 67.522553] systemd[581]: Finished Run user-specific NixOS activation.1655machine # [ 67.526868] systemd[1]: Stopped target Reactivate sysinit units.1656machine # [ 67.529006] systemd[1]: Stopping Reactivate sysinit units...1657machine # [ 67.531775] systemd[1]: Reached target Reactivate sysinit units.1658machine # [ 67.534374] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1659machine # [ 67.535692] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1660machine # [ 67.541232] systemd[1]: Reached target Local File Systems.1661machine # [ 67.543151] systemd[1]: Reached target Remote File Systems.1662machine # [ 67.548320] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1663machine # [ 67.552175] systemd[1]: Starting Create SUID/SGID Wrappers...1664machine # [ 67.553165] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1665machine # [ 67.554392] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671666machine # [ 67.559421] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1667machine # [ 67.568284] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1668machine # [ 67.569694] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1669machine # [ 67.898580] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1670machine # [ 67.901068] systemd[1]: Finished Create SUID/SGID Wrappers.1671machine # [ 67.906468] systemd[1]: Starting linger-users.service...1672machine # [ 67.972899] systemd[1]: linger-users.service: Deactivated successfully.1673machine # [ 67.975862] systemd[1]: Finished linger-users.service.1674machine # [ 68.233055] nixos[3006]: finished switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test1675machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.16 seconds)1676machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1677machine # [ 68.325064] sudo[3143]: 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.service1678machine # [ 68.347810] sudo[3143]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1679machine # [ 68.366400] sudo[3143]: pam_unix(sudo:session): session closed for user usertest1680machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.12 seconds)1681machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1682machine # [ 68.416888] sudo[3149]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1683machine # [ 68.431045] sudo[3149]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1684machine # [ 68.443428] sudo[3149]: pam_unix(sudo:session): session closed for user usertest1685machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.08 seconds)1686machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service1687machine # [ 68.487803] sudo[3155]: 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.service1688machine # [ 68.502439] sudo[3155]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1689machine # [ 68.514424] sudo[3155]: pam_unix(sudo:session): session closed for user usertest1690machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.07 seconds)1691machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1692machine # [ 68.557215] sudo[3161]: 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.service1693machine # [ 68.569971] sudo[3161]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1694machine # [ 68.581519] sudo[3161]: pam_unix(sudo:session): session closed for user usertest1695machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.07 seconds)1696machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1697machine # [ 68.622881] sudo[3167]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1698machine # [ 68.635143] sudo[3167]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1699machine # [ 68.644757] systemd[581]: Reload requested from client PID 3172 ('systemctl')...1700machine # [ 68.647184] systemd[581]: Reloading...1701machine # [ 68.835614] systemd[581]: Reloading finished in 187 ms.1702machine # [ 68.848148] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1703machine # [ 68.861382] sudo[3167]: pam_unix(sudo:session): session closed for user usertest1704machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.28 seconds)1705machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1706machine # [ 68.906667] sudo[3177]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1707machine # [ 68.919411] sudo[3177]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1708machine # [ 68.934927] systemd[581]: Starting migrated.service...1709machine # [ 68.968311] systemd[581]: Finished migrated.service.1710machine # [ 68.972415] sudo[3177]: pam_unix(sudo:session): session closed for user usertest1711machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.11 seconds)1712machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1713machine # [ 69.016673] sudo[3185]: 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.service1714machine # [ 69.029722] sudo[3185]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1715machine # [ 69.040844] sudo[3185]: pam_unix(sudo:session): session closed for user usertest1716machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1717machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1718machine # [ 69.085147] sudo[3191]: 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-owner1719machine # [ 69.097989] sudo[3191]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1720machine # [ 69.111256] sudo[3191]: pam_unix(sudo:session): session closed for user usertest1721machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)1722machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1723machine # [ 69.156558] sudo[3197]: 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.service1724machine # [ 69.171094] sudo[3197]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1725machine # [ 69.192331] sudo[3197]: pam_unix(sudo:session): session closed for user usertest1726machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds)1727machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&11728machine # [ 69.692463] nixos[3206]: switching to system configuration /nix/store/pgsdynw5yx2x1g6h36dkh5xw5fc834w9-nixos-system-machine-test1729machine # [ 69.695282] systemd[1]: Stopped target Local File Systems.1730machine # [ 69.697695] systemd[1]: Stopped target Remote File Systems.1731machine # [ 70.234808] systemd[1]: Reload requested from client PID 3206 ('.switch-to-conf') (unit backdoor.service)...1732machine # [ 70.236890] systemd[1]: Reloading...1733machine # [ 70.821890] systemd[1]: Reloading finished in 582 ms.1734machine # [ 70.980137] systemd[581]: Reexecution requested from client PID 3275 ('switch-to-confi')...1735machine # [ 70.984991] systemd[581]: Reexecuting.1736machine # [ 71.045622] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1737machine # [ 71.239306] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1738machine # [ 71.245816] systemd[581]: Reload requested from client PID 3275 ('switch-to-confi')...1739machine # [ 71.248232] systemd[581]: Reloading...1740machine # [ 71.434408] systemd[581]: Reloading finished in 185 ms.1741machine # [ 71.448156] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1742machine # [ 71.457703] systemd[581]: Stopped Run user-specific NixOS activation.1743machine # [ 71.460101] systemd[581]: Stopping Run user-specific NixOS activation...1744machine # [ 71.462984] systemd[581]: Starting Run user-specific NixOS activation...1745machine # [ 71.518834] systemd[581]: Reload requested from client PID 3287 ('systemctl') (unit nixos-activation.service)...1746machine # [ 71.523042] systemd[581]: Reloading...1747machine # [ 71.711119] systemd[581]: Reloading finished in 189 ms.1748machine # [ 71.725796] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1749machine # [ 71.741812] systemd[581]: Finished Run user-specific NixOS activation.1750machine # [ 71.744326] systemd[581]: Reload requested from client PID 3275 ('switch-to-confi')...1751machine # [ 71.746728] systemd[581]: Reloading...1752machine # [ 71.933517] systemd[581]: Reloading finished in 185 ms.1753machine # [ 71.948146] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1754machine # [ 71.959573] systemd[581]: Stopped migrated.service.1755machine # [ 71.961479] systemd[581]: Stopping migrated.service...1756machine # [ 71.963999] systemd[581]: Starting migrated.service...1757machine # [ 72.001162] systemd[581]: Finished migrated.service.1758machine # [ 72.007630] systemd[1]: Stopped target Reactivate sysinit units.1759machine # [ 72.009394] systemd[1]: Stopping Reactivate sysinit units...1760machine # [ 72.010232] systemd[1]: Reached target Reactivate sysinit units.1761machine # [ 72.012935] systemd[1]: Reached target Remote File Systems.1762machine # [ 72.017880] systemd[1]: Starting linger-users.service...1763machine # [ 72.026197] systemd[1]: Reached target Local File Systems.1764machine # [ 72.029025] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1765machine # [ 72.030358] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1766machine # [ 72.034120] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1767machine # [ 72.041356] systemd[1]: Starting Create SUID/SGID Wrappers...1768machine # [ 72.045361] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1769machine # [ 72.052481] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671770machine # [ 72.054680] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1771machine # [ 72.057331] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1772machine # [ 72.058938] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1773machine # [ 72.110712] systemd[1]: linger-users.service: Deactivated successfully.1774machine # [ 72.113348] systemd[1]: Finished linger-users.service.1775machine # [ 72.373916] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1776machine # [ 72.376771] systemd[1]: Finished Create SUID/SGID Wrappers.1777machine # [ 72.634924] nixos[3206]: finished switching to system configuration /nix/store/pgsdynw5yx2x1g6h36dkh5xw5fc834w9-nixos-system-machine-test1778machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 3.45 seconds)1779machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1780machine # [ 72.716839] sudo[3356]: 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.service1781machine # [ 72.734439] sudo[3356]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1782machine # [ 72.749760] sudo[3356]: pam_unix(sudo:session): session closed for user usertest1783machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds)1784machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1785machine # [ 72.802209] sudo[3362]: 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.service1786machine # [ 72.816416] sudo[3362]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1787machine # [ 72.844118] sudo[3362]: pam_unix(sudo:session): session closed for user usertest1788machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.09 seconds)1789machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1790machine # [ 72.886238] sudo[3368]: 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-owner1791machine # [ 72.898900] sudo[3368]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1792machine # [ 72.910101] sudo[3368]: pam_unix(sudo:session): session closed for user usertest1793machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)1794machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&11795machine # [ 73.418462] nixos[3377]: switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test1796machine # [ 73.422507] systemd[1]: Stopped target Local File Systems.1797machine # [ 73.423596] systemd[1]: Stopped target Remote File Systems.1798machine # [ 73.842847] systemd[1]: Reload requested from client PID 3377 ('.switch-to-conf') (unit backdoor.service)...1799machine # [ 73.844944] systemd[1]: Reloading...1800machine # [ 74.408908] systemd[1]: Reloading finished in 562 ms.1801machine # [ 74.551999] systemd[581]: Stopped migrated.service.1802machine # [ 74.553716] systemd[581]: Reexecution requested from client PID 3446 ('switch-to-confi')...1803machine # [ 74.556964] systemd[581]: Reexecuting.1804machine # [ 74.617417] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1805machine # [ 74.800804] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1806machine # [ 74.807485] systemd[581]: Reload requested from client PID 3446 ('switch-to-confi')...1807machine # [ 74.809963] systemd[581]: Reloading...1808machine # [ 74.991184] systemd[581]: Reloading finished in 181 ms.1809machine # [ 75.004152] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1810machine # [ 75.014323] systemd[581]: Stopped Run user-specific NixOS activation.1811machine # [ 75.015369] systemd[581]: Stopping Run user-specific NixOS activation...1812machine # [ 75.019256] systemd[581]: Starting Run user-specific NixOS activation...1813machine # [ 75.063542] systemd[581]: Finished Run user-specific NixOS activation.1814machine # [ 75.067841] systemd[1]: Stopped target Reactivate sysinit units.1815machine # [ 75.070361] systemd[1]: Stopping Reactivate sysinit units...1816machine # [ 75.071209] systemd[1]: Reached target Reactivate sysinit units.1817machine # [ 75.075669] systemd[1]: Reached target Local File Systems.1818machine # [ 75.077049] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1819machine # [ 75.080778] systemd[1]: Starting Create SUID/SGID Wrappers...1820machine # [ 75.081609] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1821machine # [ 75.082789] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671822machine # [ 75.093072] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1823machine # [ 75.094664] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1824machine # [ 75.095906] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1825machine # [ 75.104646] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1826machine # [ 75.107394] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1827machine # [ 75.109714] systemd[1]: Reached target Remote File Systems.1828machine # [ 75.404268] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1829machine # [ 75.408633] systemd[1]: Finished Create SUID/SGID Wrappers.1830machine # [ 75.411057] systemd[1]: Starting linger-users.service...1831machine # [ 75.469163] systemd[1]: linger-users.service: Deactivated successfully.1832machine # [ 75.470123] systemd[1]: Finished linger-users.service.1833machine # [ 75.728559] nixos[3377]: finished switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test1834machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.83 seconds)1835machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1836machine # [ 75.811886] sudo[3514]: 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.service1837machine # [ 75.831022] sudo[3514]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1838machine # [ 75.847329] sudo[3514]: pam_unix(sudo:session): session closed for user usertest1839machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds)1840machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1841machine # [ 75.893784] sudo[3520]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1842machine # [ 75.906956] sudo[3520]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1843machine # [ 75.918313] sudo[3520]: pam_unix(sudo:session): session closed for user usertest1844machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.07 seconds)1845machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service1846machine # [ 75.961464] sudo[3526]: 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.service1847machine # [ 75.974156] sudo[3526]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1848machine # [ 75.985830] sudo[3526]: pam_unix(sudo:session): session closed for user usertest1849machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.07 seconds)1850machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1851machine # [ 76.028217] sudo[3532]: 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.service1852machine # [ 76.042414] sudo[3532]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1853machine # [ 76.052984] sudo[3532]: pam_unix(sudo:session): session closed for user usertest1854machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.07 seconds)1855machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1856machine # [ 76.094724] sudo[3538]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1857machine # [ 76.106827] sudo[3538]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1858machine # [ 76.116426] systemd[581]: Reload requested from client PID 3543 ('systemctl')...1859machine # [ 76.118931] systemd[581]: Reloading...1860machine # [ 76.307393] systemd[581]: Reloading finished in 187 ms.1861machine # [ 76.320143] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1862machine # [ 76.332778] sudo[3538]: pam_unix(sudo:session): session closed for user usertest1863machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.28 seconds)1864machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1865machine # [ 76.375830] sudo[3548]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1866machine # [ 76.388451] sudo[3548]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1867machine # [ 76.403638] systemd[581]: Starting migrated.service...1868machine # [ 76.440282] systemd[581]: Finished migrated.service.1869machine # [ 76.444210] sudo[3548]: pam_unix(sudo:session): session closed for user usertest1870machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.11 seconds)1871machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1872machine # [ 76.487799] sudo[3556]: 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.service1873machine # [ 76.500612] sudo[3556]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1874machine # [ 76.511788] sudo[3556]: pam_unix(sudo:session): session closed for user usertest1875machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1876machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1877machine # [ 76.555829] sudo[3562]: 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-owner1878machine # [ 76.568416] sudo[3562]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1879machine # [ 76.579928] sudo[3562]: pam_unix(sudo:session): session closed for user usertest1880machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)1881machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1882machine # [ 76.621841] sudo[3568]: 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.service1883machine # [ 76.634368] sudo[3568]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1884machine # [ 76.656366] sudo[3568]: pam_unix(sudo:session): session closed for user usertest1885machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds)1886machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&11887machine # [ 77.183363] nixos[3577]: switching to system configuration /nix/store/h8pf4d2n5f8rid9nfik6p29cr8n1ijr4-nixos-system-machine-test1888machine # [ 77.186152] systemd[1]: Stopped target Remote File Systems.1889machine # [ 77.188379] systemd[1]: Stopped target Local File Systems.1890machine # [ 77.686046] systemd[1]: Reload requested from client PID 3577 ('.switch-to-conf') (unit backdoor.service)...1891machine # [ 77.688004] systemd[1]: Reloading...1892machine # [ 78.255973] systemd[1]: Reloading finished in 565 ms.1893machine # [ 78.409626] systemd[581]: Reexecution requested from client PID 3646 ('switch-to-confi')...1894machine # [ 78.413428] systemd[581]: Reexecuting.1895machine # [ 78.477733] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1896machine # [ 78.677005] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1897machine # [ 78.683407] systemd[581]: Reload requested from client PID 3646 ('switch-to-confi')...1898machine # [ 78.685424] systemd[581]: Reloading...1899machine # [ 78.870702] systemd[581]: Reloading finished in 184 ms.1900machine # [ 78.884143] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1901machine # [ 78.892851] systemd[581]: Stopped Run user-specific NixOS activation.1902machine # [ 78.895409] systemd[581]: Stopping Run user-specific NixOS activation...1903machine # [ 78.897953] systemd[581]: Starting Run user-specific NixOS activation...1904machine # [ 78.954250] systemd[581]: Reload requested from client PID 3658 ('systemctl') (unit nixos-activation.service)...1905machine # [ 78.957144] systemd[581]: Reloading...1906machine # [ 79.144224] systemd[581]: Reloading finished in 186 ms.1907machine # [ 79.160156] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1908machine # [ 79.178604] systemd[581]: Finished Run user-specific NixOS activation.1909machine # [ 79.181192] systemd[581]: Reload requested from client PID 3646 ('switch-to-confi')...1910machine # [ 79.183479] systemd[581]: Reloading...1911machine # [ 79.374587] systemd[581]: Reloading finished in 190 ms.1912machine # [ 79.388151] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1913machine # [ 79.399881] systemd[581]: Reloading migrated.service...1914machine # [ 79.434217] systemd[581]: Reloaded migrated.service.1915machine # [ 79.440749] systemd[1]: Stopped target Reactivate sysinit units.1916machine # [ 79.442581] systemd[1]: Stopping Reactivate sysinit units...1917machine # [ 79.445134] systemd[1]: Reached target Reactivate sysinit units.1918machine # [ 79.447311] systemd[1]: Reached target Remote File Systems.1919machine # [ 79.452126] systemd[1]: Starting linger-users.service...1920machine # [ 79.459483] systemd[1]: Reached target Local File Systems.1921machine # [ 79.464730] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1922machine # [ 79.467144] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1923machine # [ 79.469733] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1924machine # [ 79.477345] systemd[1]: Starting Create SUID/SGID Wrappers...1925machine # [ 79.478244] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1926machine # [ 79.479470] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671927machine # [ 79.481964] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1928machine # [ 79.483925] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1929machine # [ 79.486103] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1930machine # [ 79.546749] systemd[1]: linger-users.service: Deactivated successfully.1931machine # [ 79.550008] systemd[1]: Finished linger-users.service.1932machine # [ 79.807619] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1933machine # [ 79.810796] systemd[1]: Finished Create SUID/SGID Wrappers.1934machine # [ 80.067412] nixos[3577]: finished switching to system configuration /nix/store/h8pf4d2n5f8rid9nfik6p29cr8n1ijr4-nixos-system-machine-test1935machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 3.42 seconds)1936machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1937machine # [ 80.157773] sudo[3727]: 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.service1938machine # [ 80.178443] sudo[3727]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1939machine # [ 80.195053] sudo[3727]: pam_unix(sudo:session): session closed for user usertest1940machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.12 seconds)1941machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1942machine # [ 80.245846] sudo[3733]: 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-owner1943machine # [ 80.260793] sudo[3733]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1944machine # [ 80.274415] sudo[3733]: pam_unix(sudo:session): session closed for user usertest1945machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)1946machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&11947machine # [ 80.802705] nixos[3742]: switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test1948machine # [ 80.806693] systemd[1]: Stopped target Remote File Systems.1949machine # [ 80.807809] systemd[1]: Stopped target Local File Systems.1950machine # [ 81.273482] systemd[1]: Reload requested from client PID 3742 ('.switch-to-conf') (unit backdoor.service)...1951machine # [ 81.275448] systemd[1]: Reloading...1952machine # [ 81.869406] systemd[1]: Reloading finished in 591 ms.1953machine # [ 82.017704] systemd[581]: Stopped migrated.service.1954machine # [ 82.020681] systemd[581]: Reexecution requested from client PID 3811 ('switch-to-confi')...1955machine # [ 82.023450] systemd[581]: Reexecuting.1956machine # [ 82.085603] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1957machine # [ 82.278845] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1958machine # [ 82.286238] systemd[581]: Reload requested from client PID 3811 ('switch-to-confi')...1959machine # [ 82.287927] systemd[581]: Reloading...1960machine # [ 82.476231] systemd[581]: Reloading finished in 187 ms.1961machine # [ 82.492160] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1962machine # [ 82.501331] systemd[581]: Stopped Run user-specific NixOS activation.1963machine # [ 82.503567] systemd[581]: Stopping Run user-specific NixOS activation...1964machine # [ 82.506231] systemd[581]: Starting Run user-specific NixOS activation...1965machine # [ 82.551750] systemd[581]: Finished Run user-specific NixOS activation.1966machine # [ 82.555777] systemd[1]: Stopped target Reactivate sysinit units.1967machine # [ 82.557787] systemd[1]: Stopping Reactivate sysinit units...1968machine # [ 82.558970] systemd[1]: Reached target Reactivate sysinit units.1969machine # [ 82.562031] systemd[1]: Reached target Local File Systems.1970machine # [ 82.564952] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1971machine # [ 82.570383] systemd[1]: Starting Create SUID/SGID Wrappers...1972machine # [ 82.571234] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1973machine # [ 82.574115] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671974machine # [ 82.584530] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1975machine # [ 82.586169] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1976machine # [ 82.587382] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1977machine # [ 82.589502] systemd[1]: Reached target Remote File Systems.1978machine # [ 82.594250] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1979machine # [ 82.595697] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1980machine # [ 82.904225] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1981machine # [ 82.908761] systemd[1]: Finished Create SUID/SGID Wrappers.1982machine # [ 82.911271] systemd[1]: Starting linger-users.service...1983machine # [ 82.971930] systemd[1]: linger-users.service: Deactivated successfully.1984machine # [ 82.973625] systemd[1]: Finished linger-users.service.1985machine # [ 83.228554] nixos[3742]: finished switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test1986machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.96 seconds)1987machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1988machine # [ 83.297461] sudo[3879]: 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.service1989machine # [ 83.316843] sudo[3879]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1990machine # [ 83.333623] sudo[3879]: pam_unix(sudo:session): session closed for user usertest1991machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)1992machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1993machine # [ 83.385594] sudo[3885]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1994machine # [ 83.399629] sudo[3885]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1995machine # [ 83.412511] sudo[3885]: pam_unix(sudo:session): session closed for user usertest1996machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.08 seconds)1997machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service1998machine # [ 83.458387] sudo[3891]: 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.service1999machine # [ 83.472691] sudo[3891]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2000machine # [ 83.484754] sudo[3891]: pam_unix(sudo:session): session closed for user usertest2001machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.07 seconds)2002machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2003machine # [ 83.532865] sudo[3897]: 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.service2004machine # [ 83.546168] sudo[3897]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2005machine # [ 83.558868] sudo[3897]: pam_unix(sudo:session): session closed for user usertest2006machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.07 seconds)2007machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2008machine # [ 83.604818] sudo[3903]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2009machine # [ 83.617278] sudo[3903]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2010machine # [ 83.627004] systemd[581]: Reload requested from client PID 3908 ('systemctl')...2011machine # [ 83.628791] systemd[581]: Reloading...2012machine # [ 83.816701] systemd[581]: Reloading finished in 186 ms.2013machine # [ 83.828162] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2014machine # [ 83.841092] sudo[3903]: pam_unix(sudo:session): session closed for user usertest2015machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.28 seconds)2016machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2017machine # [ 83.885178] sudo[3913]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2018machine # [ 83.898474] sudo[3913]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2019machine # [ 83.913934] systemd[581]: Starting migrated.service...2020machine # [ 83.949046] systemd[581]: Finished migrated.service.2021machine # [ 83.953247] sudo[3913]: pam_unix(sudo:session): session closed for user usertest2022machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.11 seconds)2023machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2024machine # [ 83.998166] sudo[3921]: 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.service2025machine # [ 84.011051] sudo[3921]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2026machine # [ 84.022134] sudo[3921]: pam_unix(sudo:session): session closed for user usertest2027machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2028machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2029machine # [ 84.065957] sudo[3927]: 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-owner2030machine # [ 84.078376] sudo[3927]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2031machine # [ 84.089777] sudo[3927]: pam_unix(sudo:session): session closed for user usertest2032machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)2033machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2034machine # [ 84.132608] sudo[3933]: 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.service2035machine # [ 84.145243] sudo[3933]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2036machine # [ 84.165856] sudo[3933]: pam_unix(sudo:session): session closed for user usertest2037machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds)2038machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&12039machine # [ 84.683547] nixos[3942]: switching to system configuration /nix/store/jgsbznrsdqn3gkzsfyk9kk6ahnrfkwfc-nixos-system-machine-test2040machine # [ 84.687481] systemd[1]: Stopped target Remote File Systems.2041machine # [ 84.688812] systemd[1]: Stopped target Local File Systems.2042machine # [ 85.178939] systemd[1]: Reload requested from client PID 3942 ('.switch-to-conf') (unit backdoor.service)...2043machine # [ 85.181039] systemd[1]: Reloading...2044machine # [ 85.752069] systemd[1]: Reloading finished in 568 ms.2045machine # [ 85.909472] systemd[581]: Reexecution requested from client PID 4011 ('switch-to-confi')...2046machine # [ 85.913532] systemd[581]: Reexecuting.2047machine # [ 85.977401] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2048machine # [ 86.177446] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2049machine # [ 86.183663] systemd[581]: Reload requested from client PID 4011 ('switch-to-confi')...2050machine # [ 86.186219] systemd[581]: Reloading...2051machine # [ 86.369505] systemd[581]: Reloading finished in 183 ms.2052machine # [ 86.384155] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2053machine # [ 86.392591] systemd[581]: Stopped Run user-specific NixOS activation.2054machine # [ 86.394792] systemd[581]: Stopping Run user-specific NixOS activation...2055machine # [ 86.397486] systemd[581]: Starting Run user-specific NixOS activation...2056machine # [ 86.454749] systemd[581]: Reload requested from client PID 4023 ('systemctl') (unit nixos-activation.service)...2057machine # [ 86.458791] systemd[581]: Reloading...2058machine # [ 86.647421] systemd[581]: Reloading finished in 189 ms.2059machine # [ 86.664150] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2060machine # [ 86.681242] systemd[581]: Finished Run user-specific NixOS activation.2061machine # [ 86.683660] systemd[581]: Reload requested from client PID 4011 ('switch-to-confi')...2062machine # [ 86.686258] systemd[581]: Reloading...2063machine # [ 86.873192] systemd[581]: Reloading finished in 185 ms.2064machine # [ 86.888157] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2065machine # [ 86.902550] systemd[1]: Stopped target Reactivate sysinit units.2066machine # [ 86.903465] systemd[1]: Stopping Reactivate sysinit units...2067machine # [ 86.906155] systemd[1]: Reached target Reactivate sysinit units.2068machine # [ 86.910122] systemd[1]: Reached target Remote File Systems.2069machine # [ 86.912902] systemd[1]: Starting linger-users.service...2070machine # [ 86.922341] systemd[1]: Reached target Local File Systems.2071machine # [ 86.924279] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2072machine # [ 86.933824] systemd[1]: Starting Create SUID/SGID Wrappers...2073machine # [ 86.940488] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2074machine # [ 86.942037] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672075machine # [ 86.947363] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2076machine # [ 86.952575] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2077machine # [ 86.953980] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2078machine # [ 86.955442] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2079machine # [ 86.957350] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2080machine # [ 87.009038] systemd[1]: linger-users.service: Deactivated successfully.2081machine # [ 87.010952] systemd[1]: Finished linger-users.service.2082machine # [ 87.280729] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2083machine # [ 87.284009] systemd[1]: Finished Create SUID/SGID Wrappers.2084machine # [ 87.540817] nixos[3942]: finished switching to system configuration /nix/store/jgsbznrsdqn3gkzsfyk9kk6ahnrfkwfc-nixos-system-machine-test2085machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 3.39 seconds)2086machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2087machine # [ 87.621193] sudo[4090]: 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.service2088machine # [ 87.638488] sudo[4090]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2089machine # [ 87.652128] sudo[4090]: pam_unix(sudo:session): session closed for user usertest2090machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)2091machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2092machine # [ 87.699591] sudo[4096]: 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-owner2093machine # [ 87.713283] sudo[4096]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2094machine # [ 87.724712] sudo[4096]: pam_unix(sudo:session): session closed for user usertest2095machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)2096machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&12097machine # [ 88.227568] nixos[4105]: switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test2098machine # [ 88.231473] systemd[1]: Stopped target Local File Systems.2099machine # [ 88.232568] systemd[1]: Stopped target Remote File Systems.2100machine # [ 88.652845] systemd[1]: Reload requested from client PID 4105 ('.switch-to-conf') (unit backdoor.service)...2101machine # [ 88.654725] systemd[1]: Reloading...2102machine # [ 89.231559] systemd[1]: Reloading finished in 574 ms.2103machine # [ 89.377384] systemd[581]: Stopped migrated.service.2104machine # [ 89.380494] systemd[581]: Reexecution requested from client PID 4174 ('switch-to-confi')...2105machine # [ 89.383349] systemd[581]: Reexecuting.2106machine # [ 89.441618] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2107machine # [ 89.633427] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2108machine # [ 89.640394] systemd[581]: Reload requested from client PID 4174 ('switch-to-confi')...2109machine # [ 89.642645] systemd[581]: Reloading...2110machine # [ 89.829937] systemd[581]: Reloading finished in 186 ms.2111machine # [ 89.844162] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2112machine # [ 89.853613] systemd[581]: Stopped Run user-specific NixOS activation.2113machine # [ 89.854595] systemd[581]: Stopping Run user-specific NixOS activation...2114machine # [ 89.857326] systemd[581]: Starting Run user-specific NixOS activation...2115machine # [ 89.903555] systemd[581]: Finished Run user-specific NixOS activation.2116machine # [ 89.907389] systemd[1]: Stopped target Reactivate sysinit units.2117machine # [ 89.909182] systemd[1]: Stopping Reactivate sysinit units...2118machine # [ 89.910780] systemd[1]: Reached target Reactivate sysinit units.2119machine # [ 89.913110] systemd[1]: Reached target Remote File Systems.2120machine # [ 89.917835] systemd[1]: Starting linger-users.service...2121machine # [ 89.920739] systemd[1]: Reached target Local File Systems.2122machine # [ 89.924239] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2123machine # [ 89.929926] systemd[1]: Starting Create SUID/SGID Wrappers...2124machine # [ 89.930827] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2125machine # [ 89.931997] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672126machine # [ 89.939408] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2127machine # [ 89.951384] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2128machine # [ 89.954515] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2129machine # [ 89.956424] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2130machine # [ 89.958450] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2131machine # [ 90.012594] systemd[1]: linger-users.service: Deactivated successfully.2132machine # [ 90.015365] systemd[1]: Finished linger-users.service.2133machine # [ 90.276170] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2134machine # [ 90.279459] systemd[1]: Finished Create SUID/SGID Wrappers.2135machine # [ 90.536191] nixos[4105]: finished switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test2136machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.82 seconds)2137machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2138machine # [ 90.618631] sudo[4242]: 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.service2139machine # [ 90.635392] sudo[4242]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2140machine # [ 90.650525] sudo[4242]: pam_unix(sudo:session): session closed for user usertest2141machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)2142machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user2143machine # [ 90.698832] sudo[4248]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user2144machine # [ 90.712854] sudo[4248]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2145machine # [ 90.724445] sudo[4248]: pam_unix(sudo:session): session closed for user usertest2146machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.07 seconds)2147machine: must succeed: sudo -u usertest cp /nix/store/sjwrvqsv40pz3pq93zpd2s2k0jw0h8qf-migrated.service ~usertest/.local/share/systemd/user/migrated.service2148machine # [ 90.768438] sudo[4254]: 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.service2149machine # [ 90.781522] sudo[4254]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2150machine # [ 90.794066] sudo[4254]: pam_unix(sudo:session): session closed for user usertest2151machine: (finished: must succeed: sudo -u usertest cp /nix/store/sjwrvqsv40pz3pq93zpd2s2k0jw0h8qf-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.07 seconds)2152machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2153machine # [ 90.835534] sudo[4260]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2154machine # [ 90.848661] sudo[4260]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2155machine # [ 90.857602] systemd[581]: Reload requested from client PID 4265 ('systemctl')...2156machine # [ 90.860083] systemd[581]: Reloading...2157machine # [ 91.046544] systemd[581]: Reloading finished in 185 ms.2158machine # [ 91.060155] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2159machine # [ 91.069853] sudo[4260]: pam_unix(sudo:session): session closed for user usertest2160machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.28 seconds)2161machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2162machine # [ 91.113886] sudo[4270]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2163machine # [ 91.126470] sudo[4270]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2164machine # [ 91.144692] systemd[581]: Starting migrated.service...2165machine # [ 91.180843] systemd[581]: Finished migrated.service.2166machine # [ 91.185048] sudo[4270]: pam_unix(sudo:session): session closed for user usertest2167machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.11 seconds)2168machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2169machine # [ 91.227125] sudo[4278]: 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.service2170machine # [ 91.239925] sudo[4278]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2171machine # [ 91.251269] sudo[4278]: pam_unix(sudo:session): session closed for user usertest2172machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2173machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2174machine # [ 91.294060] sudo[4284]: 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-owner2175machine # [ 91.306402] sudo[4284]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2176machine # [ 91.317766] sudo[4284]: pam_unix(sudo:session): session closed for user usertest2177machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)2178machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2179machine # [ 91.360474] sudo[4290]: 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.service2180machine # [ 91.373208] sudo[4290]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2181machine # [ 91.417424] sudo[4290]: pam_unix(sudo:session): session closed for user usertest2182machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds)2183machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12184machine # [ 91.932199] nixos[4299]: switching to system configuration /nix/store/nbinm9zxg0vk3jxqriby4nd341jimby3-nixos-system-machine-test2185machine # [ 91.936102] systemd[1]: Stopped target Remote File Systems.2186machine # [ 91.937293] systemd[1]: Stopped target Local File Systems.2187machine # [ 92.430899] systemd[1]: Reload requested from client PID 4299 ('.switch-to-conf') (unit backdoor.service)...2188machine # [ 92.432997] systemd[1]: Reloading...2189machine # [ 93.006853] systemd[1]: Reloading finished in 571 ms.2190machine # [ 93.163097] systemd[581]: Reexecution requested from client PID 4368 ('switch-to-confi')...2191machine # [ 93.165404] systemd[581]: Reexecuting.2192machine # [ 93.230319] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2193machine # [ 93.426476] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2194machine # [ 93.432625] systemd[581]: Reload requested from client PID 4368 ('switch-to-confi')...2195machine # [ 93.434858] systemd[581]: Reloading...2196machine # [ 93.624814] systemd[581]: Reloading finished in 188 ms.2197machine # [ 93.640164] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2198machine # [ 93.649963] systemd[581]: Stopped Run user-specific NixOS activation.2199machine # [ 93.650943] systemd[581]: Stopping Run user-specific NixOS activation...2200machine # [ 93.653614] systemd[581]: Starting Run user-specific NixOS activation...2201machine # [ 93.698356] systemd[581]: Finished Run user-specific NixOS activation.2202machine # [ 93.701181] systemd[581]: Reload requested from client PID 4368 ('switch-to-confi')...2203machine # [ 93.704106] systemd[581]: Reloading...2204machine # [ 93.877279] systemd[581]: Reloading finished in 174 ms.2205machine # [ 93.892161] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2206machine # [ 93.902387] systemd[581]: Stopped migrated.service.2207machine # [ 93.904075] systemd[581]: Stopping migrated.service...2208machine # [ 93.906754] systemd[581]: Starting migrated.service...2209machine # [ 93.944098] systemd[581]: Finished migrated.service.2210machine # [ 93.950486] systemd[1]: Stopped target Reactivate sysinit units.2211machine # [ 93.953334] systemd[1]: Stopping Reactivate sysinit units...2212machine # [ 93.954792] systemd[1]: Reached target Reactivate sysinit units.2213machine # [ 93.958490] systemd[1]: Reached target Local File Systems.2214machine # [ 93.959403] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2215machine # [ 93.968845] systemd[1]: Starting Create SUID/SGID Wrappers...2216machine # [ 93.969791] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2217machine # [ 93.971004] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672218machine # [ 93.981576] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2219machine # [ 93.983229] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2220machine # [ 93.984921] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2221machine # [ 93.990030] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2222machine # [ 93.991972] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2223machine # [ 93.993918] systemd[1]: Reached target Remote File Systems.2224machine # [ 94.297831] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2225machine # [ 94.302477] systemd[1]: Finished Create SUID/SGID Wrappers.2226machine # [ 94.306018] systemd[1]: Starting linger-users.service...2227machine # [ 94.365803] systemd[1]: linger-users.service: Deactivated successfully.2228machine # [ 94.368391] systemd[1]: Finished linger-users.service.2229machine # [ 94.626208] nixos[4299]: finished switching to system configuration /nix/store/nbinm9zxg0vk3jxqriby4nd341jimby3-nixos-system-machine-test2230machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.22 seconds)2231machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2232machine # [ 94.706962] sudo[4442]: 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.service2233machine # [ 94.724892] sudo[4442]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2234machine # [ 94.739177] sudo[4442]: pam_unix(sudo:session): session closed for user usertest2235machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)2236machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2237machine # [ 94.785741] sudo[4448]: 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.service2238machine # [ 94.799887] sudo[4448]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2239machine # [ 94.825141] sudo[4448]: pam_unix(sudo:session): session closed for user usertest2240machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.09 seconds)2241machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2242machine # [ 94.865463] sudo[4454]: 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-owner2243machine # [ 94.877910] sudo[4454]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2244machine # [ 94.889318] sudo[4454]: pam_unix(sudo:session): session closed for user usertest2245machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)2246machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12247machine # [ 95.396382] nixos[4463]: switching to system configuration /nix/store/nbinm9zxg0vk3jxqriby4nd341jimby3-nixos-system-machine-test2248machine # [ 95.400619] systemd[1]: Stopped target Local File Systems.2249machine # [ 95.401760] systemd[1]: Stopped target Remote File Systems.2250machine # [ 95.855081] systemd[1]: Reload requested from client PID 4463 ('.switch-to-conf') (unit backdoor.service)...2251machine # [ 95.857309] systemd[1]: Reloading...2252machine # [ 96.444749] systemd[1]: Reloading finished in 585 ms.2253machine # [ 96.600529] systemd[581]: Reexecution requested from client PID 4532 ('switch-to-confi')...2254machine # [ 96.604184] systemd[581]: Reexecuting.2255machine # [ 96.670592] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2256machine # [ 96.873837] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2257machine # [ 96.880617] systemd[581]: Reload requested from client PID 4532 ('switch-to-confi')...2258machine # [ 96.883513] systemd[581]: Reloading...2259machine # [ 97.082727] systemd[581]: Reloading finished in 198 ms.2260machine # [ 97.096180] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2261machine # [ 97.105530] systemd[581]: Stopped Run user-specific NixOS activation.2262machine # [ 97.108124] systemd[581]: Stopping Run user-specific NixOS activation...2263machine # [ 97.111277] systemd[581]: Starting Run user-specific NixOS activation...2264machine # [ 97.154600] systemd[581]: Finished Run user-specific NixOS activation.2265machine # [ 97.158700] systemd[1]: Stopped target Reactivate sysinit units.2266machine # [ 97.160753] systemd[1]: Stopping Reactivate sysinit units...2267machine # [ 97.163425] systemd[1]: Reached target Reactivate sysinit units.2268machine # [ 97.165487] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2269machine # [ 97.169895] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2270machine # [ 97.171219] systemd[1]: Reached target Remote File Systems.2271machine # [ 97.173891] systemd[1]: Starting linger-users.service...2272machine # [ 97.184118] systemd[1]: Reached target Local File Systems.2273machine # [ 97.185037] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2274machine # [ 97.190318] systemd[1]: Starting Create SUID/SGID Wrappers...2275machine # [ 97.191217] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2276machine # [ 97.201779] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672277machine # [ 97.205463] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2278machine # [ 97.209439] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2279machine # [ 97.210818] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2280machine # [ 97.272527] systemd[1]: linger-users.service: Deactivated successfully.2281machine # [ 97.275448] systemd[1]: Finished linger-users.service.2282machine # [ 97.543649] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2283machine # [ 97.547191] systemd[1]: Finished Create SUID/SGID Wrappers.2284machine # [ 97.803700] nixos[4463]: finished switching to system configuration /nix/store/nbinm9zxg0vk3jxqriby4nd341jimby3-nixos-system-machine-test2285machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 2.93 seconds)2286machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd2287machine # [ 97.891306] sudo[4600]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd2288machine # [ 97.913170] sudo[4600]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2289machine # [ 97.928812] sudo[4600]: pam_unix(sudo:session): session closed for user usertest2290machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.12 seconds)2291machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2292machine # [ 97.982333] sudo[4606]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2293machine # [ 97.996675] sudo[4606]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2294machine # [ 98.007276] systemd[581]: Reload requested from client PID 4611 ('systemctl')...2295machine # [ 98.009715] systemd[581]: Reloading...2296machine # [ 98.205805] systemd[581]: Reloading finished in 194 ms.2297machine # [ 98.220169] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2298machine # [ 98.231930] sudo[4606]: pam_unix(sudo:session): session closed for user usertest2299machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.30 seconds)2300machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&12301machine # [ 98.773799] nixos[4619]: switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test2302machine # [ 98.777950] systemd[1]: Stopped target Local File Systems.2303machine # [ 98.779075] systemd[1]: Stopped target Remote File Systems.2304machine # [ 99.228656] systemd[1]: Reload requested from client PID 4619 ('.switch-to-conf') (unit backdoor.service)...2305machine # [ 99.230589] systemd[1]: Reloading...2306machine # [ 99.817910] systemd[1]: Reloading finished in 584 ms.2307machine # [ 99.965488] systemd[581]: Stopped migrated.service.2308machine # [ 99.968495] systemd[581]: Reexecution requested from client PID 4688 ('switch-to-confi')...2309machine # [ 99.972706] systemd[581]: Reexecuting.2310machine # [ 100.033872] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2311machine # [ 100.226335] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2312machine # [ 100.233681] systemd[581]: Reload requested from client PID 4688 ('switch-to-confi')...2313machine # [ 100.234972] systemd[581]: Reloading...2314machine # [ 100.429219] systemd[581]: Reloading finished in 192 ms.2315machine # [ 100.444160] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2316machine # [ 100.453586] systemd[581]: Stopped Run user-specific NixOS activation.2317machine # [ 100.455876] systemd[581]: Stopping Run user-specific NixOS activation...2318machine # [ 100.458725] systemd[581]: Starting Run user-specific NixOS activation...2319machine # [ 100.504749] systemd[581]: Finished Run user-specific NixOS activation.2320machine # [ 100.508780] systemd[1]: Stopped target Reactivate sysinit units.2321machine # [ 100.511763] systemd[1]: Stopping Reactivate sysinit units...2322machine # [ 100.513360] systemd[1]: Reached target Reactivate sysinit units.2323machine # [ 100.517296] systemd[1]: Reached target Remote File Systems.2324machine # [ 100.522233] systemd[1]: Starting linger-users.service...2325machine # [ 100.527006] systemd[1]: Reached target Local File Systems.2326machine # [ 100.530162] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2327machine # [ 100.531677] systemd[1]: Starting Create SUID/SGID Wrappers...2328machine # [ 100.544241] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2329machine # [ 100.545541] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672330machine # [ 100.547686] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2331machine # [ 100.553629] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2332machine # [ 100.554959] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2333machine # [ 100.556188] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2334machine # [ 100.557460] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2335machine # [ 100.620789] systemd[1]: linger-users.service: Deactivated successfully.2336machine # [ 100.623777] systemd[1]: Finished linger-users.service.2337machine # [ 100.863845] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2338machine # [ 100.867429] systemd[1]: Finished Create SUID/SGID Wrappers.2339machine # [ 101.122073] nixos[4619]: finished switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test2340machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.90 seconds)2341machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2342machine # [ 101.198695] sudo[4756]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2343machine # [ 101.215459] sudo[4756]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2344machine # [ 101.229547] sudo[4756]: pam_unix(sudo:session): session closed for user usertest2345machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.10 seconds)2346machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service2347machine # [ 101.278613] sudo[4762]: 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.service2348machine # [ 101.293729] sudo[4762]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2349machine # [ 101.308571] sudo[4762]: pam_unix(sudo:session): session closed for user usertest2350machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.08 seconds)2351machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2352machine # [ 101.357642] sudo[4768]: 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.service2353machine # [ 101.371089] sudo[4768]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2354machine # [ 101.383670] sudo[4768]: pam_unix(sudo:session): session closed for user usertest2355machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.07 seconds)2356machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2357machine # [ 101.429340] sudo[4774]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2358machine # [ 101.442551] sudo[4774]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2359machine # [ 101.452926] systemd[581]: Reload requested from client PID 4779 ('systemctl')...2360machine # [ 101.455677] systemd[581]: Reloading...2361machine # [ 101.646728] systemd[581]: Reloading finished in 190 ms.2362machine # [ 101.664174] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2363machine # [ 101.677775] sudo[4774]: pam_unix(sudo:session): session closed for user usertest2364machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.29 seconds)2365machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2366machine # [ 101.723489] sudo[4784]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2367machine # [ 101.736904] sudo[4784]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2368machine # [ 101.754609] systemd[581]: Starting migrated.service...2369machine # [ 101.793864] systemd[581]: Finished migrated.service.2370machine # [ 101.798225] sudo[4784]: pam_unix(sudo:session): session closed for user usertest2371machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.12 seconds)2372machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2373machine # [ 101.844401] sudo[4792]: 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.service2374machine # [ 101.857680] sudo[4792]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2375machine # [ 101.869551] sudo[4792]: pam_unix(sudo:session): session closed for user usertest2376machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2377machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2378machine # [ 101.912871] sudo[4798]: 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-owner2379machine # [ 101.925573] sudo[4798]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2380machine # [ 101.937429] sudo[4798]: pam_unix(sudo:session): session closed for user usertest2381machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)2382machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2383machine # [ 101.983350] sudo[4804]: 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.service2384machine # [ 101.995552] sudo[4804]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2385machine # [ 102.015892] sudo[4804]: pam_unix(sudo:session): session closed for user usertest2386machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds)2387machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12388machine # [ 102.541425] nixos[4813]: switching to system configuration /nix/store/nbinm9zxg0vk3jxqriby4nd341jimby3-nixos-system-machine-test2389machine # [ 102.544169] systemd[1]: Stopped target Local File Systems.2390machine # [ 102.546459] systemd[1]: Stopped target Remote File Systems.2391machine # [ 102.981533] systemd[1]: Reload requested from client PID 4813 ('.switch-to-conf') (unit backdoor.service)...2392machine # [ 102.983488] systemd[1]: Reloading...2393machine # [ 103.532675] systemd[1]: Reloading finished in 547 ms.2394machine # [ 103.680473] systemd[581]: Reexecution requested from client PID 4882 ('switch-to-confi')...2395machine # [ 103.681886] systemd[581]: Reexecuting.2396machine # [ 103.745831] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2397machine # [ 103.933883] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2398machine # [ 103.941086] systemd[581]: Reload requested from client PID 4882 ('switch-to-confi')...2399machine # [ 103.942798] systemd[581]: Reloading...2400machine # [ 104.134480] systemd[581]: Reloading finished in 190 ms.2401machine # [ 104.148157] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2402machine # [ 104.157940] systemd[581]: Stopped Run user-specific NixOS activation.2403machine # [ 104.158954] systemd[581]: Stopping Run user-specific NixOS activation...2404machine # [ 104.161900] systemd[581]: Starting Run user-specific NixOS activation...2405machine # [ 104.210797] systemd[581]: Finished Run user-specific NixOS activation.2406machine # [ 104.213183] systemd[581]: Reload requested from client PID 4882 ('switch-to-confi')...2407machine # [ 104.216782] systemd[581]: Reloading...2408machine # [ 104.407039] systemd[581]: Reloading finished in 191 ms.2409machine # [ 104.420156] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2410machine # [ 104.434168] systemd[1]: Stopped target Reactivate sysinit units.2411machine # [ 104.436009] systemd[1]: Stopping Reactivate sysinit units...2412machine # [ 104.438427] systemd[1]: Reached target Reactivate sysinit units.2413machine # [ 104.442228] systemd[1]: Reached target Remote File Systems.2414machine # [ 104.447306] systemd[1]: Starting linger-users.service...2415machine # [ 104.451699] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2416machine # [ 104.456497] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2417machine # [ 104.460536] systemd[1]: Reached target Local File Systems.2418machine # [ 104.465919] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2419machine # [ 104.470713] systemd[1]: Starting Create SUID/SGID Wrappers...2420machine # [ 104.471623] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2421machine # [ 104.474316] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672422machine # [ 104.477507] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2423machine # [ 104.479630] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2424machine # [ 104.484502] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2425machine # [ 104.539706] systemd[1]: linger-users.service: Deactivated successfully.2426machine # [ 104.542953] systemd[1]: Finished linger-users.service.2427machine # [ 104.820041] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2428machine # [ 104.822961] systemd[1]: Finished Create SUID/SGID Wrappers.2429machine # [ 105.082994] nixos[4813]: finished switching to system configuration /nix/store/nbinm9zxg0vk3jxqriby4nd341jimby3-nixos-system-machine-test2430machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.08 seconds)2431machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2432machine # [ 105.166240] sudo[4954]: 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.service2433machine # [ 105.186224] sudo[4954]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2434machine # [ 105.216767] sudo[4954]: pam_unix(sudo:session): session closed for user usertest2435machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.12 seconds)2436machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2437machine # [ 105.269354] sudo[4960]: 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-owner2438machine # [ 105.283402] sudo[4960]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2439machine # [ 105.296563] sudo[4960]: pam_unix(sudo:session): session closed for user usertest2440machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)2441machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd2442machine # [ 105.342639] sudo[4966]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd2443machine # [ 105.354976] sudo[4966]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2444machine # [ 105.366761] sudo[4966]: pam_unix(sudo:session): session closed for user usertest2445machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.07 seconds)2446machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2447machine # [ 105.410062] sudo[4972]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2448machine # [ 105.423792] sudo[4972]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2449machine # [ 105.433912] systemd[581]: Reload requested from client PID 4977 ('systemctl')...2450machine # [ 105.436690] systemd[581]: Reloading...2451machine # [ 105.630807] systemd[581]: Reloading finished in 192 ms.2452machine # [ 105.645556] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2453machine # [ 105.655430] sudo[4972]: pam_unix(sudo:session): session closed for user usertest2454machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.29 seconds)2455machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service2456machine # [ 105.700609] sudo[4982]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user stop migrated.service2457machine # [ 105.713343] sudo[4982]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2458machine # [ 105.726145] systemd[581]: Stopped migrated.service.2459machine # [ 105.730527] sudo[4982]: pam_unix(sudo:session): session closed for user usertest2460machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.07 seconds)2461machine: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&12462machine # [ 106.247048] nixos[4991]: switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test2463machine # [ 106.249972] systemd[1]: Stopped target Local File Systems.2464machine # [ 106.252476] systemd[1]: Stopped target Remote File Systems.2465machine # [ 106.689750] systemd[1]: Reload requested from client PID 4991 ('.switch-to-conf') (unit backdoor.service)...2466machine # [ 106.691672] systemd[1]: Reloading...2467machine # [ 107.273080] systemd[1]: Reloading finished in 578 ms.2468machine # [ 107.418817] systemd[581]: Reexecution requested from client PID 5060 ('switch-to-confi')...2469machine # [ 107.422678] systemd[581]: Reexecuting.2470machine # [ 107.486079] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2471machine # [ 107.679238] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2472machine # [ 107.686379] systemd[581]: Reload requested from client PID 5060 ('switch-to-confi')...2473machine # [ 107.688886] systemd[581]: Reloading...2474machine # [ 107.881203] systemd[581]: Reloading finished in 192 ms.2475machine # [ 107.896170] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2476machine # [ 107.906142] systemd[581]: Stopped Run user-specific NixOS activation.2477machine # [ 107.908583] systemd[581]: Stopping Run user-specific NixOS activation...2478machine # [ 107.911571] systemd[581]: Starting Run user-specific NixOS activation...2479machine # [ 107.956483] systemd[581]: Finished Run user-specific NixOS activation.2480machine # [ 107.960607] systemd[1]: Stopped target Reactivate sysinit units.2481machine # [ 107.962605] systemd[1]: Stopping Reactivate sysinit units...2482machine # [ 107.964790] systemd[1]: Reached target Reactivate sysinit units.2483machine # [ 107.966328] systemd[1]: Reached target Remote File Systems.2484machine # [ 107.969153] systemd[1]: Reached target Local File Systems.2485machine # [ 107.971711] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2486machine # [ 107.979133] systemd[1]: Starting Create SUID/SGID Wrappers...2487machine # [ 107.981316] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2488machine # [ 107.985767] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672489machine # [ 107.987926] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2490machine # [ 107.997376] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2491machine # [ 107.998714] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2492machine # [ 107.999912] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2493machine # [ 108.001372] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2494machine # [ 108.317363] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2495machine # [ 108.320838] systemd[1]: Finished Create SUID/SGID Wrappers.2496machine # [ 108.324571] systemd[1]: Starting linger-users.service...2497machine # [ 108.381578] systemd[1]: linger-users.service: Deactivated successfully.2498machine # [ 108.384168] systemd[1]: Finished linger-users.service.2499machine # [ 108.642181] nixos[4991]: finished switching to system configuration /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test2500machine: (finished: must succeed: /nix/store/43l4fnpsbsr5z2v9w6dxwwkyx3b4da10-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.92 seconds)2501(finished: subtest: user services, in 79.23 seconds)2502(finished: run the VM test script, in 109.38 seconds)2503test script finished in 109.45s2504cleanup2505kill QemuMachine (pid 45)2506machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14)2507(finished: cleanup, in 0.17 seconds)2508additionally exposed symbols:2509 machine, other,2510 vlan1,2511 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