vm-test-run-switch-test-services
checks.aarch64-linux.switchTest
· 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.F4fLTEIHib', 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: 7bb2c026-4bd6-45d1-a5ab-3ab3cf8fad6b17machine # 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/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/init regInfo=/nix/store/gym0p1p4dywmcgpsl47a8zn1wgvbcjlf-closure-info/registration console=ttyAMA0,115200n8 console=tty061machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/gym0p1p4dywmcgpsl47a8zn1wgvbcjlf-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 @44cf0000 (indirect, esz 8, psz 64K, shr 1)96machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)97machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d1000098machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d2000099machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.100machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns101machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).102machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns103machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns104machine # [ 0.000027] arm-pv: using stolen time PV105machine # [ 0.000368] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)106machine # [ 0.000535] Console: colour dummy device 80x25107machine # [ 0.000542] printk: legacy console [tty0] enabled108machine # [ 0.000743] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)109machine # [ 0.000749] pid_max: default: 32768 minimum: 301110machine # [ 0.000823] LSM: initializing lsm=capability,landlock,yama,bpf,ima111machine # [ 0.000947] landlock: Up and running.112machine # [ 0.000949] Yama: becoming mindful.113machine # [ 0.001356] LSM support for eBPF active114machine # [ 0.001479] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)115machine # [ 0.001499] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)116machine # [ 0.002564] cacheinfo: Unable to detect cache hierarchy for CPU 0117machine # [ 0.003287] rcu: Hierarchical SRCU implementation.118machine # [ 0.003291] rcu: Max phase no-delay instances is 1000.119machine # [ 0.004426] fsl-mc MSI: its@8080000 domain created120machine # [ 0.004514] EFI services will not be available.121machine # [ 0.004588] smp: Bringing up secondary CPUs ...122machine # [ 0.004596] smp: Brought up 1 node, 1 CPU123machine # [ 0.004599] SMP: Total of 1 processors activated.124machine # [ 0.004602] CPU: All CPU(s) started at EL1125machine # [ 0.004614] CPU features: detected: Branch Target Identification126machine # [ 0.004618] CPU features: detected: ARMv8.4 Translation Table Level127machine # [ 0.004621] CPU features: detected: Instruction cache invalidation not required for I/D coherence128machine # [ 0.004625] CPU features: detected: Data cache clean to the PoU not required for I/D coherence129machine # [ 0.004628] CPU features: detected: Common not Private translations130machine # [ 0.004632] CPU features: detected: CRC32 instructions131machine # [ 0.004634] CPU features: detected: Data cache clean to Point of Deep Persistence132machine # [ 0.004638] CPU features: detected: Data cache clean to Point of Persistence133machine # [ 0.004641] CPU features: detected: Data independent timing control (DIT)134machine # [ 0.004644] CPU features: detected: E0PD135machine # [ 0.004647] CPU features: detected: Enhanced Counter Virtualization136machine # [ 0.004650] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)137machine # [ 0.004653] CPU features: detected: Enhanced Virtualization Traps138machine # [ 0.004656] CPU features: detected: Fine Grained Traps139machine # [ 0.004660] CPU features: detected: Generic authentication (architected QARMA5 algorithm)140machine # [ 0.004665] CPU features: detected: RCpc load-acquire (LDAPR)141machine # [ 0.004668] CPU features: detected: LSE atomic instructions142machine # [ 0.004670] CPU features: detected: Privileged Access Never143machine # [ 0.004673] CPU features: detected: PMUv3144machine # [ 0.004676] CPU features: detected: RAS Extension Support145machine # [ 0.004679] CPU features: detected: RASv1p1 Extension Support146machine # [ 0.004681] CPU features: detected: Random Number Generator147machine # [ 0.004684] CPU features: detected: Speculation barrier (SB)148machine # [ 0.004687] CPU features: detected: Stage-2 Force Write-Back149machine # [ 0.004690] CPU features: detected: TLB range maintenance instructions150machine # [ 0.004694] CPU features: detected: Speculative Store Bypassing Safe (SSBS)151machine # [ 0.004740] alternatives: applying system-wide alternatives152machine # [ 0.007580] CPU features: detected: BBM Level 2 without TLB conflict abort153machine # [ 0.007760] Memory: 894300K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 112964K reserved, 32768K cma-reserved)154machine # [ 0.008082] devtmpfs: initialized155machine # [ 0.009737] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)156machine # [ 0.009758] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).157machine # [ 0.009927] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL158machine # [ 0.009931] 0 pages in range for non-PLT usage159machine # [ 0.009932] 508288 pages in range for PLT usage160machine # [ 0.010031] pinctrl core: initialized pinctrl subsystem161machine # [ 0.010760] DMI not present or invalid.162machine # [ 0.013722] NET: Registered PF_NETLINK/PF_ROUTE protocol family163machine # [ 0.015910] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations164machine # [ 0.016065] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations165machine # [ 0.016235] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations166machine # [ 0.016255] audit: initializing netlink subsys (disabled)167machine # [ 0.016801] thermal_sys: Registered thermal governor 'fair_share'168machine # [ 0.016803] thermal_sys: Registered thermal governor 'bang_bang'169machine # [ 0.016806] thermal_sys: Registered thermal governor 'step_wise'170machine # [ 0.016809] thermal_sys: Registered thermal governor 'user_space'171machine # [ 0.016814] thermal_sys: Registered thermal governor 'power_allocator'172machine # [ 0.016836] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1173machine # [ 0.016846] cpuidle: using governor ladder174machine # [ 0.016851] cpuidle: using governor menu175machine # [ 0.017040] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.176machine # [ 0.017055] ASID allocator initialised with 65536 entries177machine # [ 0.018196] Serial: AMBA PL011 UART driver178machine # [ 0.023252] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1179machine # [ 0.023401] printk: console [ttyAMA0] enabled180machine # [ 0.144753] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages181machine # [ 0.144770] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page182machine # [ 0.144776] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages183machine # [ 0.144780] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page184machine # [ 0.144784] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages185machine # [ 0.144788] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page186machine # [ 0.144793] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages187machine # [ 0.144797] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page188machine # [ 0.152324] fbcon: Taking over console189machine # [ 0.152341] ACPI: Interpreter disabled.190machine # [ 0.154220] iommu: Default domain type: Translated191machine # [ 0.154230] iommu: DMA domain TLB invalidation policy: strict mode192machine # [ 0.155908] SCSI subsystem initialized193machine # [ 0.156573] usbcore: registered new interface driver usbfs194machine # [ 0.156605] usbcore: registered new interface driver hub195machine # [ 0.156620] usbcore: registered new device driver usb196machine # [ 0.164939] pps_core: LinuxPPS API ver. 1 registered197machine # [ 0.164947] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>198machine # [ 0.164959] PTP clock support registered199machine # [ 0.165012] EDAC MC: Ver: 3.0.0200machine # [ 0.167660] scmi_core: SCMI protocol bus registered201machine # [ 0.168580] FPGA manager framework202machine # [ 0.169525] vgaarb: loaded203machine # [ 0.170129] clocksource: Switched to clocksource arch_sys_counter204machine # [ 0.172354] VFS: Disk quotas dquot_6.6.0205machine # [ 0.172389] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)206machine # [ 0.175945] netfs: FS-Cache loaded207machine # [ 0.176059] pnp: PnP ACPI: disabled208machine # [ 0.179864] NET: Registered PF_INET protocol family209machine # [ 0.180015] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)210machine # [ 0.208280] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)211machine # [ 0.208319] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)212machine # [ 0.208340] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)213machine # [ 0.208386] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)214machine # [ 0.208461] TCP: Hash tables configured (established 8192 bind 8192)215machine # [ 0.208567] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)216machine # [ 0.208597] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)217machine # [ 0.208621] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)218machine # [ 0.208711] NET: Registered PF_UNIX/PF_LOCAL protocol family219machine # [ 0.208731] NET: Registered PF_XDP protocol family220machine # [ 0.208750] PCI: CLS 0 bytes, default 64221machine # [ 0.208972] Trying to unpack rootfs image as initramfs...222machine # [ 0.223538] kvm [1]: HYP mode not available223machine # [ 0.314584] Initialise system trusted keyrings224machine # [ 0.315301] workingset: timestamp_bits=42 max_order=18 bucket_order=0225machine # [ 0.316505] squashfs: version 4.0 (2009/01/31) Phillip Lougher226machine # [ 0.317274] 9p: Installing v9fs 9p2000 file system support227machine # [ 0.337813] Key type asymmetric registered228machine # [ 0.337830] Asymmetric key parser 'x509' registered229machine # [ 0.337887] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)230machine # [ 0.346197] io scheduler mq-deadline registered231machine # [ 0.346213] io scheduler kyber registered232machine # [ 0.351003] pl061_gpio 9030000.pl061: PL061 GPIO chip registered233machine # [ 0.358167] ledtrig-cpu: registered to indicate activity on CPUs234machine # [ 0.358621] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:235machine # [ 0.358641] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000236machine # [ 0.358675] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000237machine # [ 0.358684] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000238machine # [ 0.358705] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits239machine # [ 0.358730] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]240machine # [ 0.358806] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00241machine # [ 0.358815] pci_bus 0000:00: root bus resource [bus 00-ff]242machine # [ 0.358821] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]243machine # [ 0.358827] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]244machine # [ 0.358832] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]245machine # [ 0.358914] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint246machine # [ 0.359352] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint247machine # [ 0.359533] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]248machine # [ 0.359549] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]249machine # [ 0.359578] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]250machine # [ 0.359594] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]251machine # [ 0.360034] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint252machine # [ 0.360212] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]253machine # [ 0.360228] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]254machine # [ 0.360257] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]255machine # [ 0.360711] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint256machine # [ 0.360890] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]257machine # [ 0.360906] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]258machine # [ 0.360936] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]259machine # [ 0.361372] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint260machine # [ 0.361550] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]261machine # [ 0.361566] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]262machine # [ 0.361595] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]263machine # [ 0.362038] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint264machine # [ 0.362236] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]265machine # [ 0.362253] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]266machine # [ 0.362282] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]267machine # [ 0.362728] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint268machine # [ 0.362905] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]269machine # [ 0.362921] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]270machine # [ 0.362950] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]271machine # [ 0.363402] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint272machine # [ 0.363578] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]273machine # [ 0.363594] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]274machine # [ 0.363623] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275machine # [ 0.363639] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]276machine # [ 0.364077] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint277machine # [ 0.364259] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]278machine # [ 0.364288] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279machine # [ 0.364749] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint280machine # [ 0.364931] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.364961] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.365335] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint283machine # [ 0.365511] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]284machine # [ 0.365755] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint285machine # [ 0.366013] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]286machine # [ 0.366030] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]287machine # [ 0.366059] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]288machine # [ 0.414580] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint289machine # [ 0.414774] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]290machine # [ 0.414791] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]291machine # [ 0.414820] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]292machine # [ 0.415394] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned293machine # [ 0.415406] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned294machine # [ 0.415412] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned295machine # [ 0.415457] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned296machine # [ 0.415502] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned297machine # [ 0.415548] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned298machine # [ 0.415594] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned299machine # [ 0.415640] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned300machine # [ 0.415687] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned301machine # [ 0.415734] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned302machine # [ 0.415780] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned303machine # [ 0.415826] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned304machine # [ 0.415933] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned305machine # [ 0.415979] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned306machine # [ 0.416000] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned307machine # [ 0.416021] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned308machine # [ 0.416042] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned309machine # [ 0.416063] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned310machine # [ 0.416088] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned311machine # [ 0.416109] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned312machine # [ 0.416131] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned313machine # [ 0.416152] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned314machine # [ 0.416174] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned315machine # [ 0.416198] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned316machine # [ 0.416220] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned317machine # [ 0.416241] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned318machine # [ 0.416262] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned319machine # [ 0.416283] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned320machine # [ 0.416304] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned321machine # [ 0.416325] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned322machine # [ 0.416346] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned323machine # [ 0.416367] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned324machine # [ 0.416389] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned325machine # [ 0.416410] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned326machine # [ 0.416435] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]327machine # [ 0.416445] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]328machine # [ 0.416450] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]329machine # [ 0.417582] pci 0000:00:0a.0: enabling device (0000 -> 0002)330machine # [ 0.477417] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)331machine # [ 0.483508] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)332machine # [ 0.485449] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)333machine # [ 0.495554] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)334machine # [ 0.497715] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)335machine # [ 0.509091] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)336machine # [ 0.511937] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)337machine # [ 0.513987] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)338machine # [ 0.523919] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)339machine # [ 0.525697] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)340machine # [ 0.537145] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)341machine # [ 0.551343] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled342machine # [ 0.553872] msm_serial: driver initialized343machine # [ 0.554006] SuperH (H)SCI(F) driver initialized344machine # [ 0.554060] STM32 USART driver initialized345machine # [ 0.584843] loop: module loaded346machine # [ 0.585030] virtio_blk virtio5: 1/0/0 default/read/poll queues347machine # [ 0.585966] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)348machine # [ 0.590684] megasas: 07.734.00.00-rc1349machine # [ 0.591333] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]350machine # [ 0.602169] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000351machine # [ 0.602193] Intel/Sharp Extended Query Table at 0x0031352machine # [ 0.603891] Using buffer write method353machine # [ 0.603958] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]354machine # [ 0.606915] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000355machine # [ 0.606936] Intel/Sharp Extended Query Table at 0x0031356machine # [ 0.608308] Using buffer write method357machine # [ 0.608333] Concatenating MTD devices:358machine # [ 0.608337] (0): "0.flash"359machine # [ 0.608341] (1): "0.flash"360machine # [ 0.608344] into device "0.flash"361machine # [ 0.837933] Freeing initrd memory: 26104K362machine # [ 0.843805] tun: Universal TUN/TAP device driver, 1.6363machine # [ 0.847457] thunder_xcv, ver 1.0364machine # [ 0.847495] thunder_bgx, ver 1.0365machine # [ 0.847517] nicpf, ver 1.0366machine # [ 0.848050] e1000: Intel(R) PRO/1000 Network Driver367machine # [ 0.848057] e1000: Copyright (c) 1999-2006 Intel Corporation.368machine # [ 0.848085] e1000e: Intel(R) PRO/1000 Network Driver369machine # [ 0.848093] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.370machine # [ 0.848117] igb: Intel(R) Gigabit Ethernet Network Driver371machine # [ 0.848122] igb: Copyright (c) 2007-2014 Intel Corporation.372machine # [ 0.848143] igbvf: Intel(R) Gigabit Virtual Function Network Driver373machine # [ 0.848149] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.374machine # [ 0.848281] sky2: driver version 1.30375machine # [ 0.849796] usbcore: registered new interface driver usb-storage376machine # [ 0.849933] usbcore: registered new interface driver usbserial_generic377machine # [ 0.849946] usbserial: USB Serial support registered for generic378machine # [ 0.850868] ehci-pci 0000:00:0a.0: EHCI Host Controller379machine # [ 0.850915] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1380machine # [ 0.851155] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000381machine # [ 0.862597] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00382machine # [ 0.862904] hub 1-0:1.0: USB hub found383machine # [ 0.862929] hub 1-0:1.0: 6 ports detected384machine # [ 0.865363] hv_vmbus: registering driver hyperv_keyboard385machine # [ 0.866869] rtc-pl031 9010000.pl031: registered as rtc0386machine # [ 0.866892] rtc-pl031 9010000.pl031: setting system clock to 2026-09-06T17:55:29 UTC (1788717329)387machine # [ 0.867188] i2c_dev: i2c /dev entries driver388machine # [ 0.871921] sdhci: Secure Digital Host Controller Interface driver389machine # [ 0.871934] sdhci: Copyright(c) Pierre Ossman390machine # [ 0.872216] Synopsys Designware Multimedia Card Interface Driver391machine # [ 0.872582] sdhci-pltfm: SDHCI platform and OF driver helper392machine # [ 0.876806] hid: raw HID events driver (C) Jiri Kosina393machine # [ 0.877055] usbcore: registered new interface driver usbhid394machine # [ 0.877061] usbhid: USB HID core driver395machine # [ 0.879936] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available396machine # [ 0.881444] drop_monitor: Initializing network drop monitor service397machine # [ 0.881600] NET: Registered PF_INET6 protocol family398machine # [ 0.884602] Segment Routing with IPv6399machine # [ 0.884630] In-situ OAM (IOAM) with IPv6400machine # [ 0.884658] NET: Registered PF_PACKET protocol family401machine # [ 0.886476] 9pnet: Installing 9P2000 support402machine # [ 0.888515] Key type dns_resolver registered403machine # [ 0.895026] registered taskstats version 1404machine # [ 0.895174] Loading compiled-in X.509 certificates405machine # [ 0.903441] Demotion targets for Node 0: null406machine # [ 0.903535] Key type .fscrypt registered407machine # [ 0.903541] Key type fscrypt-provisioning registered408machine # [ 0.903632] ima: No TPM chip found, activating TPM-bypass!409machine # [ 0.903652] ima: Allocated hash algorithm: sha1410machine # [ 0.903676] ima: No architecture policies found411machine # [ 0.907715] input: gpio-keys as /devices/platform/gpio-keys/input/input0412machine # [ 0.925213] clk: Disabling unused clocks413machine # [ 0.925246] PM: genpd: Disabling unused power domains414machine # [ 0.929435] Freeing unused kernel memory: 4736K415machine # [ 0.929617] Run /init as init process416machine # [ 0.944292] systemd[1]: Successfully made /usr/ read-only.417machine # [ 1.110209] usb 1-1: new high-speed USB device number 2 using ehci-pci418machine # [ 1.262546] 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.279143] 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.291166] systemd[1]: Detected virtualization qemu.421machine # [ 1.293146] systemd[1]: Detected architecture arm64.422machine # [ 1.295080] systemd[1]: Running in initrd.423machine # [ 1.297664] systemd[1]: Initializing machine ID from random generator.424machine # [ 1.300540] systemd[1]: Hostname set to <machine>.425machine # [ 1.346403] 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.443261] systemd[1]: bpf-restrict-fs: LSM BPF program attached427machine # [ 1.466229] usb 1-2: new high-speed USB device number 3 using ehci-pci428machine # [ 1.495737] systemd[1]: Queued start job for default target Initrd Default Target.429machine # [ 1.504026] systemd[1]: Created slice Slice /system/modprobe.430machine # [ 1.505080] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.431machine # [ 1.506128] systemd[1]: Expecting device /dev/disk/by-label/nixos...432machine # [ 1.507022] systemd[1]: Reached target Path Units.433machine # [ 1.507667] systemd[1]: Reached target Slice Units.434machine # [ 1.508313] systemd[1]: Reached target Swaps.435machine # [ 1.508896] systemd[1]: Reached target Timer Units.436machine # [ 1.509659] systemd[1]: Listening on D-Bus System Message Bus Socket.437machine # [ 1.510786] systemd[1]: Listening on Journal Socket (/dev/log).438machine # [ 1.511697] systemd[1]: Listening on Journal Sockets.439machine # [ 1.512450] systemd[1]: Listening on udev Kernel Socket.440machine # [ 1.513172] systemd[1]: Reached target Socket Units.441machine # [ 1.515120] systemd[1]: Starting Create List of Static Device Nodes...442machine # [ 1.521671] systemd[1]: Starting Load Kernel Module 9pnet_virtio...443machine # [ 1.522779] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs444machine # [ 1.537329] systemd[1]: Mounting Kernel Configuration File System...445machine # [ 1.548622] systemd[1]: Starting Journal Service...446machine # [ 1.561717] systemd[1]: Starting Load Kernel Modules...447machine # [ 1.562628] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os448machine # [ 1.575269] systemd[1]: Starting Coldplug All udev Devices...449machine # [ 1.594411] systemd[1]: Finished Create List of Static Device Nodes.450machine # [ 1.595524] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.451machine # [ 1.595802] systemd[1]: Finished Load Kernel Module 9pnet_virtio.452machine # [ 1.596017] systemd[1]: Mounted Kernel Configuration File System.453machine # [ 1.602540] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...454machine # [ 1.623144] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2455machine # [ 1.623276] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0456machine # [ 1.627765] systemd-journald[73]: Collecting audit messages is disabled.457machine # [ 1.636083] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.458machine # [ 1.642251] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev459machine # [ 1.646631] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0460machine # [ 1.646866] [drm] features: -virgl +edid -resource_blob -host_visible461machine # [ 1.646875] [drm] features: -context_init462machine # [ 1.647574] [drm] number of scanouts: 1463machine # [ 1.647591] [drm] number of cap sets: 0464machine # [ 1.666257] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.465machine # [ 1.668725] systemd[1]: Starting Create Static Device Nodes in /dev...466machine # [ 1.670529] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic467machine # [ 1.670545] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0468machine # [ 1.702757] Console: switching to colour frame buffer device 160x50469machine # [ 1.709147] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device470machine # [ 1.726705] systemd[1]: Finished Create Static Device Nodes in /dev.471machine # [ 1.726897] systemd[1]: Reached target Preparation for Local File Systems.472machine # [ 1.726931] systemd[1]: Reached target Local File Systems.473machine # [ 1.731658] systemd[1]: Starting Rule-based Manager for Device Events and Files...474machine # [ 1.738736] systemd[1]: Finished Load Kernel Modules.475machine # [ 1.750561] systemd[1]: Starting Apply Kernel Variables...476machine # [ 1.786694] systemd[1]: Finished Apply Kernel Variables.477machine # [ 1.810453] systemd[1]: Started Journal Service.478machine # [ 1.799329] systemd-modules-load[74]: Inserted module 'dm_mod'479machine # [ 1.800427] systemd-modules-load[74]: Module 'virtio_balloon' is built in480machine # [ 1.801520] systemd-modules-load[74]: Module 'virtio_console' is built in481machine # [ 1.802601] systemd-modules-load[74]: Inserted module 'virtio_gpu'482machine # [ 1.803574] systemd-modules-load[74]: Module 'virtio_rng' is built in483machine # [ 1.820403] systemd[1]: Starting Create System Files and Directories...484machine # [ 1.821744] systemd-udevd[79]: Using default interface naming scheme 'v261'.485machine # [ 1.850008] systemd[1]: Finished Create System Files and Directories.486machine # [ 1.868892] systemd[1]: Started Rule-based Manager for Device Events and Files.487machine # [ 1.947984] systemd[1]: Starting Virtual Console Setup...488machine # [ 2.004443] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.489machine # [ 2.007701] systemd[1]: Finished Virtual Console Setup.490machine # [ 2.609149] systemd[1]: Finished Coldplug All udev Devices.491machine # [ 2.610127] systemd[1]: Reached target System Initialization.492machine # [ 2.611121] systemd[1]: Reached target Basic System.493machine # [ 2.766716] (udev-worker)[94]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.494machine # [ 2.781873] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.495machine # [ 2.792588] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line.496machine # [ 2.839543] systemd[1]: Found device /dev/disk/by-label/nixos.497machine # [ 2.843054] systemd[1]: Reached target Initrd Root Device.498machine # [ 2.846434] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...499machine # [ 2.890949] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks500machine # [ 2.898024] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.501machine # [ 2.904421] systemd[1]: Mounting /sysroot...502machine # [ 2.951462] EXT4-fs (vda): mounted filesystem 7bb2c026-4bd6-45d1-a5ab-3ab3cf8fad6b r/w with ordered data mode. Quota mode: none.503machine # [ 2.945298] systemd[1]: Mounted /sysroot.504machine # [ 2.946343] systemd[1]: Reached target Initrd Root File System.505machine # [ 2.952239] systemd[1]: Starting Mountpoints Configured in the Real Root...506machine # [ 2.976710] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.507machine # [ 2.981315] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...508machine # [ 2.985906] systemd[1]: Reloading...509machine # [ 3.165302] systemd[1]: Reloading finished in 179 ms.510machine # [ 3.198819] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...511machine # [ 3.201851] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...512machine # [ 3.204650] systemd[1]: Starting Load Kernel Module 9pnet_virtio...513machine # [ 3.215583] systemd[1]: initrd-parse-etc.service: Deactivated successfully.514machine # [ 3.226251] systemd[1]: Finished Mountpoints Configured in the Real Root.515machine # [ 3.227317] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.516machine # [ 3.233204] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.517machine # [ 3.235815] systemd[1]: Finished Load Kernel Module 9pnet_virtio.518machine # [ 3.567537] systemd[1]: Mounting /sysroot/nix/.ro-store...519machine # [ 3.579256] systemd[1]: Mounting /sysroot/nix/.rw-store...520machine # [ 3.593494] systemd[1]: Mounting /sysroot/run...521machine # [ 3.617411] systemd[1]: Mounting /sysroot/tmp/shared...522machine # [ 3.637299] systemd[1]: Mounting /sysroot/tmp/xchg...523machine # [ 3.639985] systemd[1]: Mounted /sysroot/nix/.rw-store.524machine # [ 3.671286] systemd[1]: Starting rw-sysroot-nix-store.service...525machine # [ 3.674898] systemd[1]: Mounted /sysroot/nix/.ro-store.526machine # [ 3.694082] systemd[1]: Mounted /sysroot/run.527machine # [ 3.695935] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.528machine # [ 3.705872] systemd[1]: Finished rw-sysroot-nix-store.service.529machine # [ 3.714288] systemd[1]: Mounted /sysroot/tmp/shared.530machine # [ 3.727133] systemd[1]: Mounting /sysroot/nix/store...531machine # [ 3.731713] systemd[1]: Mounted /sysroot/tmp/xchg.532machine # [ 3.768007] systemd[1]: Mounted /sysroot/nix/store.533machine # [ 3.770514] systemd[1]: Reached target Initrd File Systems.534machine # [ 3.774704] systemd[1]: Starting Find NixOS closure...535machine # [ 3.784169] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...536machine # [ 3.824120] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.537machine # [ 3.839800] systemd[1]: Finished Find NixOS closure.538machine # [ 3.843102] systemd[1]: Reached target Initrd Default Target.539machine # [ 3.850923] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...540machine # [ 3.878996] systemd[1]: Stopped target Initrd Default Target.541machine # [ 3.880114] systemd[1]: Stopped target Basic System.542machine # [ 3.881110] systemd[1]: Stopped target Initrd Root Device.543machine # [ 3.885121] systemd[1]: Stopped target Path Units.544machine # [ 3.887039] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.545machine # [ 3.889987] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.546machine # [ 3.892860] systemd[1]: Stopped target Slice Units.547machine # [ 3.896791] systemd[1]: Stopped target Socket Units.548machine # [ 3.898642] systemd[1]: Stopped target System Initialization.549machine # [ 3.900844] systemd[1]: Stopped target Swaps.550machine # [ 3.904768] systemd[1]: Stopped target Timer Units.551machine # [ 3.906610] systemd[1]: dbus.socket: Deactivated successfully.552machine # [ 3.910458] systemd[1]: Closed D-Bus System Message Bus Socket.553machine # [ 3.912232] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.554machine # [ 3.917110] systemd[1]: Stopped Find NixOS closure.555machine # [ 3.918856] systemd[1]: Starting Load Kernel Module 9pnet_virtio...556machine # [ 3.925543] systemd[1]: Starting rw-sysroot-nix-store.service...557machine # [ 3.929438] systemd[1]: systemd-sysctl.service: Deactivated successfully.558machine # [ 3.932464] systemd[1]: Stopped Apply Kernel Variables.559machine # [ 3.940457] systemd[1]: systemd-modules-load.service: Deactivated successfully.560machine # [ 3.948725] systemd[1]: Stopped Load Kernel Modules.561machine # [ 3.951479] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.562machine # [ 3.957939] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.563machine # [ 3.959047] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.564machine # [ 3.962334] systemd[1]: Stopped Create System Files and Directories.565machine # [ 3.965059] systemd[1]: Stopped target Local File Systems.566machine # [ 3.965883] systemd[1]: Stopped target Preparation for Local File Systems.567machine # [ 3.966818] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.568machine # [ 3.967791] systemd[1]: Stopped Coldplug All udev Devices.569machine # [ 3.972843] systemd[1]: Stopping Rule-based Manager for Device Events and Files...570machine # [ 3.976354] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.571machine # [ 3.977419] systemd[1]: Stopped Virtual Console Setup.572machine # [ 3.980455] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.573machine # [ 3.981493] systemd[1]: Finished Load Kernel Module 9pnet_virtio.574machine # [ 3.982580] systemd[1]: initrd-cleanup.service: Deactivated successfully.575machine # [ 3.983591] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.576machine # [ 3.984828] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.577machine # [ 3.985811] systemd[1]: Finished rw-sysroot-nix-store.service.578machine # [ 3.986617] systemd[1]: systemd-udevd.service: Deactivated successfully.579machine # [ 3.987536] systemd[1]: Stopped Rule-based Manager for Device Events and Files.580machine # [ 3.996287] systemd[1]: systemd-udevd.service: Consumed 1.128s CPU time over 2.253s wall clock time, 17.9M memory peak.581machine # [ 3.997719] systemd[1]: Starting Cleanup udev Database...582machine # [ 3.998726] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.583machine # [ 4.004183] systemd[1]: Stopped Create Static Device Nodes in /dev.584machine # [ 4.005078] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.585machine # [ 4.006203] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.586machine # [ 4.007171] systemd[1]: kmod-static-nodes.service: Deactivated successfully.587machine # [ 4.012502] systemd[1]: Stopped Create List of Static Device Nodes.588machine # [ 4.029139] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.589machine # [ 4.032564] systemd[1]: Finished Cleanup udev Database.590machine # [ 4.033385] systemd[1]: Reached target Switch Root.591machine # [ 4.036498] systemd[1]: Starting NixOS Activation...592machine # [ 4.197838] initrd-nixos-activation-start[172]: booting system configuration /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test593machine # [ 4.262601] initrd-nixos-activation-start[172]: running activation script...594machine # [ 4.680991] initrd-nixos-activation-start[195]: setting up /etc...595machine # [ 4.935993] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.596machine # [ 4.938692] systemd[1]: Finished NixOS Activation.597machine # [ 4.943860] systemd[1]: Starting Switch Root...598machine # [ 4.963906] systemd[1]: Switching root.599machine # [ 5.153491] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).600machine # [ 5.753522] 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.766591] systemd[1]: Detected virtualization qemu.602machine # [ 5.769570] systemd[1]: Detected architecture arm64.603machine # [ 5.773383] systemd[1]: Detected first boot.604machine # [ 5.780384] systemd[1]: Initializing machine ID from random generator.605machine # [ 5.948524] systemd[1]: bpf-restrict-fs: LSM BPF program attached606machine # [ 6.111155] systemd[1]: Applying preset policy.607machine # [ 6.589428] systemd[1]: Populated /etc with preset unit settings.608machine # [ 7.086965] systemd[1]: initrd-switch-root.service: Deactivated successfully.609machine # [ 7.088315] systemd[1]: Stopped initrd-switch-root.service.610machine # [ 7.091704] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.611machine # [ 7.095326] systemd[1]: Created slice Slice /system/getty.612machine # [ 7.097382] systemd[1]: Created slice User and Session Slice.613machine # [ 7.098930] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.614machine # [ 7.099369] systemd[1]: Started Forward Password Requests to Wall Directory Watch.615machine # [ 7.099691] systemd[1]: Expecting device /dev/hvc0...616machine # [ 7.099952] systemd[1]: Expecting device /dev/ttyAMA0...617machine # [ 7.100208] systemd[1]: Reached target Local Encrypted Volumes.618machine # [ 7.100466] systemd[1]: Stopped target initrd-fs.target.619machine # [ 7.100741] systemd[1]: Stopped target initrd-root-fs.target.620machine # [ 7.100987] systemd[1]: Stopped target initrd-switch-root.target.621machine # [ 7.101234] systemd[1]: Reached target Virtual Machines and Containers.622machine # [ 7.101494] systemd[1]: Reached target Path Units.623machine # [ 7.101735] systemd[1]: Reached target Remote File Systems.624machine # [ 7.101974] systemd[1]: Reached target Slice Units.625machine # [ 7.110917] systemd[1]: Reached target Swaps.626machine # [ 7.115416] systemd[1]: Listening on Query the User Interactively for a Password.627machine # [ 7.120491] systemd[1]: Listening on Process Core Dump Socket.628machine # [ 7.124462] systemd[1]: Listening on Credential Encryption/Decryption.629machine # [ 7.128162] systemd[1]: Listening on Factory Reset Management.630machine # [ 7.129378] systemd[1]: Listening on Hostname Service Socket.631machine # [ 7.135073] systemd[1]: Starting Journal Log Access Socket...632machine # [ 7.137140] systemd[1]: Listening on Journal Audit Socket.633machine # [ 7.140902] systemd[1]: Listening on Console Output Muting Service Socket.634machine # [ 7.142502] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.635machine # [ 7.144214] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os636machine # [ 7.147022] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki637machine # [ 7.158232] systemd[1]: Listening on Disk Repartitioning Service Socket.638machine # [ 7.160483] systemd[1]: Listening on udev Varlink Socket.639machine # [ 7.165327] systemd[1]: Mounting Huge Pages File System...640machine # [ 7.170697] systemd[1]: Mounting POSIX Message Queue File System...641machine # [ 7.179652] systemd[1]: Mounting Kernel Debug File System...642machine # [ 7.191118] systemd[1]: Mounting Kernel Trace File System...643machine # [ 7.203049] systemd[1]: Starting Create List of Static Device Nodes...644machine # [ 7.217005] systemd[1]: Starting Load Kernel Module 9pnet_virtio...645machine # [ 7.220905] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs646machine # [ 7.232110] systemd[1]: Mounting Kernel Configuration File System...647machine # [ 7.237335] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm648machine # [ 7.240142] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore649machine # [ 7.265166] systemd[1]: Starting Load Kernel Module fuse...650machine # [ 7.265556] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67651machine # [ 7.336713] systemd[1]: Starting Journal Service...652machine # [ 7.369196] systemd[1]: Starting Load Kernel Modules...653machine # [ 7.399728] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...654machine # [ 7.434867] systemd[1]: Starting Remount Root and Kernel File Systems...655machine # [ 7.438857] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os656machine # [ 7.466784] systemd[1]: Starting Coldplug All udev Devices...657machine # [ 7.469507] systemd[1]: Listening on Journal Log Access Socket.658machine # [ 7.469926] systemd[1]: Mounted Huge Pages File System.659machine # [ 7.482657] systemd[1]: Mounted POSIX Message Queue File System.660machine # [ 7.496697] fuse: init (API version 7.45)661machine # [ 7.507638] systemd[1]: Mounted Kernel Debug File System.662machine # [ 7.512325] systemd[1]: Mounted Kernel Trace File System.663machine # [ 7.518667] systemd-journald[268]: Collecting audit messages is enabled.664machine # [ 7.526250] systemd[1]: Finished Create List of Static Device Nodes.665machine # [ 7.527142] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.666machine # [ 7.527679] systemd[1]: Finished Load Kernel Module 9pnet_virtio.667machine # [ 7.528136] systemd[1]: Mounted Kernel Configuration File System.668machine # [ 7.528784] systemd[1]: modprobe@fuse.service: Deactivated successfully.669machine # [ 7.529299] systemd[1]: Finished Load Kernel Module fuse.670machine # [ 7.529958] systemd[1]: Finished Load Kernel Modules.671machine # [ 7.528828] systemd[1]: Queued start job for default target Multi-User System.672machine # [ 7.531040] systemd[1]: systemd-journald.service: Deactivated successfully.673machine # [ 7.538232] systemd-modules-load[269]: Module 'atkbd' is built in674machine # [ 7.555187] systemd[1]: Mounting FUSE Control File System...675machine # [ 7.539984] systemd-modules-load[269]: Module 'loop' is built in676machine # [ 7.569325] EXT4-fs (vda): re-mounted 7bb2c026-4bd6-45d1-a5ab-3ab3cf8fad6b.677machine # [ 7.582607] systemd[1]: Starting Firewall...678machine # [ 7.593400] systemd[1]: Starting Apply Kernel Variables...679machine # [ 7.586276] systemd-oomd[270]: No swap; memory pressure usage will be degraded680machine # [ 7.623158] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...681machine # [ 7.632124] systemd[1]: Started Journal Service.682machine # [ 7.645287] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.683machine # [ 7.654061] systemd[1]: Finished Remount Root and Kernel File Systems.684machine # [ 7.693797] systemd[1]: Finished Apply Kernel Variables.685machine # [ 7.712149] systemd[1]: Mounted FUSE Control File System.686machine # [ 7.723528] systemd[1]: Listening on Disk Image Download Service Socket.687machine # [ 7.733493] systemd[1]: Starting Flush Journal to Persistent Storage...688machine # [ 7.737904] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore689machine # [ 7.751493] systemd[1]: Starting Load/Save OS Random Seed...690machine # [ 7.754227] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os691machine # [ 7.816476] systemd-journald[268]: Received client request to flush runtime journal.692machine # [ 7.870292] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.693machine # [ 7.872367] systemd[1]: Starting Create Static Device Nodes in /dev...694machine # [ 7.875404] systemd[1]: Finished Load/Save OS Random Seed.695machine # [ 7.879571] systemd[1]: Reached target First Boot Complete.696machine # [ 7.884943] systemd[1]: Finished Flush Journal to Persistent Storage.697machine # [ 7.958442] systemd[1]: Finished Create Static Device Nodes in /dev.698machine # [ 7.962111] systemd[1]: Reached target Preparation for Local File Systems.699machine # [ 7.965364] systemd[1]: Starting Rule-based Manager for Device Events and Files...700machine # [ 8.082193] systemd[1]: Mounting /run/wrappers...701machine # [ 8.148911] systemd[1]: Mounted /run/wrappers.702machine # [ 8.150374] systemd[1]: Reached target Local File Systems.703machine # [ 8.154300] systemd[1]: Listening on Boot Loader Control Service Socket.704machine # [ 8.160747] systemd[1]: Starting register-nix-paths.service...705machine # [ 8.184486] systemd[1]: Starting Create SUID/SGID Wrappers...706machine # [ 8.185480] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.707machine # [ 8.201948] systemd[1]: Starting Save Transient machine-id to Disk...708machine # [ 8.221109] systemd[1]: Starting Create System Files and Directories...709machine # [ 8.232552] systemd-udevd[313]: Using default interface naming scheme 'v261'.710machine # [ 8.376575] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.711machine # [ 8.386164] systemd[1]: Finished Save Transient machine-id to Disk.712machine # [ 8.465508] systemd[1]: Finished Create System Files and Directories.713machine # [ 8.483417] systemd[1]: Starting Rebuild Journal Catalog...714machine # [ 8.493113] systemd[1]: Starting Record System Boot/Shutdown in UTMP...715machine # [ 8.542942] systemd[1]: Started Rule-based Manager for Device Events and Files.716machine # [ 8.656715] systemd[1]: Finished Record System Boot/Shutdown in UTMP.717machine # [ 8.737190] systemd[1]: Finished Rebuild Journal Catalog.718machine # [ 8.752740] systemd[1]: Starting Update is Completed...719machine # [ 8.860173] systemd[1]: Finished Update is Completed.720machine # [ 9.225628] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.721machine # [ 9.249722] systemd[1]: Finished Create SUID/SGID Wrappers.722machine # [ 9.468208] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3723machine # [ 9.957409] systemd[1]: Finished register-nix-paths.service.724machine # [ 10.020152] systemd[1]: Finished Coldplug All udev Devices.725machine # [ 10.021177] systemd[1]: Reached target System Initialization.726machine # [ 10.024213] systemd[1]: Started Discard unused filesystem blocks once a week.727machine # [ 10.025784] systemd[1]: Started Daily Cleanup of Temporary Directories.728machine # [ 10.037630] systemd[1]: Reached target Timer Units.729machine # [ 10.038598] systemd[1]: Listening on D-Bus System Message Bus Socket.730machine # [ 10.039682] systemd[1]: Listening on Nix Daemon Socket.731machine # [ 10.041480] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.732machine # [ 10.048679] systemd[1]: Reached target Socket Units.733machine # [ 10.056803] systemd[1]: Reached target Basic System.734machine # [ 10.059395] systemd[1]: Starting Import lastlog data into lastlog2 database...735machine # [ 10.060720] systemd[1]: Starting Name Service Cache Daemon (nsncd)...736machine # [ 10.068715] systemd[1]: Starting Post-Boot Actions...737machine # [ 10.080342] systemd[1]: Started Reset console on configuration changes.738machine # [ 10.112116] systemd[1]: Starting resolvconf update...739machine # [ 10.117060] systemd[1]: Finished Firewall.740machine # [ 10.180439] systemd[1]: Starting D-Bus System Message Bus...741machine # [ 10.215533] nsncd[476]: Sep 06 17:55:38.858 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"742machine # [ 10.221831] systemd[1]: Started Name Service Cache Daemon (nsncd).743machine # [ 10.232307] systemd[1]: Finished Post-Boot Actions.744machine # [ 10.248532] systemd[1]: Reached target Host and Network Name Lookups.745machine # [ 10.256367] systemd[1]: Reached target User and Group Name Lookups.746machine # [ 10.275768] systemd[1]: Starting User Login Management...747machine # [ 10.320339] systemd[1]: Finished Import lastlog data into lastlog2 database.748machine # [ 10.453870] dbus-broker-launch[484]: Looking up NSS user entry for 'systemd-timesync'...749machine # [ 10.463134] dbus-broker-launch[484]: NSS returned no entry for 'systemd-timesync'750machine # [ 10.465877] dbus-broker-launch[484]: Invalid user-name in /nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"751machine # [ 10.478625] systemd-logind[499]: New seat seat0.752machine # [ 10.485132] systemd[1]: Started User Login Management.753machine # [ 10.491424] systemd[1]: Starting linger-users.service...754machine # [ 10.514366] systemd[1]: Started D-Bus System Message Bus.755machine # [ 10.522672] systemd[1]: Stopped target Host and Network Name Lookups.756machine # [ 10.528391] systemd[1]: Stopping Host and Network Name Lookups...757machine # [ 10.539520] systemd[1]: Stopped target User and Group Name Lookups.758machine # [ 10.543893] systemd[1]: Stopping User and Group Name Lookups...759machine # [ 10.546114] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...760machine # [ 10.556442] systemd[1]: nscd.service: Deactivated successfully.761machine # [ 10.557332] systemd[1]: Stopped Name Service Cache Daemon (nsncd).762machine # [ 10.567676] dbus-broker-launch[484]: Ready763machine # [ 10.578836] systemd[1]: Starting Name Service Cache Daemon (nsncd)...764machine # [ 10.698658] systemd[1]: Created slice Slice /user/1001.765machine # [ 10.717712] systemd[1]: Starting User Runtime Directory /run/user/1001...766machine # [ 10.743752] nsncd[548]: Sep 06 17:55:39.389 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"767machine # [ 10.788975] systemd[1]: linger-users.service: Deactivated successfully.768machine # [ 10.800477] systemd[1]: Finished linger-users.service.769machine # [ 10.803979] systemd[1]: Started Name Service Cache Daemon (nsncd).770machine # [ 10.828833] systemd[1]: Reached target Host and Network Name Lookups.771machine # [ 10.851086] systemd[1]: Reached target User and Group Name Lookups.772machine # [ 10.866266] systemd[1]: Finished User Runtime Directory /run/user/1001.773machine # [ 10.884612] systemd[1]: Starting User Manager for UID 1001...774machine # [ 10.930999] systemd[1]: Finished resolvconf update.775machine # [ 10.949282] systemd[1]: Reached target Preparation for Network.776machine # [ 10.967011] systemd[1]: Starting DHCP Client...777machine # [ 10.986818] systemd[1]: Starting Extra networking commands....778machine # [ 11.010179] systemd[1]: Starting Virtual Console Setup...779machine # [ 11.037940] (systemd)[576]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)780machine # [ 11.068528] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs781machine # [ 11.118091] systemd-logind[499]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.782machine # [ 11.163898] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy783machine # [ 11.303311] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.784machine # [ 11.316851] systemd[1]: Stopped Virtual Console Setup.785machine # [ 11.325970] systemd[1]: Starting Virtual Console Setup...786machine # [ 11.390509] systemd[576]: Queued start job for default target Main User Target.787machine # [ 11.401036] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy788machine # [ 11.420504] systemd[576]: Created slice User Application Slice.789machine # [ 11.425054] systemd[576]: Started Daily Cleanup of User's Temporary Directories.790machine # [ 11.433570] systemd[576]: Reached target Paths.791machine # [ 11.439227] systemd[576]: Reached target Timers.792machine # [ 11.444820] systemd[576]: Starting D-Bus User Message Bus Socket...793machine # [ 11.460200] systemd[576]: Starting Create User Files and Directories...794machine # [ 11.488818] systemd[576]: Finished Create User Files and Directories.795machine # [ 11.568304] systemd[576]: Listening on D-Bus User Message Bus Socket.796machine # [ 11.569375] systemd[576]: Reached target Sockets.797machine # [ 11.570088] systemd[576]: Reached target Basic System.798machine # [ 11.570872] systemd[1]: Started User Manager for UID 1001.799machine # [ 11.571678] systemd[576]: Starting Run user-specific NixOS activation...800machine # [ 11.635603] systemd[576]: Finished Run user-specific NixOS activation.801machine # [ 11.650467] systemd[576]: Reached target Main User Target.802machine # [ 11.665766] systemd[576]: Startup finished in 523ms.803machine # [ 11.738625] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.804machine # [ 11.772464] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.805machine # [ 11.792720] systemd[1]: Started backdoor.service.806machine # [ 11.892471] systemd-logind[499]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)807machine # [ 11.917554] (udev-worker)[638]: Network interface NamePolicy= disabled on kernel command line.808machine # [ 11.946186] (udev-worker)[626]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.809machine # [ 12.009731] dhcpcd[596]: dhcpcd-10.3.2 starting810machine # [ 12.034658] (udev-worker)[626]: Network interface NamePolicy= disabled on kernel command line.811machine # [ 12.066155] dhcpcd[663]: dev: loaded udev812machine # [ 12.098047] 8021q: 802.1Q VLAN Support v1.8813machine # [ 12.126888] systemd[1]: Condition check resulted in Virtio network device being skipped.814machine # [ 12.182845] systemd[1]: Starting Address configuration of eth1...[ 12.196172] cfg80211: Loading compiled-in X.509 certificates for regulatory database815machine # 816machine # [ 12.234684] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'817machine # [ 12.235173] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'818machine # [ 12.240163] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2819machine # [ 12.240488] cfg80211: failed to load regulatory.db820machine # [ 12.220226] systemd[1]: Finished Extra networking commands..821machine # [ 12.249222] systemd[1]: Reached target Network.822machine # [ 12.264576] systemd[1]: Starting Permit User Sessions...823machine # [ 12.279382] systemd-logind[499]: Watching system buttons on /dev/input/event0 (gpio-keys)824machine # connecting to host...825machine # [ 12.421372] mousedev: PS/2 mouse device common for all mice826machine: Guest shell says: b'Spawning backdoor root shell...\n'827machine # [ 12.435528] systemd[1]: Finished Permit User Sessions.828machine: connected to guest root shell829machine # [ 12.444489] systemd[1]: Started Getty on tty1.830machine: (connecting took 12.74 seconds)831machine # [ 12.445179] systemd[1]: Reached target Login Prompts.832machine: (finished: waiting for the VM to finish booting, in 13.24 seconds)833machine # [ 12.547640] 8021q: adding VLAN 0 to HW filter on device eth0834machine # [ 12.550186] dhcpcd[663]: eth0: waiting for carrier835machine # [ 12.575311] 8021q: adding VLAN 0 to HW filter on device eth1836machine # [ 12.583826] dhcpcd[663]: eth0: waiting for carrier837machine # [ 12.594297] dhcpcd[663]: eth0: carrier acquired838machine # [ 12.660591] systemd-logind[499]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)839machine # [ 12.679556] network-addresses-eth1-start[673]: adding address 192.168.1.1/24... done840machine # [ 12.703471] dhcpcd[663]: DUID 00:01:00:01:32:30:65:9d:52:54:00:12:34:56841machine # [ 12.728783] dhcpcd[663]: eth0: IAID 00:12:34:56842machine # [ 12.739851] dhcpcd[663]: eth0: adding address fe80::5054:ff:fe12:3456843machine # [ 12.760947] network-addresses-eth1-start[673]: adding address 2001:db8:1::1/64... done844machine # [ 12.864325] systemd[1]: Finished Address configuration of eth1.845machine # [ 13.076407] systemd-vconsole-setup[591]: Configuration of first virtual console was skipped, ignoring remaining ones.846machine # [ 13.101765] systemd[1]: Finished Virtual Console Setup.847machine # [ 13.465495] dhcpcd[663]: eth0: soliciting a DHCP lease848machine # [ 13.481250] dhcpcd[663]: eth0: offered 10.0.2.15 from 10.0.2.2849machine # [ 13.491107] dhcpcd[663]: eth0: probing address 10.0.2.15/24850machine # [ 13.709087] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.851machine # [ 13.715761] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.852machine # [ 13.724388] systemd[1]: Stopped Virtual Console Setup.853machine # [ 13.728525] systemd[1]: Stopping Virtual Console Setup...854machine # [ 13.729375] systemd[1]: Starting Virtual Console Setup...855machine # [ 13.752527] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.856machine # [ 13.753653] systemd[1]: Stopped Virtual Console Setup.857machine # [ 13.757065] systemd[1]: Starting Virtual Console Setup...858machine # [ 14.109339] systemd-vconsole-setup[719]: Configuration of first virtual console was skipped, ignoring remaining ones.859machine # [ 14.114260] systemd[1]: Finished Virtual Console Setup.860machine # [ 14.969248] dhcpcd[663]: eth0: soliciting an IPv6 router861machine # [ 14.971754] dhcpcd[663]: eth0: Router Advertisement from fe80::2862machine # [ 14.974513] dhcpcd[663]: eth0: adding address fec0::5054:ff:fe12:3456/64863machine # [ 14.977460] dhcpcd[663]: eth0: adding route to fec0::/64864machine # [ 14.979776] dhcpcd[663]: eth0: adding default route via fe80::2865machine # [ 18.021623] dhcpcd[663]: eth0: leased 10.0.2.15 for 86400 seconds866machine # [ 18.023551] dhcpcd[663]: eth0: adding route to 10.0.2.0/24867machine # [ 18.025292] dhcpcd[663]: eth0: adding default route via 10.0.2.2868machine # [ 18.155860] systemd[1]: Started DHCP Client.869machine # [ 18.158096] systemd[1]: Reached target Multi-User System.870machine # [ 18.158922] systemd[1]: Startup finished in 919ms (kernel) + 4.340s (initrd) + 12.896s (userspace) = 18.157s.871machine: (finished: waiting for unit multi-user.target, in 19.36 seconds)872machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/bin/switch-to-configuration test873machine # Checking switch inhibitors... done874machine # [ 19.204381] nixos[826]: switching to system configuration /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test875machine # [ 19.207023] systemd[1]: Stopped target Remote File Systems.876machine # activating the configuration...877machine # [ 19.209439] systemd[1]: Stopped target Local File Systems.878machine # setting up /etc...879machine # [ 19.664592] systemd[1]: Reload requested from client PID 826 ('.switch-to-conf') (unit backdoor.service)...880machine # [ 19.666511] systemd[1]: Reloading...881machine # [ 20.247094] systemd[1]: Reloading finished in 578 ms.882machine # reloading user units for usertest...883machine # [ 20.293481] systemd[576]: Created slice User Core Session Slice.884machine # [ 20.296767] systemd[576]: Starting D-Bus User Message Bus...885machine # [ 20.355401] dbus-broker-launch[897]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.import1.service'886machine # [ 20.358589] dbus-broker-launch[897]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.machine1.service'887machine # [ 20.361695] dbus-broker-launch[897]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.portable1.service'888machine # [ 20.365041] dbus-broker-launch[897]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'889machine # [ 20.368536] dbus-broker-launch[897]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored890machine # [ 20.371037] dbus-broker-launch[897]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored891machine # [ 20.373346] systemd[576]: Started D-Bus User Message Bus.892machine # [ 20.380218] dbus-broker-launch[897]: Ready893machine # [ 20.481526] systemd[576]: Reexecution requested from client PID 896 ('switch-to-confi')...894machine # [ 20.484817] systemd[576]: Reexecuting.895machine # [ 20.533351] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy896machine # [ 20.716063] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy897machine # [ 20.722120] systemd[576]: Reload requested from client PID 896 ('switch-to-confi')...898machine # [ 20.724319] systemd[576]: Reloading...899machine # [ 20.909795] systemd[576]: Reloading finished in 184 ms.900machine # [ 20.924140] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy901machine # restarting the following user units: nixos-activation.service902machine # [ 20.933964] systemd[576]: Stopped Run user-specific NixOS activation.903machine # [ 20.936167] systemd[576]: Stopping Run user-specific NixOS activation...904machine # [ 20.938920] systemd[576]: Starting Run user-specific NixOS activation...905machine # [ 20.984164] systemd[576]: Finished Run user-specific NixOS activation.906machine # restarting sysinit-reactivation.target907machine # [ 21.000358] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....908machine # [ 21.100111] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..909machine # [ 21.103149] systemd[1]: Reached target Reactivate sysinit units.910machine # [ 21.106313] systemd[1]: Reached target Local File Systems.911machine # [ 21.109596] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore912machine # [ 21.114061] systemd[1]: Starting Create SUID/SGID Wrappers...913machine # [ 21.114948] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.914machine # [ 21.118861] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67915machine # [ 21.125490] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore916machine # [ 21.127233] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os917machine # [ 21.133134] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os918machine # [ 21.134371] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os919machine # [ 21.135640] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki920machine # [ 21.139107] systemd[1]: Reached target Remote File Systems.921machine # [ 21.445020] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.922machine # [ 21.448458] systemd[1]: Finished Create SUID/SGID Wrappers.923machine # [ 21.452804] systemd[1]: Starting linger-users.service...924machine # [ 21.511644] systemd[1]: linger-users.service: Deactivated successfully.925machine # [ 21.514242] systemd[1]: Finished linger-users.service.926machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service927machine # [ 21.778241] nixos[826]: finished switching to system configuration /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test928machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/bin/switch-to-configuration test, in 3.22 seconds)929machine: must succeed: rm /etc/NIXOS930machine: (finished: must succeed: rm /etc/NIXOS, in 0.05 seconds)931machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/fkrnrx1kb4pvplbfnsgm5jx77m1hvwvw-nixos-system-other-test/bin/switch-to-configuration test932machine # this should succeed (config: /nix/store/fkrnrx1kb4pvplbfnsgm5jx77m1hvwvw-nixos-system-other-test, action: test)933machine # Checking switch inhibitors... done934machine # [ 22.487176] nixos[974]: switching to system configuration /nix/store/fkrnrx1kb4pvplbfnsgm5jx77m1hvwvw-nixos-system-other-test935machine # [ 22.489957] systemd[1]: Stopped target Remote File Systems.936machine # activating the configuration...937machine # [ 22.492682] systemd[1]: Stopped target Local File Systems.938machine # removing user ‘usertest’939machine # setting up /etc...940machine # [ 22.983787] systemd[1]: Reload requested from client PID 974 ('.switch-to-conf') (unit backdoor.service)...941machine # [ 22.985970] systemd[1]: Reloading...942machine # [ 23.622442] systemd[1]: Reloading finished in 634 ms.943machine # reloading user units for usertest...944machine # [ 23.777380] systemd[576]: Reexecution requested from client PID 1046 ('switch-to-confi')...945machine # [ 23.781405] systemd[576]: Reexecuting.946machine # [ 23.842267] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy947machine # [ 24.039555] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy948machine # [ 24.046876] systemd[576]: Reload requested from client PID 1046 ('switch-to-confi')...949machine # [ 24.049386] systemd[576]: Reloading...950machine # [ 24.232679] systemd[576]: Reloading finished in 182 ms.951machine # [ 24.248179] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy952machine # restarting the following user units: nixos-activation.service953machine # [ 24.258520] systemd[576]: Stopped Run user-specific NixOS activation.954machine # [ 24.260848] systemd[576]: Stopping Run user-specific NixOS activation...955machine # [ 24.263761] systemd[576]: Starting Run user-specific NixOS activation...956machine # [ 24.309570] systemd[576]: Finished Run user-specific NixOS activation.957machine # restarting sysinit-reactivation.target958machine # [ 24.314691] systemd[1]: Stopped target Reactivate sysinit units.959machine # [ 24.315591] systemd[1]: Stopping Reactivate sysinit units...960machine # reloading the following units: dbus-broker.service961machine # [ 24.317791] systemd[1]: Reached target Reactivate sysinit units.962machine # [ 24.319961] systemd[1]: Reloading D-Bus System Message Bus...963machine # [ 24.322410] dbus-broker-launch[484]: Caught SIGHUP, trigger reload.964machine # [ 24.343659] dbus-broker-launch[484]: Looking up NSS user entry for 'systemd-timesync'...965machine # [ 24.345864] dbus-broker-launch[484]: NSS returned no entry for 'systemd-timesync'966machine # [ 24.347010] dbus-broker-launch[484]: Invalid user-name in /nix/store/2d37a0ag8593x6bsc14irprkj2vpg80c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"967machine # [ 24.378201] systemd[1]: Reloaded D-Bus System Message Bus.968machine # restarting the following units: network-addresses-eth1.service969machine # [ 24.385390] systemd[1]: Stopping Address configuration of eth1...970machine # [ 24.437748] network-addresses-eth1-pre-stop[1058]: deleting address 192.168.1.1/24... done971machine # [ 24.449025] network-addresses-eth1-pre-stop[1058]: deleting address 2001:db8:1::1/64... done972machine # [ 24.458711] systemd[1]: network-addresses-eth1.service: Deactivated successfully.973machine # [ 24.462399] systemd[1]: Stopped Address configuration of eth1.974machine # [ 24.464937] systemd[1]: Starting Address configuration of eth1...975machine # [ 24.534947] network-addresses-eth1-start[1063]: adding address 192.168.1.2/24... done976machine # [ 24.548088] network-addresses-eth1-start[1063]: adding address 2001:db8:1::2/64... done977machine # [ 24.563007] systemd[1]: Finished Address configuration of eth1.978machine # [ 24.568218] systemd[1]: Reached target Local File Systems.979machine # [ 24.572272] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os980machine # [ 24.573858] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki981machine # [ 24.577294] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore982machine # [ 24.578808] systemd[1]: Starting Create SUID/SGID Wrappers...983machine # [ 24.579581] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.984machine # [ 24.583751] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67985machine # [ 24.595020] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore986machine # [ 24.599784] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os987machine # [ 24.601275] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os988machine # [ 24.602455] systemd[1]: Reached target Remote File Systems.989machine # [ 24.974112] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.990machine # [ 24.978679] systemd[1]: Finished Create SUID/SGID Wrappers.991machine # [ 24.981353] systemd[1]: Starting linger-users.service...992machine # [ 25.026266] linger-users-start[1135]: id: ‘usertest’: no such user993machine # [ 25.028710] linger-users-start[1133]: Removing linger for missing user usertest994machine # [ 25.037718] systemd[1]: linger-users.service: Deactivated successfully.995machine # [ 25.039950] systemd[1]: Finished linger-users.service.996machine # [ 25.298408] nixos[974]: finished switching to system configuration /nix/store/fkrnrx1kb4pvplbfnsgm5jx77m1hvwvw-nixos-system-other-test997machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/fkrnrx1kb4pvplbfnsgm5jx77m1hvwvw-nixos-system-other-test/bin/switch-to-configuration test, in 3.47 seconds)998subtest: services999machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/bin/switch-to-configuration test 2>&11000machine # [ 25.907198] nixos[1141]: switching to system configuration /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test1001machine # [ 25.909699] systemd[1]: Stopped target Local File Systems.1002machine # [ 25.911907] systemd[1]: Stopped target Remote File Systems.1003machine # [ 26.359210] systemd[1]: Reload requested from client PID 1141 ('.switch-to-conf') (unit backdoor.service)...1004machine # [ 26.361507] systemd[1]: Reloading...1005machine # [ 26.959818] systemd[1]: Reloading finished in 596 ms.1006machine # [ 27.105986] systemd[576]: Reexecution requested from client PID 1211 ('switch-to-confi')...1007machine # [ 27.107372] systemd[576]: Reexecuting.1008machine # [ 27.169793] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1009machine # [ 27.361713] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1010machine # [ 27.369124] systemd[576]: Reload requested from client PID 1211 ('switch-to-confi')...1011machine # [ 27.371516] systemd[576]: Reloading...1012machine # [ 27.565568] systemd[576]: Reloading finished in 192 ms.1013machine # [ 27.576194] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1014machine # [ 27.585767] systemd[576]: Stopped Run user-specific NixOS activation.1015machine # [ 27.588497] systemd[576]: Stopping Run user-specific NixOS activation...1016machine # [ 27.592114] systemd[576]: Starting Run user-specific NixOS activation...1017machine # [ 27.640230] systemd[576]: Finished Run user-specific NixOS activation.1018machine # [ 27.645077] systemd[1]: Stopped target Reactivate sysinit units.1019machine # [ 27.649476] systemd[1]: Stopping Reactivate sysinit units...1020machine # [ 27.650534] systemd[1]: Reached target Reactivate sysinit units.1021machine # [ 27.651717] systemd[1]: Reloading D-Bus System Message Bus...1022machine # [ 27.653495] dbus-broker-launch[484]: Caught SIGHUP, trigger reload.1023machine # [ 27.666362] dbus-broker-launch[484]: Looking up NSS user entry for 'systemd-timesync'...1024machine # [ 27.668687] dbus-broker-launch[484]: NSS returned no entry for 'systemd-timesync'1025machine # [ 27.669926] dbus-broker-launch[484]: Invalid user-name in /nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1026machine # [ 27.689831] systemd[1]: Reloaded D-Bus System Message Bus.1027machine # [ 27.696684] systemd[1]: Stopping Address configuration of eth1...1028machine # [ 27.745243] network-addresses-eth1-pre-stop[1223]: deleting address 192.168.1.2/24... done1029machine # [ 27.756841] network-addresses-eth1-pre-stop[1223]: deleting address 2001:db8:1::2/64... done1030machine # [ 27.767084] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1031machine # [ 27.769354] systemd[1]: Stopped Address configuration of eth1.1032machine # [ 27.774290] systemd[1]: Starting Address configuration of eth1...1033machine # [ 27.846064] network-addresses-eth1-start[1228]: adding address 192.168.1.1/24... done1034machine # [ 27.857566] network-addresses-eth1-start[1228]: adding address 2001:db8:1::1/64... done1035machine # [ 27.872597] systemd[1]: Finished Address configuration of eth1.1036machine # [ 27.879404] systemd[1]: Reached target Local File Systems.1037machine # [ 27.882871] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1038machine # [ 27.887016] systemd[1]: Starting Create SUID/SGID Wrappers...1039machine # [ 27.887867] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1040machine # [ 27.892549] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671041machine # [ 27.897221] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1042machine # [ 27.902319] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1043machine # [ 27.908245] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1044machine # [ 27.909488] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1045machine # [ 27.910742] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1046machine # [ 27.911967] systemd[1]: Reached target Remote File Systems.1047machine # [ 28.215840] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1048machine # [ 28.218313] systemd[1]: Finished Create SUID/SGID Wrappers.1049machine # [ 28.223021] systemd[1]: Starting linger-users.service...1050machine # [ 28.270979] systemd[1]: linger-users.service: Deactivated successfully.1051machine # [ 28.273847] systemd[1]: Finished linger-users.service.1052machine # [ 28.530795] nixos[1141]: finished switching to system configuration /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test1053machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.23 seconds)1054machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/bin/switch-to-configuration test 2>&11055machine # [ 29.149153] nixos[1297]: switching to system configuration /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test1056machine # [ 29.151826] systemd[1]: Stopped target Local File Systems.1057machine # [ 29.154178] systemd[1]: Stopped target Remote File Systems.1058machine # [ 29.598722] systemd[1]: Reload requested from client PID 1297 ('.switch-to-conf') (unit backdoor.service)...1059machine # [ 29.600973] systemd[1]: Reloading...1060machine # [ 30.335967] systemd[1]: Reloading finished in 732 ms.1061machine # [ 30.514440] systemd[576]: Reexecution requested from client PID 1367 ('switch-to-confi')...1062machine # [ 30.518629] systemd[576]: Reexecuting.1063machine # [ 30.574347] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1064machine # [ 30.807177] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1065machine # [ 30.816197] systemd[576]: Reload requested from client PID 1367 ('switch-to-confi')...1066machine # [ 30.818927] systemd[576]: Reloading...1067machine # [ 31.051097] systemd[576]: Reloading finished in 230 ms.1068machine # [ 31.068304] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1069machine # [ 31.081899] systemd[576]: Stopped Run user-specific NixOS activation.1070machine # [ 31.085128] systemd[576]: Stopping Run user-specific NixOS activation...1071machine # [ 31.089254] systemd[576]: Starting Run user-specific NixOS activation...1072machine # [ 31.148939] systemd[576]: Finished Run user-specific NixOS activation.1073machine # [ 31.153742] systemd[1]: Stopped target Reactivate sysinit units.1074machine # [ 31.156904] systemd[1]: Stopping Reactivate sysinit units...1075machine # [ 31.158873] systemd[1]: Reached target Reactivate sysinit units.1076machine # [ 31.163560] systemd[1]: Reached target Remote File Systems.1077machine # [ 31.167662] systemd[1]: Starting linger-users.service...1078machine # [ 31.171933] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1079machine # [ 31.178223] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1080machine # [ 31.179602] systemd[1]: Reached target Local File Systems.1081machine # [ 31.193342] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1082machine # [ 31.203652] systemd[1]: Starting Create SUID/SGID Wrappers...1083machine # [ 31.207239] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1084machine # [ 31.210785] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671085machine # [ 31.213460] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1086machine # [ 31.220243] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1087machine # [ 31.222088] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1088machine # [ 31.302566] systemd[1]: linger-users.service: Deactivated successfully.1089machine # [ 31.306482] systemd[1]: Finished linger-users.service.1090machine # [ 31.649964] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1091machine # [ 31.654652] systemd[1]: Finished Create SUID/SGID Wrappers.1092machine # [ 31.915477] nixos[1297]: finished switching to system configuration /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test1093machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.39 seconds)1094machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&11095machine # [ 32.583103] nixos[1438]: switching to system configuration /nix/store/0g51ffg5s2lfjpchq6xrh5x2vsha1kyf-nixos-system-machine-test1096machine # [ 32.586524] systemd[1]: Stopped target Local File Systems.1097machine # [ 32.587980] systemd[1]: Stopped target Remote File Systems.1098machine # [ 33.107143] systemd[1]: Reload requested from client PID 1438 ('.switch-to-conf') (unit backdoor.service)...1099machine # [ 33.109300] systemd[1]: Reloading...1100machine # [ 33.765526] systemd[1]: Reloading finished in 654 ms.1101machine # [ 33.914011] systemd[576]: Reexecution requested from client PID 1508 ('switch-to-confi')...1102machine # [ 33.917763] systemd[576]: Reexecuting.1103machine # [ 33.970019] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1104machine # [ 34.167109] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1105machine # [ 34.174131] systemd[576]: Reload requested from client PID 1508 ('switch-to-confi')...1106machine # [ 34.176180] systemd[576]: Reloading...1107machine # [ 34.366712] systemd[576]: Reloading finished in 189 ms.1108machine # [ 34.380166] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1109machine # [ 34.389149] systemd[576]: Stopped Run user-specific NixOS activation.1110machine # [ 34.391492] systemd[576]: Stopping Run user-specific NixOS activation...1111machine # [ 34.394324] systemd[576]: Starting Run user-specific NixOS activation...1112machine # [ 34.441628] systemd[576]: Finished Run user-specific NixOS activation.1113machine # [ 34.446152] systemd[1]: Stopped target Reactivate sysinit units.1114machine # [ 34.448181] systemd[1]: Stopping Reactivate sysinit units...1115machine # [ 34.449102] systemd[1]: Reached target Reactivate sysinit units.1116machine # [ 34.452386] systemd[1]: Reached target Local File Systems.1117machine # [ 34.455250] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1118machine # [ 34.457666] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1119machine # [ 34.462200] systemd[1]: Reached target Remote File Systems.1120machine # [ 34.465608] systemd[1]: Starting linger-users.service...1121machine # [ 34.468176] systemd[1]: Starting test.service...1122machine # [ 34.474125] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1123machine # [ 34.486079] systemd[1]: Starting Create SUID/SGID Wrappers...1124machine # [ 34.486984] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1125machine # [ 34.489378] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671126machine # [ 34.496928] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1127machine # [ 34.501229] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1128machine # [ 34.506114] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1129machine # [ 34.539381] systemd[1]: Finished test.service.1130machine # [ 34.571927] systemd[1]: linger-users.service: Deactivated successfully.1131machine # [ 34.575244] systemd[1]: Finished linger-users.service.1132machine # [ 34.842931] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1133machine # [ 34.846334] systemd[1]: Finished Create SUID/SGID Wrappers.1134machine # [ 35.104853] nixos[1438]: finished switching to system configuration /nix/store/0g51ffg5s2lfjpchq6xrh5x2vsha1kyf-nixos-system-machine-test1135machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 3.19 seconds)1136machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&11137machine # [ 35.740496] nixos[1581]: switching to system configuration /nix/store/0g51ffg5s2lfjpchq6xrh5x2vsha1kyf-nixos-system-machine-test1138machine # [ 35.744100] systemd[1]: Stopped target Local File Systems.1139machine # [ 35.745455] systemd[1]: Stopped target Remote File Systems.1140machine # [ 36.194368] systemd[1]: Reload requested from client PID 1581 ('.switch-to-conf') (unit backdoor.service)...1141machine # [ 36.196336] systemd[1]: Reloading...1142machine # [ 36.809555] systemd[1]: Reloading finished in 610 ms.1143machine # [ 36.954354] systemd[576]: Reexecution requested from client PID 1651 ('switch-to-confi')...1144machine # [ 36.958474] systemd[576]: Reexecuting.1145machine # [ 37.006182] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1146machine # [ 37.200779] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1147machine # [ 37.208734] systemd[576]: Reload requested from client PID 1651 ('switch-to-confi')...1148machine # [ 37.211721] systemd[576]: Reloading...1149machine # [ 37.411233] systemd[576]: Reloading finished in 199 ms.1150machine # [ 37.424191] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1151machine # [ 37.434726] systemd[576]: Stopped Run user-specific NixOS activation.1152machine # [ 37.435817] systemd[576]: Stopping Run user-specific NixOS activation...1153machine # [ 37.439046] systemd[576]: Starting Run user-specific NixOS activation...1154machine # [ 37.488828] systemd[576]: Finished Run user-specific NixOS activation.1155machine # [ 37.493743] systemd[1]: Stopped target Reactivate sysinit units.1156machine # [ 37.497526] systemd[1]: Stopping Reactivate sysinit units...1157machine # [ 37.498500] systemd[1]: Reached target Reactivate sysinit units.1158machine # [ 37.499349] systemd[1]: Reached target Local File Systems.1159machine # [ 37.504285] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1160machine # [ 37.508759] systemd[1]: Starting Create SUID/SGID Wrappers...1161machine # [ 37.509687] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1162machine # [ 37.510925] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671163machine # [ 37.514609] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1164machine # [ 37.528829] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1165machine # [ 37.530187] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1166machine # [ 37.531368] systemd[1]: Reached target Remote File Systems.1167machine # [ 37.535848] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1168machine # [ 37.537610] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1169machine # [ 37.868341] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1170machine # [ 37.872609] systemd[1]: Finished Create SUID/SGID Wrappers.1171machine # [ 37.875271] systemd[1]: Starting linger-users.service...1172machine # [ 37.939033] systemd[1]: linger-users.service: Deactivated successfully.1173machine # [ 37.941719] systemd[1]: Finished linger-users.service.1174machine # [ 38.197562] nixos[1581]: finished switching to system configuration /nix/store/0g51ffg5s2lfjpchq6xrh5x2vsha1kyf-nixos-system-machine-test1175machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 3.09 seconds)1176machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&11177machine # [ 38.814535] nixos[1722]: switching to system configuration /nix/store/h0b7sd9qr8a6vgl23jkzahr8n304nhgz-nixos-system-machine-test1178machine # [ 38.818488] systemd[1]: Stopped target Local File Systems.1179machine # [ 38.819776] systemd[1]: Stopped target Remote File Systems.1180machine # [ 39.373105] systemd[1]: Reload requested from client PID 1722 ('.switch-to-conf') (unit backdoor.service)...1181machine # [ 39.375158] systemd[1]: Reloading...1182machine # [ 40.033508] systemd[1]: Reloading finished in 656 ms.1183machine # [ 40.175396] systemd[576]: Reexecution requested from client PID 1792 ('switch-to-confi')...1184machine # [ 40.177519] systemd[576]: Reexecuting.1185machine # [ 40.247317] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1186machine # [ 40.438946] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1187machine # [ 40.445491] systemd[576]: Reload requested from client PID 1792 ('switch-to-confi')...1188machine # [ 40.447994] systemd[576]: Reloading...1189machine # [ 40.634942] systemd[576]: Reloading finished in 185 ms.1190machine # [ 40.652161] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1191machine # [ 40.662142] systemd[576]: Stopped Run user-specific NixOS activation.1192machine # [ 40.664452] systemd[576]: Stopping Run user-specific NixOS activation...1193machine # [ 40.667320] systemd[576]: Starting Run user-specific NixOS activation...1194machine # [ 40.713051] systemd[576]: Finished Run user-specific NixOS activation.1195machine # [ 40.717396] systemd[1]: Stopped target Reactivate sysinit units.1196machine # [ 40.720174] systemd[1]: Stopping Reactivate sysinit units...1197machine # [ 40.721136] systemd[1]: Reached target Reactivate sysinit units.1198machine # [ 40.721980] systemd[1]: Reached target Remote File Systems.1199machine # [ 40.724949] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1200machine # [ 40.727499] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1201machine # [ 40.733365] systemd[1]: Starting linger-users.service...1202machine # [ 40.736954] systemd[1]: Reached target Local File Systems.1203machine # [ 40.744143] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1204machine # [ 40.747748] systemd[1]: Starting Create SUID/SGID Wrappers...1205machine # [ 40.751380] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1206machine # [ 40.756532] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671207machine # [ 40.758711] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1208machine # [ 40.767630] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1209machine # [ 40.769316] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1210machine # [ 40.825699] systemd[1]: linger-users.service: Deactivated successfully.1211machine # [ 40.828528] systemd[1]: Finished linger-users.service.1212machine # [ 41.100906] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1213machine # [ 41.104467] systemd[1]: Finished Create SUID/SGID Wrappers.1214machine # [ 41.361152] nixos[1722]: finished switching to system configuration /nix/store/h0b7sd9qr8a6vgl23jkzahr8n304nhgz-nixos-system-machine-test1215machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&1, in 3.17 seconds)1216machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&11217machine # [ 41.978373] nixos[1863]: switching to system configuration /nix/store/nddm39yhin93faxx4z4z7r6yf2sbc2iz-nixos-system-machine-test1218machine # [ 41.981152] systemd[1]: Stopped target Local File Systems.1219machine # [ 41.984237] systemd[1]: Stopped target Remote File Systems.1220machine # [ 41.985268] systemd[1]: test.service: Deactivated successfully.1221machine # [ 41.986176] systemd[1]: Stopped Test unit.1222machine # [ 42.518831] systemd[1]: Reload requested from client PID 1863 ('.switch-to-conf') (unit backdoor.service)...1223machine # [ 42.520935] systemd[1]: Reloading...1224machine # [ 43.172454] systemd[1]: Reloading finished in 648 ms.1225machine # [ 43.318454] systemd[576]: Reexecution requested from client PID 1933 ('switch-to-confi')...1226machine # [ 43.319798] systemd[576]: Reexecuting.1227machine # [ 43.390350] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1228machine # [ 43.587886] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1229machine # [ 43.594194] systemd[576]: Reload requested from client PID 1933 ('switch-to-confi')...1230machine # [ 43.596394] systemd[576]: Reloading...1231machine # [ 43.778786] systemd[576]: Reloading finished in 180 ms.1232machine # [ 43.792143] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1233machine # [ 43.800796] systemd[576]: Stopped Run user-specific NixOS activation.1234machine # [ 43.803025] systemd[576]: Stopping Run user-specific NixOS activation...1235machine # [ 43.805697] systemd[576]: Starting Run user-specific NixOS activation...1236machine # [ 43.852810] systemd[576]: Finished Run user-specific NixOS activation.1237machine # [ 43.857003] systemd[1]: Stopped target Reactivate sysinit units.1238machine # [ 43.858314] systemd[1]: Stopping Reactivate sysinit units...1239machine # [ 43.859152] systemd[1]: Reached target Reactivate sysinit units.1240machine # [ 43.861995] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1241machine # [ 43.864648] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1242machine # [ 43.870371] systemd[1]: Starting test.service...1243machine # [ 43.880935] systemd[1]: Reached target Remote File Systems.1244machine # [ 43.881873] systemd[1]: Reached target Local File Systems.1245machine # [ 43.885085] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1246machine # [ 43.893183] systemd[1]: Starting Create SUID/SGID Wrappers...1247machine # [ 43.894085] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1248machine # [ 43.895343] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671249machine # [ 43.902111] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1250machine # [ 43.904054] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1251machine # [ 43.905832] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1252machine # [ 43.938059] systemd[1]: Finished test.service.1253machine # [ 44.220583] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1254machine # [ 44.224680] systemd[1]: Finished Create SUID/SGID Wrappers.1255machine # [ 44.227705] systemd[1]: Starting linger-users.service...1256machine # [ 44.284855] systemd[1]: linger-users.service: Deactivated successfully.1257machine # [ 44.286934] systemd[1]: Finished linger-users.service.1258machine # [ 44.543841] nixos[1863]: finished switching to system configuration /nix/store/nddm39yhin93faxx4z4z7r6yf2sbc2iz-nixos-system-machine-test1259machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 3.18 seconds)1260machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&11261machine # [ 45.150681] nixos[2006]: switching to system configuration /nix/store/9gkxac46y07mli01920h4isghs5ifx4y-nixos-system-machine-test1262machine # [ 45.153480] systemd[1]: Stopped target Local File Systems.1263machine # [ 45.155936] systemd[1]: Stopped target Remote File Systems.1264machine # [ 45.688545] systemd[1]: Reload requested from client PID 2006 ('.switch-to-conf') (unit backdoor.service)...1265machine # [ 45.690437] systemd[1]: Reloading...1266machine # [ 46.331547] systemd[1]: Reloading finished in 638 ms.1267machine # [ 46.479398] systemd[576]: Reexecution requested from client PID 2076 ('switch-to-confi')...1268machine # [ 46.481916] systemd[576]: Reexecuting.1269machine # [ 46.546227] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1270machine # [ 46.749929] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1271machine # [ 46.756230] systemd[576]: Reload requested from client PID 2076 ('switch-to-confi')...1272machine # [ 46.758597] systemd[576]: Reloading...1273machine # [ 46.952277] systemd[576]: Reloading finished in 192 ms.1274machine # [ 46.968156] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1275machine # [ 46.978580] systemd[576]: Stopped Run user-specific NixOS activation.1276machine # [ 46.980996] systemd[576]: Stopping Run user-specific NixOS activation...1277machine # [ 46.983998] systemd[576]: Starting Run user-specific NixOS activation...1278machine # [ 47.027364] systemd[576]: Finished Run user-specific NixOS activation.1279machine # [ 47.031684] systemd[1]: Stopped target Reactivate sysinit units.1280machine # [ 47.033766] systemd[1]: Stopping Reactivate sysinit units...1281machine # [ 47.035202] systemd[1]: Reached target Reactivate sysinit units.1282machine # [ 47.038706] systemd[1]: test.service: Deactivated successfully.1283machine # [ 47.039863] systemd[1]: Stopped test.service.1284machine # [ 47.041855] systemd[1]: Stopping test.service...1285machine # [ 47.042580] systemd[1]: Starting test.service...1286machine # [ 47.079684] systemd[1]: Finished test.service.1287machine # [ 47.085091] systemd[1]: Reached target Local File Systems.1288machine # [ 47.087732] systemd[1]: Reached target Remote File Systems.1289machine # [ 47.092787] systemd[1]: Starting linger-users.service...1290machine # [ 47.096334] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1291machine # [ 47.103365] systemd[1]: Starting Create SUID/SGID Wrappers...1292machine # [ 47.108673] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1293machine # [ 47.109968] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671294machine # [ 47.121131] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1295machine # [ 47.122885] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1296machine # [ 47.124283] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1297machine # [ 47.125439] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1298machine # [ 47.126663] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1299machine # [ 47.187812] systemd[1]: linger-users.service: Deactivated successfully.1300machine # [ 47.191165] systemd[1]: Finished linger-users.service.1301machine # [ 47.442886] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1302machine # [ 47.446074] systemd[1]: Finished Create SUID/SGID Wrappers.1303machine # [ 47.703197] nixos[2006]: finished switching to system configuration /nix/store/9gkxac46y07mli01920h4isghs5ifx4y-nixos-system-machine-test1304machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 3.16 seconds)1305machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&11306machine # [ 48.351866] nixos[2149]: switching to system configuration /nix/store/f405ibjvhjyddl1n8f83qbvlaa1ajz26-nixos-system-machine-test1307machine # [ 48.354589] systemd[1]: Stopped target Local File Systems.1308machine # [ 48.356909] systemd[1]: Stopped target Remote File Systems.1309machine # [ 48.853159] systemd[1]: Reload requested from client PID 2149 ('.switch-to-conf') (unit backdoor.service)...1310machine # [ 48.855116] systemd[1]: Reloading...1311machine # [ 49.462046] systemd[1]: Reloading finished in 604 ms.1312machine # [ 49.596352] systemd[576]: Reexecution requested from client PID 2218 ('switch-to-confi')...1313machine # [ 49.600575] systemd[576]: Reexecuting.1314machine # [ 49.658974] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1315machine # [ 49.858658] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1316machine # [ 49.865700] systemd[576]: Reload requested from client PID 2218 ('switch-to-confi')...1317machine # [ 49.868456] systemd[576]: Reloading...1318machine # [ 50.053062] systemd[576]: Reloading finished in 183 ms.1319machine # [ 50.068184] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1320machine # [ 50.078255] systemd[576]: Stopped Run user-specific NixOS activation.1321machine # [ 50.080874] systemd[576]: Stopping Run user-specific NixOS activation...1322machine # [ 50.084094] systemd[576]: Starting Run user-specific NixOS activation...1323machine # [ 50.132198] systemd[576]: Finished Run user-specific NixOS activation.1324machine # [ 50.136886] systemd[1]: Stopped target Reactivate sysinit units.1325machine # [ 50.140514] systemd[1]: Stopping Reactivate sysinit units...1326machine # [ 50.141417] systemd[1]: Reached target Reactivate sysinit units.1327machine # [ 50.145504] systemd[1]: Reloading test.service...1328machine # [ 50.185145] systemd[1]: Reloaded test.service.1329machine # [ 50.190921] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1330machine # [ 50.193763] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1331machine # [ 50.198098] systemd[1]: Reached target Remote File Systems.1332machine # [ 50.201211] systemd[1]: Starting linger-users.service...1333machine # [ 50.211384] systemd[1]: Reached target Local File Systems.1334machine # [ 50.212452] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1335machine # [ 50.218804] systemd[1]: Starting Create SUID/SGID Wrappers...1336machine # [ 50.223060] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1337machine # [ 50.229287] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671338machine # [ 50.231518] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1339machine # [ 50.234936] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1340machine # [ 50.238043] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1341machine # [ 50.301826] systemd[1]: linger-users.service: Deactivated successfully.1342machine # [ 50.304888] systemd[1]: Finished linger-users.service.1343machine # [ 50.560739] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1344machine # [ 50.564348] systemd[1]: Finished Create SUID/SGID Wrappers.1345machine # [ 50.823356] nixos[2149]: finished switching to system configuration /nix/store/f405ibjvhjyddl1n8f83qbvlaa1ajz26-nixos-system-machine-test1346machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&1, in 3.12 seconds)1347machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&11348machine # [ 51.462183] nixos[2291]: switching to system configuration /nix/store/c3q2ln63wnh9sc1xkp6590g4lgrv1mnn-nixos-system-machine-test1349machine # [ 51.465386] systemd[1]: Stopped target Local File Systems.1350machine # [ 51.467323] systemd[1]: Stopped target Remote File Systems.1351machine # [ 51.968853] systemd[1]: Reload requested from client PID 2291 ('.switch-to-conf') (unit backdoor.service)...1352machine # [ 51.970770] systemd[1]: Reloading...1353machine # [ 52.605561] systemd[1]: Reloading finished in 632 ms.1354machine # [ 52.758012] systemd[576]: Reexecution requested from client PID 2360 ('switch-to-confi')...1355machine # [ 52.762419] systemd[576]: Reexecuting.1356machine # [ 52.817827] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1357machine # [ 53.010936] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1358machine # [ 53.018425] systemd[576]: Reload requested from client PID 2360 ('switch-to-confi')...1359machine # [ 53.020994] systemd[576]: Reloading...1360machine # [ 53.211639] systemd[576]: Reloading finished in 189 ms.1361machine # [ 53.228153] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1362machine # [ 53.237436] systemd[576]: Stopped Run user-specific NixOS activation.1363machine # [ 53.239724] systemd[576]: Stopping Run user-specific NixOS activation...1364machine # [ 53.242502] systemd[576]: Starting Run user-specific NixOS activation...1365machine # [ 53.289523] systemd[576]: Finished Run user-specific NixOS activation.1366machine # [ 53.294307] systemd[1]: Stopped target Reactivate sysinit units.1367machine # [ 53.297546] systemd[1]: Stopping Reactivate sysinit units...1368machine # [ 53.299562] systemd[1]: Reached target Reactivate sysinit units.1369machine # [ 53.304153] systemd[1]: Reached target Local File Systems.1370machine # [ 53.307687] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1371machine # [ 53.312156] systemd[1]: Starting Create SUID/SGID Wrappers...1372machine # [ 53.313027] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1373machine # [ 53.314233] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671374machine # [ 53.318561] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1375machine # [ 53.329717] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1376machine # [ 53.331052] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1377machine # [ 53.334330] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1378machine # [ 53.335619] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1379machine # [ 53.336977] systemd[1]: Reached target Remote File Systems.1380machine # [ 53.647844] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1381machine # [ 53.652279] systemd[1]: Finished Create SUID/SGID Wrappers.1382machine # [ 53.655740] systemd[1]: Starting linger-users.service...1383machine # [ 53.715505] systemd[1]: linger-users.service: Deactivated successfully.1384machine # [ 53.717460] systemd[1]: Finished linger-users.service.1385machine # [ 53.976659] nixos[2291]: finished switching to system configuration /nix/store/c3q2ln63wnh9sc1xkp6590g4lgrv1mnn-nixos-system-machine-test1386machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 3.15 seconds)1387machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&11388machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&1, in 0.67 seconds)1389machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/bin/switch-to-configuration test 2>&11390machine # [ 55.209632] nixos[2441]: switching to system configuration /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test1391machine # [ 55.213721] systemd[1]: Stopped target Local File Systems.1392machine # [ 55.215440] systemd[1]: Stopped target Remote File Systems.1393machine # [ 55.216940] systemd[1]: test.service: Deactivated successfully.1394machine # [ 55.217794] systemd[1]: Stopped test.service.1395machine # [ 55.657659] systemd[1]: Reload requested from client PID 2441 ('.switch-to-conf') (unit backdoor.service)...1396machine # [ 55.659649] systemd[1]: Reloading...1397machine # [ 56.250861] systemd[1]: Reloading finished in 588 ms.1398machine # [ 56.395510] systemd[576]: Reexecution requested from client PID 2510 ('switch-to-confi')...1399machine # [ 56.403289] systemd[576]: Reexecuting.1400machine # [ 56.461723] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1401machine # [ 56.653660] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1402machine # [ 56.660562] systemd[576]: Reload requested from client PID 2510 ('switch-to-confi')...1403machine # [ 56.663072] systemd[576]: Reloading...1404machine # [ 56.849178] systemd[576]: Reloading finished in 184 ms.1405machine # [ 56.864150] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1406machine # [ 56.874008] systemd[576]: Stopped Run user-specific NixOS activation.1407machine # [ 56.876127] systemd[576]: Stopping Run user-specific NixOS activation...1408machine # [ 56.879085] systemd[576]: Starting Run user-specific NixOS activation...1409machine # [ 56.924542] systemd[576]: Finished Run user-specific NixOS activation.1410machine # [ 56.928942] systemd[1]: Stopped target Reactivate sysinit units.1411machine # [ 56.929936] systemd[1]: Stopping Reactivate sysinit units...1412machine # [ 56.930749] systemd[1]: Reached target Reactivate sysinit units.1413machine # [ 56.934178] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1414machine # [ 56.938643] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1415machine # [ 56.939948] systemd[1]: Reached target Remote File Systems.1416machine # [ 56.941666] systemd[1]: Reached target Local File Systems.1417machine # [ 56.944677] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1418machine # [ 56.951500] systemd[1]: Starting Create SUID/SGID Wrappers...1419machine # [ 56.954323] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1420machine # [ 56.957865] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671421machine # [ 56.960850] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1422machine # [ 56.963144] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1423machine # [ 56.968285] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1424machine # [ 57.280799] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1425machine # [ 57.284685] systemd[1]: Finished Create SUID/SGID Wrappers.1426machine # [ 57.288751] systemd[1]: Starting linger-users.service...1427machine # [ 57.347302] systemd[1]: linger-users.service: Deactivated successfully.1428machine # [ 57.350279] systemd[1]: Finished linger-users.service.1429machine # [ 57.606919] nixos[2441]: finished switching to system configuration /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test1430machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.96 seconds)1431machine: must succeed: /nix/store/pfd0qvyry9wawbdyq7nx8hhdipj4bbvl-separate-activation-script/bin/switch-to-configuration test 2>&11432machine # [ 58.214370] nixos[2581]: switching to system configuration /nix/store/wsy896zs9is9lmv0vm8jp664c7vcp1g6-nixos-system-machine-test1433machine # [ 58.218453] systemd[1]: Stopped target Remote File Systems.1434machine # [ 58.219610] systemd[1]: Stopped target Local File Systems.1435machine # [ 58.750370] systemd[1]: Reload requested from client PID 2581 ('.switch-to-conf') (unit backdoor.service)...1436machine # [ 58.752546] systemd[1]: Reloading...1437machine # [ 59.355297] systemd[1]: Reloading finished in 600 ms.1438machine # [ 59.517140] systemd[576]: Stopped Run user-specific NixOS activation.1439machine # [ 59.522356] systemd[576]: Reexecution requested from client PID 2649 ('switch-to-confi')...1440machine # [ 59.523753] systemd[576]: Reexecuting.1441machine # [ 59.586139] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1442machine # [ 59.779511] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1443machine # [ 59.786284] systemd[576]: Reload requested from client PID 2649 ('switch-to-confi')...1444machine # [ 59.789105] systemd[576]: Reloading...1445machine # [ 59.969598] systemd[576]: Reloading finished in 179 ms.1446machine # [ 59.984166] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1447machine # [ 59.996611] systemd[1]: Stopped target Reactivate sysinit units.1448machine # [ 59.999474] systemd[1]: Stopping Reactivate sysinit units...1449machine # [ 60.001126] systemd[1]: Reached target Reactivate sysinit units.1450machine # [ 60.001966] systemd[1]: Reached target Local File Systems.1451machine # [ 60.005843] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1452machine # [ 60.011428] systemd[1]: Starting Create SUID/SGID Wrappers...1453machine # [ 60.014771] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1454machine # [ 60.017408] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671455machine # [ 60.019584] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1456machine # [ 60.029777] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1457machine # [ 60.031141] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1458machine # [ 60.034892] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1459machine # [ 60.039455] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1460machine # [ 60.044191] systemd[1]: Reached target Remote File Systems.1461machine # [ 60.360172] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1462machine # [ 60.362713] systemd[1]: Finished Create SUID/SGID Wrappers.1463machine # [ 60.370184] systemd[1]: Starting linger-users.service...1464machine # [ 60.373509] systemd[1]: Starting test.service...1465machine # [ 60.421954] systemd[1]: Finished test.service.1466machine # [ 60.452138] systemd[1]: linger-users.service: Deactivated successfully.1467machine # [ 60.454355] systemd[1]: Finished linger-users.service.1468machine # [ 60.714301] nixos[2581]: finished switching to system configuration /nix/store/wsy896zs9is9lmv0vm8jp664c7vcp1g6-nixos-system-machine-test1469machine: (finished: must succeed: /nix/store/pfd0qvyry9wawbdyq7nx8hhdipj4bbvl-separate-activation-script/bin/switch-to-configuration test 2>&1, in 3.11 seconds)1470machine: must succeed: ! test -e /run/current-system/activate1471machine: (finished: must succeed: ! test -e /run/current-system/activate, in 0.04 seconds)1472machine: must succeed: ! test -e /run/current-system/dry-activate1473machine: (finished: must succeed: ! test -e /run/current-system/dry-activate, in 0.03 seconds)1474machine: must succeed: ! test -e /run/current-system/bin/switch-to-configuration1475machine: (finished: must succeed: ! test -e /run/current-system/bin/switch-to-configuration, in 0.03 seconds)1476machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&11477machine # [ 61.392182] nixos[2729]: switching to system configuration /nix/store/igs01w5cq5hvcy0lx0lxqwfwdcqdirv2-nixos-system-machine-test1478machine # [ 61.396661] systemd[1]: Stopped target Local File Systems.1479machine # [ 61.399340] systemd[1]: Stopped target Remote File Systems.1480machine # [ 61.400554] systemd[1]: test.service: Deactivated successfully.1481machine # [ 61.401812] systemd[1]: Stopped test.service.1482machine # [ 61.925616] systemd[1]: Reload requested from client PID 2729 ('.switch-to-conf') (unit backdoor.service)...1483machine # [ 61.927635] systemd[1]: Reloading...1484machine # [ 62.556651] systemd[1]: Reloading finished in 626 ms.1485machine # [ 62.704544] systemd[576]: Reexecution requested from client PID 2798 ('switch-to-confi')...1486machine # [ 62.708811] systemd[576]: Reexecuting.1487machine # [ 62.771644] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1488machine # [ 62.977965] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1489machine # [ 62.985889] systemd[576]: Reload requested from client PID 2798 ('switch-to-confi')...1490machine # [ 62.988869] systemd[576]: Reloading...1491machine # [ 63.183449] systemd[576]: Reloading finished in 193 ms.1492machine # [ 63.200236] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1493machine # [ 63.212070] systemd[576]: Starting Run user-specific NixOS activation...1494machine # [ 63.264123] systemd[576]: Finished Run user-specific NixOS activation.1495machine # [ 63.269857] systemd[576]: Stopped Run user-specific NixOS activation.1496machine # [ 63.271364] systemd[576]: Stopping Run user-specific NixOS activation...1497machine # [ 63.274591] systemd[576]: Starting Run user-specific NixOS activation...1498machine # [ 63.277791] systemd[1]: Stopped target Reactivate sysinit units.1499machine # [ 63.284586] systemd[1]: Stopping Reactivate sysinit units...1500machine # [ 63.285530] systemd[1]: Reached target Reactivate sysinit units.1501machine # [ 63.292113] systemd[1]: Reached target Local File Systems.1502machine # [ 63.294115] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1503machine # [ 63.303707] systemd[1]: Starting Create SUID/SGID Wrappers...1504machine # [ 63.307709] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1505machine # [ 63.311216] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671506machine # [ 63.324424] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1507machine # [ 63.329131] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1508machine # [ 63.330458] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1509machine # [ 63.335018] systemd[1]: Reached target Remote File Systems.1510machine # [ 63.340621] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1511machine # [ 63.341953] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1512machine # [ 63.359312] systemd[576]: Finished Run user-specific NixOS activation.1513machine # [ 63.682800] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1514machine # [ 63.687242] systemd[1]: Finished Create SUID/SGID Wrappers.1515machine # [ 63.691549] systemd[1]: Starting linger-users.service...1516machine # [ 63.695227] systemd[1]: Started test.service.1517machine # [ 63.742371] systemd[1]: test.service: Deactivated successfully.1518machine # [ 63.771227] systemd[1]: linger-users.service: Deactivated successfully.1519machine # [ 63.774229] systemd[1]: Finished linger-users.service.1520machine # [ 64.031026] nixos[2729]: finished switching to system configuration /nix/store/igs01w5cq5hvcy0lx0lxqwfwdcqdirv2-nixos-system-machine-test1521machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&1, in 3.22 seconds)1522machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&11523machine # [ 64.683678] nixos[2874]: switching to system configuration /nix/store/2ndvk2r0k5nlpxgv8j1fgjrr2p6qydxq-nixos-system-machine-test1524machine # [ 64.687320] systemd[1]: Stopped target Local File Systems.1525machine # [ 64.687751] systemd[1]: Stopped target Remote File Systems.1526machine # [ 65.256770] systemd[1]: Reload requested from client PID 2874 ('.switch-to-conf') (unit backdoor.service)...1527machine # [ 65.258954] systemd[1]: Reloading...1528machine # [ 65.957742] systemd[1]: Reloading finished in 696 ms.1529machine # [ 66.103894] systemd[576]: Reexecution requested from client PID 2943 ('switch-to-confi')...1530machine # [ 66.106186] systemd[576]: Reexecuting.1531machine # [ 66.173822] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1532machine # [ 66.377029] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1533machine # [ 66.384741] systemd[576]: Reload requested from client PID 2943 ('switch-to-confi')...1534machine # [ 66.388109] systemd[576]: Reloading...1535machine # [ 66.590889] systemd[576]: Reloading finished in 203 ms.1536machine # [ 66.604178] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1537machine # [ 66.615868] systemd[576]: Stopped Run user-specific NixOS activation.1538machine # [ 66.617179] systemd[576]: Stopping Run user-specific NixOS activation...1539machine # [ 66.620337] systemd[576]: Starting Run user-specific NixOS activation...1540machine # [ 66.667832] systemd[576]: Finished Run user-specific NixOS activation.1541machine # [ 66.672866] systemd[1]: Stopped target Reactivate sysinit units.1542machine # [ 66.674273] systemd[1]: Stopping Reactivate sysinit units...1543machine # [ 66.675129] systemd[1]: Reached target Reactivate sysinit units.1544machine # [ 66.678037] systemd[1]: Reached target Remote File Systems.1545machine # [ 66.681682] systemd[1]: Reached target Local File Systems.1546machine # [ 66.690711] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1547machine # [ 66.694933] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1548machine # [ 66.699701] systemd[1]: Starting escaped\x2ddash.service...1549machine # [ 66.703823] systemd[1]: Starting linger-users.service...1550machine # [ 66.712350] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1551machine # [ 66.715798] systemd[1]: Starting Create SUID/SGID Wrappers...1552machine # [ 66.717562] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1553machine # [ 66.723649] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671554machine # [ 66.726749] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1555machine # [ 66.734215] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1556machine # [ 66.735555] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1557machine # [ 66.774310] systemd[1]: Finished escaped\x2ddash.service.1558machine # [ 66.809000] systemd[1]: linger-users.service: Deactivated successfully.1559machine # [ 66.812524] systemd[1]: Finished linger-users.service.1560machine # [ 67.108406] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1561machine # [ 67.111642] systemd[1]: Finished Create SUID/SGID Wrappers.1562machine # [ 67.370325] nixos[2874]: finished switching to system configuration /nix/store/2ndvk2r0k5nlpxgv8j1fgjrr2p6qydxq-nixos-system-machine-test1563machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&1, in 3.34 seconds)1564machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&11565machine # [ 68.041681] nixos[3016]: switching to system configuration /nix/store/nsgss3kmvl8y9b0z4zwvjvzip9rv1d3i-nixos-system-machine-test1566machine # [ 68.045585] systemd[1]: Stopped target Local File Systems.1567machine # [ 68.047384] systemd[1]: Stopped target Remote File Systems.1568machine # [ 68.048963] systemd[1]: escaped\x2ddash.service: Deactivated successfully.1569machine # [ 68.049944] systemd[1]: Stopped escaped\x2ddash.service.1570machine # [ 68.566071] systemd[1]: Reload requested from client PID 3016 ('.switch-to-conf') (unit backdoor.service)...1571machine # [ 68.568046] systemd[1]: Reloading...1572machine # [ 69.229370] systemd[1]: Reloading finished in 659 ms.1573machine # [ 69.381902] systemd[576]: Reexecution requested from client PID 3085 ('switch-to-confi')...1574machine # [ 69.383334] systemd[576]: Reexecuting.1575machine # [ 69.454078] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1576machine # [ 69.650469] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1577machine # [ 69.656971] systemd[576]: Reload requested from client PID 3085 ('switch-to-confi')...1578machine # [ 69.659285] systemd[576]: Reloading...1579machine # [ 69.851297] systemd[576]: Reloading finished in 190 ms.1580machine # [ 69.864183] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1581machine # [ 69.874557] systemd[576]: Stopped Run user-specific NixOS activation.1582machine # [ 69.875617] systemd[576]: Stopping Run user-specific NixOS activation...1583machine # [ 69.878779] systemd[576]: Starting Run user-specific NixOS activation...1584machine # [ 69.923365] systemd[576]: Finished Run user-specific NixOS activation.1585machine # [ 69.927772] systemd[1]: Stopped target Reactivate sysinit units.1586machine # [ 69.930603] systemd[1]: Stopping Reactivate sysinit units...1587machine # [ 69.931457] systemd[1]: Reached target Reactivate sysinit units.1588machine # [ 69.936521] systemd[1]: Reached target Local File Systems.1589machine # [ 69.937402] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1590machine # [ 69.942143] systemd[1]: Starting Create SUID/SGID Wrappers...1591machine # [ 69.946332] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1592machine # [ 69.947624] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671593machine # [ 69.957084] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1594machine # [ 69.958746] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1595machine # [ 69.960387] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1596machine # [ 69.965044] systemd[1]: Reached target Remote File Systems.1597machine # [ 69.966993] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1598machine # [ 69.969690] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1599machine # [ 70.267906] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1600machine # [ 70.270229] systemd[1]: Finished Create SUID/SGID Wrappers.1601machine # [ 70.278696] systemd[1]: Starting escaped\x2ddash.service...1602machine # [ 70.282482] systemd[1]: Starting linger-users.service...1603machine # [ 70.332899] systemd[1]: Finished escaped\x2ddash.service.1604machine # [ 70.366687] systemd[1]: linger-users.service: Deactivated successfully.1605machine # [ 70.369273] systemd[1]: Finished linger-users.service.1606machine # [ 70.629184] nixos[3016]: finished switching to system configuration /nix/store/nsgss3kmvl8y9b0z4zwvjvzip9rv1d3i-nixos-system-machine-test1607machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&1, in 3.26 seconds)1608machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&11609machine # [ 71.308967] nixos[3158]: switching to system configuration /nix/store/jmr1l4mfj2pck63say225q1r6gzywf56-nixos-system-machine-test1610machine # [ 71.313219] systemd[1]: Stopped target Local File Systems.1611machine # [ 71.315134] systemd[1]: Stopped target Remote File Systems.1612machine # [ 71.316756] systemd[1]: escaped\x2ddash.service: Deactivated successfully.1613machine # [ 71.317874] systemd[1]: Stopped escaped\x2ddash.service.1614machine # [ 71.856790] systemd[1]: Reload requested from client PID 3158 ('.switch-to-conf') (unit backdoor.service)...1615machine # [ 71.858834] systemd[1]: Reloading...1616machine # [ 72.525892] systemd[1]: Reloading finished in 664 ms.1617machine # [ 72.671727] systemd[576]: Reexecution requested from client PID 3227 ('switch-to-confi')...1618machine # [ 72.674351] systemd[576]: Reexecuting.1619machine # [ 72.742182] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1620machine # [ 72.940807] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1621machine # [ 72.948101] systemd[576]: Reload requested from client PID 3227 ('switch-to-confi')...1622machine # [ 72.949450] systemd[576]: Reloading...1623machine # [ 73.142199] systemd[576]: Reloading finished in 191 ms.1624machine # [ 73.156248] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1625machine # [ 73.168107] systemd[576]: Stopped Run user-specific NixOS activation.1626machine # [ 73.169137] systemd[576]: Stopping Run user-specific NixOS activation...1627machine # [ 73.172697] systemd[576]: Starting Run user-specific NixOS activation...1628machine # [ 73.219386] systemd[576]: Finished Run user-specific NixOS activation.1629machine # [ 73.223940] systemd[1]: Stopped target Reactivate sysinit units.1630machine # [ 73.226537] systemd[1]: Stopping Reactivate sysinit units...1631machine # [ 73.227423] systemd[1]: Reached target Reactivate sysinit units.1632machine # [ 73.232773] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1633machine # [ 73.237267] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1634machine # [ 73.239340] systemd[1]: Reached target Local File Systems.1635machine # [ 73.244775] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1636machine # [ 73.249297] systemd[1]: Starting Create SUID/SGID Wrappers...1637machine # [ 73.256733] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1638machine # [ 73.258122] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671639machine # [ 73.264173] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1640machine # [ 73.265805] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1641machine # [ 73.267136] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1642machine # [ 73.268464] systemd[1]: Reached target Remote File Systems.1643machine # [ 73.582185] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1644machine # [ 73.586471] systemd[1]: Finished Create SUID/SGID Wrappers.1645machine # [ 73.590056] systemd[1]: Starting -.service...1646machine # [ 73.593046] systemd[1]: Starting linger-users.service...1647machine # [ 73.638205] systemd[1]: Finished -.service.1648machine # [ 73.662589] systemd[1]: linger-users.service: Deactivated successfully.1649machine # [ 73.665826] systemd[1]: Finished linger-users.service.1650machine # [ 73.920843] nixos[3158]: finished switching to system configuration /nix/store/jmr1l4mfj2pck63say225q1r6gzywf56-nixos-system-machine-test1651machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&1, in 3.28 seconds)1652machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&11653machine # [ 74.581566] nixos[3300]: switching to system configuration /nix/store/j5w2c6jv7s26cmcf2swlpg91j8zpbhhp-nixos-system-machine-test1654machine # [ 74.585349] systemd[1]: Stopped target Local File Systems.1655machine # [ 74.586928] systemd[1]: Stopped target Remote File Systems.1656machine # [ 75.093144] systemd[1]: Reload requested from client PID 3300 ('.switch-to-conf') (unit backdoor.service)...1657machine # [ 75.095127] systemd[1]: Reloading...1658machine # [ 75.716919] systemd[1]: Reloading finished in 619 ms.1659machine # [ 75.857427] systemd[576]: Reexecution requested from client PID 3368 ('switch-to-confi')...1660machine # [ 75.861437] systemd[576]: Reexecuting.1661machine # [ 75.917872] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1662machine # [ 76.101192] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1663machine # [ 76.107689] systemd[576]: Reload requested from client PID 3368 ('switch-to-confi')...1664machine # [ 76.110141] systemd[576]: Reloading...1665machine # [ 76.294747] systemd[576]: Reloading finished in 183 ms.1666machine # [ 76.312194] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1667machine # [ 76.322084] systemd[576]: Stopped Run user-specific NixOS activation.1668machine # [ 76.324394] systemd[576]: Stopping Run user-specific NixOS activation...1669machine # [ 76.327142] systemd[576]: Starting Run user-specific NixOS activation...1670machine # [ 76.373828] systemd[576]: Finished Run user-specific NixOS activation.1671machine # [ 76.377855] systemd[1]: Stopped target Reactivate sysinit units.1672machine # [ 76.382030] systemd[1]: Stopping Reactivate sysinit units...1673machine # [ 76.383245] systemd[1]: Reached target Reactivate sysinit units.1674machine # [ 76.385782] systemd[1]: Reloading -.service...1675machine # [ 76.422483] systemd[1]: Reloaded -.service.1676machine # [ 76.427027] systemd[1]: Reached target Remote File Systems.1677machine # [ 76.429459] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1678machine # [ 76.435837] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1679machine # [ 76.437452] systemd[1]: Reached target Local File Systems.1680machine # [ 76.439854] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1681machine # [ 76.448317] systemd[1]: Starting Create SUID/SGID Wrappers...1682machine # [ 76.449256] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1683machine # [ 76.450468] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671684machine # [ 76.453246] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1685machine # [ 76.455192] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1686machine # [ 76.459128] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1687machine # [ 76.759659] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1688machine # [ 76.763710] systemd[1]: Finished Create SUID/SGID Wrappers.1689machine # [ 76.767406] systemd[1]: Starting linger-users.service...1690machine # [ 76.827731] systemd[1]: linger-users.service: Deactivated successfully.1691machine # [ 76.830407] systemd[1]: Finished linger-users.service.1692machine # [ 77.089289] nixos[3300]: finished switching to system configuration /nix/store/j5w2c6jv7s26cmcf2swlpg91j8zpbhhp-nixos-system-machine-test1693machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&1, in 3.18 seconds)1694machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&11695machine # [ 77.705070] nixos[3442]: switching to system configuration /nix/store/yxz4byyn14f4v3wabm0sn449jl4s1qpv-nixos-system-machine-test1696machine # [ 77.707708] systemd[1]: Stopped target Remote File Systems.1697machine # [ 77.710383] systemd[1]: -.service: Deactivated successfully.1698machine # [ 77.711202] systemd[1]: Stopped -.service.1699machine # [ 77.712292] systemd[1]: Stopped target Local File Systems.1700machine # [ 78.211218] systemd[1]: Reload requested from client PID 3442 ('.switch-to-conf') (unit backdoor.service)...1701machine # [ 78.213339] systemd[1]: Reloading...1702machine # [ 78.841556] systemd[1]: Reloading finished in 626 ms.1703machine # [ 78.988523] systemd[576]: Reexecution requested from client PID 3511 ('switch-to-confi')...1704machine # [ 78.991256] systemd[576]: Reexecuting.1705machine # [ 79.053940] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1706machine # [ 79.241851] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1707machine # [ 79.248461] systemd[576]: Reload requested from client PID 3511 ('switch-to-confi')...1708machine # [ 79.250954] systemd[576]: Reloading...1709machine # [ 79.434385] systemd[576]: Reloading finished in 182 ms.1710machine # [ 79.448155] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1711machine # [ 79.456920] systemd[576]: Stopped Run user-specific NixOS activation.1712machine # [ 79.459133] systemd[576]: Stopping Run user-specific NixOS activation...1713machine # [ 79.461795] systemd[576]: Starting Run user-specific NixOS activation...1714machine # [ 79.508552] systemd[576]: Finished Run user-specific NixOS activation.1715machine # [ 79.512812] systemd[1]: Stopped target Reactivate sysinit units.1716machine # [ 79.515338] systemd[1]: Stopping Reactivate sysinit units...1717machine # [ 79.516844] systemd[1]: Reached target Reactivate sysinit units.1718machine # [ 79.518402] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1719machine # [ 79.519705] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1720machine # [ 79.523092] systemd[1]: Reached target Local File Systems.1721machine # [ 79.526426] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1722machine # [ 79.530409] systemd[1]: Starting Create SUID/SGID Wrappers...1723machine # [ 79.531285] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1724machine # [ 79.534915] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671725machine # [ 79.543747] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1726machine # [ 79.545728] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1727machine # [ 79.548666] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1728machine # [ 79.551510] systemd[1]: Reached target Remote File Systems.1729machine # [ 79.854259] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1730machine # [ 79.856660] systemd[1]: Finished Create SUID/SGID Wrappers.1731machine # [ 79.862804] systemd[1]: Starting linger-users.service...1732machine # [ 79.866496] systemd[1]: Starting required-service.service...1733machine # [ 79.869706] systemd[1]: Starting test-service.service...1734machine # [ 79.919668] systemd[1]: Finished required-service.service.1735machine # [ 79.923381] systemd[1]: Finished test-service.service.1736machine # [ 79.947667] systemd[1]: linger-users.service: Deactivated successfully.1737machine # [ 79.950150] systemd[1]: Finished linger-users.service.1738machine # [ 80.208300] nixos[3442]: finished switching to system configuration /nix/store/yxz4byyn14f4v3wabm0sn449jl4s1qpv-nixos-system-machine-test1739machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&1, in 3.12 seconds)1740machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&11741machine # [ 80.822246] nixos[3586]: switching to system configuration /nix/store/0wfw2xbqgxs0vsv2n0nl6k6a4mz78ab3-nixos-system-machine-test1742machine # [ 80.825001] systemd[1]: Stopped target Local File Systems.1743machine # [ 80.828885] systemd[1]: required-service.service: Deactivated successfully.1744machine # [ 80.830111] systemd[1]: Stopped required-service.service.1745machine # [ 80.831151] systemd[1]: test-service.service: Deactivated successfully.1746machine # [ 80.832392] systemd[1]: Stopped test-service.service.1747machine # [ 80.833202] systemd[1]: Stopped target Remote File Systems.1748machine # [ 81.336609] systemd[1]: Reload requested from client PID 3586 ('.switch-to-conf') (unit backdoor.service)...1749machine # [ 81.338582] systemd[1]: Reloading...1750machine # [ 82.011118] systemd[1]: Reloading finished in 670 ms.1751machine # [ 82.156319] systemd[576]: Reexecution requested from client PID 3655 ('switch-to-confi')...1752machine # [ 82.160141] systemd[576]: Reexecuting.1753machine # [ 82.230109] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1754machine # [ 82.420972] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1755machine # [ 82.427813] systemd[576]: Reload requested from client PID 3655 ('switch-to-confi')...1756machine # [ 82.430623] systemd[576]: Reloading...1757machine # [ 82.613536] systemd[576]: Reloading finished in 182 ms.1758machine # [ 82.628169] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1759machine # [ 82.638371] systemd[576]: Stopped Run user-specific NixOS activation.1760machine # [ 82.639440] systemd[576]: Stopping Run user-specific NixOS activation...1761machine # [ 82.642247] systemd[576]: Starting Run user-specific NixOS activation...1762machine # [ 82.690125] systemd[576]: Finished Run user-specific NixOS activation.1763machine # [ 82.694259] systemd[1]: Stopped target Reactivate sysinit units.1764machine # [ 82.696370] systemd[1]: Stopping Reactivate sysinit units...1765machine # [ 82.697327] systemd[1]: Reached target Reactivate sysinit units.1766machine # [ 82.703176] systemd[1]: Reached target Local File Systems.1767machine # [ 82.706665] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1768machine # [ 82.711180] systemd[1]: Starting Create SUID/SGID Wrappers...1769machine # [ 82.715191] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1770machine # [ 82.718736] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671771machine # [ 82.722554] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1772machine # [ 82.727923] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1773machine # [ 82.735797] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1774machine # [ 82.737594] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1775machine # [ 82.739506] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1776machine # [ 82.741071] systemd[1]: Reached target Remote File Systems.1777machine # [ 83.036877] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1778machine # [ 83.040678] systemd[1]: Finished Create SUID/SGID Wrappers.1779machine # [ 83.044354] systemd[1]: Starting linger-users.service...1780machine # [ 83.048531] systemd[1]: Starting required-service.service...1781machine # [ 83.053832] systemd[1]: Starting test-service.service...1782machine # [ 83.105783] systemd[1]: Finished test-service.service.1783machine # [ 83.109707] systemd[1]: Finished required-service.service.1784machine # [ 83.135639] systemd[1]: linger-users.service: Deactivated successfully.1785machine # [ 83.138778] systemd[1]: Finished linger-users.service.1786machine # [ 83.395307] nixos[3586]: finished switching to system configuration /nix/store/0wfw2xbqgxs0vsv2n0nl6k6a4mz78ab3-nixos-system-machine-test1787machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&1, in 3.19 seconds)1788machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&11789machine # [ 84.023931] nixos[3730]: switching to system configuration /nix/store/jdf8i31imwkdy61hpqrd1m3xj1w7nifj-nixos-system-machine-test1790machine # [ 84.026723] systemd[1]: required-service.service: Deactivated successfully.1791machine # [ 84.029933] systemd[1]: Stopped required-service.service.1792machine # [ 84.030764] systemd[1]: test-service.service: Deactivated successfully.1793machine # [ 84.031638] systemd[1]: Stopped test-service.service.1794machine # [ 84.033362] systemd[1]: Stopped target Local File Systems.1795machine # [ 84.034416] systemd[1]: Stopped target Remote File Systems.1796machine # [ 84.534576] systemd[1]: Reload requested from client PID 3730 ('.switch-to-conf') (unit backdoor.service)...1797machine # [ 84.536722] systemd[1]: Reloading...1798machine # [ 85.188621] systemd[1]: Reloading finished in 649 ms.1799machine # [ 85.331935] systemd[576]: Reexecution requested from client PID 3799 ('switch-to-confi')...1800machine # [ 85.335643] systemd[576]: Reexecuting.1801machine # [ 85.389801] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1802machine # [ 85.578335] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1803machine # [ 85.584899] systemd[576]: Reload requested from client PID 3799 ('switch-to-confi')...1804machine # [ 85.587438] systemd[576]: Reloading...1805machine # [ 85.775421] systemd[576]: Reloading finished in 186 ms.1806machine # [ 85.788155] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1807machine # [ 85.797449] systemd[576]: Stopped Run user-specific NixOS activation.1808machine # [ 85.799773] systemd[576]: Stopping Run user-specific NixOS activation...1809machine # [ 85.802662] systemd[576]: Starting Run user-specific NixOS activation...1810machine # [ 85.849037] systemd[576]: Finished Run user-specific NixOS activation.1811machine # [ 85.853423] systemd[1]: Stopped target Reactivate sysinit units.1812machine # [ 85.856563] systemd[1]: Stopping Reactivate sysinit units...1813machine # [ 85.857411] systemd[1]: Reached target Reactivate sysinit units.1814machine # [ 85.858264] systemd[1]: Reached target Local File Systems.1815machine # [ 85.863746] systemd[1]: Starting required-service.service...1816machine # [ 85.871909] systemd[1]: Reached target Remote File Systems.1817machine # [ 85.876704] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1818machine # [ 85.878045] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1819machine # [ 85.887210] systemd[1]: Starting linger-users.service...1820machine # [ 85.890093] systemd[1]: Starting test-service.service...1821machine # [ 85.897754] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1822machine # [ 85.906965] systemd[1]: Starting Create SUID/SGID Wrappers...1823machine # [ 85.913119] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1824machine # [ 85.914403] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671825machine # [ 85.919760] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1826machine # [ 85.921942] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1827machine # [ 85.923292] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1828machine # [ 85.951903] systemd[1]: Finished required-service.service.1829machine # [ 85.970648] systemd[1]: Finished test-service.service.1830machine # [ 86.003080] systemd[1]: linger-users.service: Deactivated successfully.1831machine # [ 86.006666] systemd[1]: Finished linger-users.service.1832machine # [ 86.266085] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1833machine # [ 86.269199] systemd[1]: Finished Create SUID/SGID Wrappers.1834machine # [ 86.524414] nixos[3730]: finished switching to system configuration /nix/store/jdf8i31imwkdy61hpqrd1m3xj1w7nifj-nixos-system-machine-test1835machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&1, in 3.12 seconds)1836machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&11837machine # [ 87.124379] nixos[3874]: switching to system configuration /nix/store/zdwik0mgcms0p47r7qxvafg4c8dp96l4-nixos-system-machine-test1838machine # [ 87.127767] systemd[1]: test-service.service: Deactivated successfully.1839machine # [ 87.130622] systemd[1]: Stopped test-service.service.1840machine # [ 87.131397] systemd[1]: required-service.service: Deactivated successfully.1841machine # [ 87.133895] systemd[1]: Stopped required-service.service.1842machine # [ 87.134704] systemd[1]: Stopped target Local File Systems.1843machine # [ 87.135440] systemd[1]: Stopped target Remote File Systems.1844machine # [ 87.671156] systemd[1]: Reload requested from client PID 3874 ('.switch-to-conf') (unit backdoor.service)...1845machine # [ 87.673444] systemd[1]: Reloading...1846machine # [ 88.334822] systemd[1]: Reloading finished in 657 ms.1847machine # [ 88.471683] systemd[576]: Reexecution requested from client PID 3942 ('switch-to-confi')...1848machine # [ 88.475337] systemd[576]: Reexecuting.1849machine # [ 88.537731] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1850machine # [ 88.733531] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1851machine # [ 88.740247] systemd[576]: Reload requested from client PID 3942 ('switch-to-confi')...1852machine # [ 88.742590] systemd[576]: Reloading...1853machine # [ 88.926339] systemd[576]: Reloading finished in 182 ms.1854machine # [ 88.944184] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1855machine # [ 88.953376] systemd[576]: Stopped Run user-specific NixOS activation.1856machine # [ 88.955712] systemd[576]: Stopping Run user-specific NixOS activation...1857machine # [ 88.958570] systemd[576]: Starting Run user-specific NixOS activation...1858machine # [ 88.998974] systemd[576]: Finished Run user-specific NixOS activation.1859machine # [ 89.003087] systemd[1]: Stopped target Reactivate sysinit units.1860machine # [ 89.004811] systemd[1]: Stopping Reactivate sysinit units...1861machine # [ 89.005640] systemd[1]: Reached target Reactivate sysinit units.1862machine # [ 89.008244] systemd[1]: Reached target Local File Systems.1863machine # [ 89.011747] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1864machine # [ 89.015248] systemd[1]: Starting Create SUID/SGID Wrappers...1865machine # [ 89.018212] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1866machine # [ 89.024386] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671867machine # [ 89.026593] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1868machine # [ 89.034160] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1869machine # [ 89.035441] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1870machine # [ 89.039850] systemd[1]: Created slice Slice /system/instantiated.1871machine # [ 89.042624] systemd[1]: Reached target Remote File Systems.1872machine # [ 89.043410] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1873machine # [ 89.045501] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1874machine # [ 89.371457] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1875machine # [ 89.376631] systemd[1]: Finished Create SUID/SGID Wrappers.1876machine # [ 89.380438] systemd[1]: Starting instantiated@one.service...1877machine # [ 89.384209] systemd[1]: Starting instantiated@two.service...1878machine # [ 89.389223] systemd[1]: Starting linger-users.service...1879machine # [ 89.441940] systemd[1]: Finished instantiated@two.service.1880machine # [ 89.447007] systemd[1]: Finished instantiated@one.service.1881machine # [ 89.474540] systemd[1]: linger-users.service: Deactivated successfully.1882machine # [ 89.477494] systemd[1]: Finished linger-users.service.1883machine # [ 89.736183] nixos[3874]: finished switching to system configuration /nix/store/zdwik0mgcms0p47r7qxvafg4c8dp96l4-nixos-system-machine-test1884machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&1, in 3.21 seconds)1885machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&11886machine # [ 90.441031] nixos[4018]: switching to system configuration /nix/store/xvxkmjhzpy42dda914zmrqk56ibwjlaz-nixos-system-machine-test1887machine # [ 90.445593] systemd[1]: Stopped target Remote File Systems.1888machine # [ 90.449266] systemd[1]: instantiated@one.service: Deactivated successfully.1889machine # [ 90.450591] systemd[1]: Stopped instantiated@one.service.1890machine # [ 90.451583] systemd[1]: instantiated@two.service: Deactivated successfully.1891machine # [ 90.453245] systemd[1]: Stopped instantiated@two.service.1892machine # [ 90.454291] systemd[1]: Stopped target Local File Systems.1893machine # [ 90.979958] systemd[1]: Reload requested from client PID 4018 ('.switch-to-conf') (unit backdoor.service)...1894machine # [ 90.982042] systemd[1]: Reloading...1895machine # [ 91.636534] systemd[1]: Reloading finished in 652 ms.1896machine # [ 91.784485] systemd[576]: Reexecution requested from client PID 4087 ('switch-to-confi')...1897machine # [ 91.787824] systemd[576]: Reexecuting.1898machine # [ 91.841834] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1899machine # [ 92.031720] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1900machine # [ 92.038321] systemd[576]: Reload requested from client PID 4087 ('switch-to-confi')...1901machine # [ 92.040864] systemd[576]: Reloading...1902machine # [ 92.225827] systemd[576]: Reloading finished in 183 ms.1903machine # [ 92.240198] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1904machine # [ 92.250933] systemd[576]: Stopped Run user-specific NixOS activation.1905machine # [ 92.253615] systemd[576]: Stopping Run user-specific NixOS activation...1906machine # [ 92.256931] systemd[576]: Starting Run user-specific NixOS activation...1907machine # [ 92.308588] systemd[576]: Finished Run user-specific NixOS activation.1908machine # [ 92.313252] systemd[1]: Stopped target Reactivate sysinit units.1909machine # [ 92.314632] systemd[1]: Stopping Reactivate sysinit units...1910machine # [ 92.315480] systemd[1]: Reached target Reactivate sysinit units.1911machine # [ 92.318924] systemd[1]: Reached target Remote File Systems.1912machine # [ 92.325651] systemd[1]: Starting instantiated@one.service...1913machine # [ 92.330028] systemd[1]: Starting instantiated@two.service...1914machine # [ 92.333580] systemd[1]: Starting linger-users.service...1915machine # [ 92.354437] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1916machine # [ 92.357073] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1917machine # [ 92.370007] systemd[1]: Reached target Local File Systems.1918machine # [ 92.380848] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1919machine # [ 92.382357] systemd[1]: Starting Create SUID/SGID Wrappers...1920machine # [ 92.383129] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1921machine # [ 92.385490] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671922machine # [ 92.395409] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1923machine # [ 92.398991] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1924machine # [ 92.405307] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1925machine # [ 92.421669] systemd[1]: Finished instantiated@two.service.1926machine # [ 92.428168] systemd[1]: Finished instantiated@one.service.1927machine # [ 92.469352] systemd[1]: linger-users.service: Deactivated successfully.1928machine # [ 92.473083] systemd[1]: Finished linger-users.service.1929machine # [ 92.730580] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1930machine # [ 92.733759] systemd[1]: Finished Create SUID/SGID Wrappers.1931machine # [ 92.990398] nixos[4018]: finished switching to system configuration /nix/store/xvxkmjhzpy42dda914zmrqk56ibwjlaz-nixos-system-machine-test1932machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&1, in 3.26 seconds)1933(finished: subtest: services, in 67.69 seconds)1934subtest: failing units1935machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&11936machine # [ 93.600943] nixos[4162]: switching to system configuration /nix/store/nddm39yhin93faxx4z4z7r6yf2sbc2iz-nixos-system-machine-test1937machine # [ 93.603601] systemd[1]: instantiated@one.service: Deactivated successfully.1938machine # [ 93.607429] systemd[1]: Stopped instantiated@one.service.1939machine # [ 93.609458] systemd[1]: Stopped target Remote File Systems.1940machine # [ 93.610568] systemd[1]: instantiated@two.service: Deactivated successfully.1941machine # [ 93.611547] systemd[1]: Stopped instantiated@two.service.1942machine # [ 93.612763] systemd[1]: Stopped target Local File Systems.1943machine # [ 94.068962] systemd[1]: Reload requested from client PID 4162 ('.switch-to-conf') (unit backdoor.service)...1944machine # [ 94.070897] systemd[1]: Reloading...1945machine # [ 94.667549] systemd[1]: Reloading finished in 594 ms.1946machine # [ 94.812338] systemd[576]: Reexecution requested from client PID 4231 ('switch-to-confi')...1947machine # [ 94.813826] systemd[576]: Reexecuting.1948machine # [ 94.882632] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1949machine # [ 95.085292] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1950machine # [ 95.092692] systemd[576]: Reload requested from client PID 4231 ('switch-to-confi')...1951machine # [ 95.096064] systemd[576]: Reloading...1952machine # [ 95.299797] systemd[576]: Reloading finished in 204 ms.1953machine # [ 95.312197] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1954machine # [ 95.321598] systemd[576]: Stopped Run user-specific NixOS activation.1955machine # [ 95.324271] systemd[576]: Stopping Run user-specific NixOS activation...1956machine # [ 95.327271] systemd[576]: Starting Run user-specific NixOS activation...1957machine # [ 95.379505] systemd[576]: Finished Run user-specific NixOS activation.1958machine # [ 95.384814] systemd[1]: Stopped target Reactivate sysinit units.1959machine # [ 95.385788] systemd[1]: Stopping Reactivate sysinit units...1960machine # [ 95.386582] systemd[1]: Reached target Reactivate sysinit units.1961machine # [ 95.390042] systemd[1]: Reached target Local File Systems.1962machine # [ 95.392852] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1963machine # [ 95.399335] systemd[1]: Starting Create SUID/SGID Wrappers...1964machine # [ 95.403631] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1965machine # [ 95.406268] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671966machine # [ 95.413338] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1967machine # [ 95.414925] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1968machine # [ 95.422722] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1969machine # [ 95.423989] systemd[1]: Reached target Remote File Systems.1970machine # [ 95.426700] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1971machine # [ 95.427961] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1972machine # [ 95.759054] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1973machine # [ 95.763910] systemd[1]: Finished Create SUID/SGID Wrappers.1974machine # [ 95.768350] systemd[1]: Starting linger-users.service...1975machine # [ 95.770999] systemd[1]: Starting test.service...1976machine # [ 95.819599] systemd[1]: Finished test.service.1977machine # [ 95.845550] systemd[1]: linger-users.service: Deactivated successfully.1978machine # [ 95.848656] systemd[1]: Finished linger-users.service.1979machine # [ 96.104084] nixos[4162]: finished switching to system configuration /nix/store/nddm39yhin93faxx4z4z7r6yf2sbc2iz-nixos-system-machine-test1980machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 3.11 seconds)1981machine: must fail: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&11982machine # [ 96.723441] nixos[4304]: switching to system configuration /nix/store/iz8bl4p3m71g63vnvl6zqy1084fhna3y-nixos-system-machine-test1983machine # [ 96.726403] systemd[1]: Stopped target Local File Systems.1984machine # [ 96.728983] systemd[1]: test.service: Deactivated successfully.1985machine # [ 96.729924] systemd[1]: Stopped test.service.1986machine # [ 96.731195] systemd[1]: Stopped target Remote File Systems.1987machine # [ 97.253272] systemd[1]: Reload requested from client PID 4304 ('.switch-to-conf') (unit backdoor.service)...1988machine # [ 97.255246] systemd[1]: Reloading...1989machine # [ 97.922773] systemd[1]: Reloading finished in 664 ms.1990machine # [ 98.067876] systemd[576]: Reexecution requested from client PID 4373 ('switch-to-confi')...1991machine # [ 98.071915] systemd[576]: Reexecuting.1992machine # [ 98.134588] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1993machine # [ 98.330275] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1994machine # [ 98.336926] systemd[576]: Reload requested from client PID 4373 ('switch-to-confi')...1995machine # [ 98.339467] systemd[576]: Reloading...1996machine # [ 98.524643] systemd[576]: Reloading finished in 184 ms.1997machine # [ 98.540170] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1998machine # [ 98.550185] systemd[576]: Stopped Run user-specific NixOS activation.1999machine # [ 98.552645] systemd[576]: Stopping Run user-specific NixOS activation...2000machine # [ 98.555862] systemd[576]: Starting Run user-specific NixOS activation...2001machine # [ 98.596883] systemd[576]: Finished Run user-specific NixOS activation.2002machine # [ 98.600879] systemd[1]: Stopped target Reactivate sysinit units.2003machine # [ 98.603900] systemd[1]: Stopping Reactivate sysinit units...2004machine # [ 98.605580] systemd[1]: Reached target Reactivate sysinit units.2005machine # [ 98.610433] systemd[1]: Starting test.service...2006machine # [ 98.618093] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2007machine # [ 98.619447] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2008machine # [ 98.626338] systemd[1]: Reached target Remote File Systems.2009machine # [ 98.627184] systemd[1]: Reached target Local File Systems.2010machine # [ 98.630639] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2011machine # [ 98.638176] systemd[1]: Starting Create SUID/SGID Wrappers...2012machine # [ 98.639068] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2013machine # [ 98.642456] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672014machine # [ 98.645288] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2015machine # [ 98.647327] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2016machine # [ 98.649230] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2017machine # [ 98.679483] systemd[1]: test.service: Main process exited, code=exited, status=1/FAILURE2018machine # [ 98.681090] systemd[1]: test.service: Failed with result 'exit-code'.2019machine # [ 98.682970] systemd[1]: Failed to start test.service.2020machine # [ 98.987764] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2021machine # [ 98.990272] systemd[1]: Finished Create SUID/SGID Wrappers.2022machine # [ 98.995202] systemd[1]: Starting linger-users.service...2023machine # [ 99.061546] systemd[1]: linger-users.service: Deactivated successfully.2024machine # [ 99.064553] systemd[1]: Finished linger-users.service.2025machine # [ 99.365670] nixos[4304]: switching to system configuration /nix/store/iz8bl4p3m71g63vnvl6zqy1084fhna3y-nixos-system-machine-test failed (status 4)2026machine: (finished: must fail: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&1, in 3.26 seconds)2027machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&12028machine # [ 99.990535] nixos[4447]: switching to system configuration /nix/store/0asv451czl87jqz932gryjjxsqadvhk9-nixos-system-machine-test2029machine # [ 99.993619] systemd[1]: Stopped target Remote File Systems.2030machine # [ 99.995237] systemd[1]: Stopped target Local File Systems.2031machine # [ 100.511358] systemd[1]: Reload requested from client PID 4447 ('.switch-to-conf') (unit backdoor.service)...2032machine # [ 100.513592] systemd[1]: Reloading...2033machine # [ 101.184834] systemd[1]: Reloading finished in 668 ms.2034machine # [ 101.333489] systemd[576]: Reexecution requested from client PID 4516 ('switch-to-confi')...2035machine # [ 101.337520] systemd[576]: Reexecuting.2036machine # [ 101.390632] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2037machine # [ 101.592319] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2038machine # [ 101.600402] systemd[576]: Reload requested from client PID 4516 ('switch-to-confi')...2039machine # [ 101.602696] systemd[576]: Reloading...2040machine # [ 101.797781] systemd[576]: Reloading finished in 193 ms.2041machine # [ 101.812161] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2042machine # [ 101.821338] systemd[576]: Stopped Run user-specific NixOS activation.2043machine # [ 101.823642] systemd[576]: Stopping Run user-specific NixOS activation...2044machine # [ 101.826765] systemd[576]: Starting Run user-specific NixOS activation...2045machine # [ 101.874352] systemd[576]: Finished Run user-specific NixOS activation.2046machine # [ 101.878416] systemd[1]: Stopped target Reactivate sysinit units.2047machine # [ 101.881102] systemd[1]: Stopping Reactivate sysinit units...2048machine # [ 101.882348] systemd[1]: Reached target Reactivate sysinit units.2049machine # [ 101.886482] systemd[1]: Reached target Remote File Systems.2050machine # [ 101.892439] systemd[1]: Started autorestart.service.2051machine # [ 101.895111] systemd[1]: Starting linger-users.service...2052machine # [ 101.900623] systemd[1]: Reached target Local File Systems.2053machine # [ 101.901450] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2054machine # [ 101.914693] systemd[1]: Starting Create SUID/SGID Wrappers...2055machine # [ 101.915607] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2056machine # [ 101.925821] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672057machine # [ 101.930166] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2058machine # [ 101.932738] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2059machine # [ 101.935266] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2060machine # [ 101.938294] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2061machine # [ 101.940347] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2062machine # [ 101.973388] systemd[1]: autorestart.service: Deactivated successfully.2063machine # [ 102.007189] systemd[1]: linger-users.service: Deactivated successfully.2064machine # [ 102.010418] systemd[1]: Finished linger-users.service.2065machine # [ 102.273325] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2066machine # [ 102.276476] systemd[1]: Finished Create SUID/SGID Wrappers.2067machine # [ 102.535878] nixos[4447]: finished switching to system configuration /nix/store/0asv451czl87jqz932gryjjxsqadvhk9-nixos-system-machine-test2068machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 3.17 seconds)2069machine # [ 102.611092] systemd[1]: Stopped autorestart.service.2070machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&12071machine # [ 103.171495] nixos[4594]: switching to system configuration /nix/store/0asv451czl87jqz932gryjjxsqadvhk9-nixos-system-machine-test2072machine # [ 103.174452] systemd[1]: Stopped target Local File Systems.2073machine # [ 103.177128] systemd[1]: Stopped target Remote File Systems.2074machine # [ 103.622333] systemd[1]: Reload requested from client PID 4594 ('.switch-to-conf') (unit backdoor.service)...2075machine # [ 103.624303] systemd[1]: Reloading...2076machine # [ 104.213005] systemd[1]: Reloading finished in 586 ms.2077machine # [ 104.353973] systemd[576]: Reexecution requested from client PID 4662 ('switch-to-confi')...2078machine # [ 104.357514] systemd[576]: Reexecuting.2079machine # [ 104.409451] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2080machine # [ 104.594381] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2081machine # [ 104.601003] systemd[576]: Reload requested from client PID 4662 ('switch-to-confi')...2082machine # [ 104.603265] systemd[576]: Reloading...2083machine # [ 104.788715] systemd[576]: Reloading finished in 184 ms.2084machine # [ 104.800193] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2085machine # [ 104.809006] systemd[576]: Stopped Run user-specific NixOS activation.2086machine # [ 104.811296] systemd[576]: Stopping Run user-specific NixOS activation...2087machine # [ 104.814026] systemd[576]: Starting Run user-specific NixOS activation...2088machine # [ 104.859346] systemd[576]: Finished Run user-specific NixOS activation.2089machine # [ 104.863701] systemd[1]: Stopped target Reactivate sysinit units.2090machine # [ 104.865980] systemd[1]: Stopping Reactivate sysinit units...2091machine # [ 104.867826] systemd[1]: Reached target Reactivate sysinit units.2092machine # [ 104.872735] systemd[1]: Reached target Local File Systems.2093machine # [ 104.876316] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2094machine # [ 104.880259] systemd[1]: Starting Create SUID/SGID Wrappers...2095machine # [ 104.881122] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2096machine # [ 104.882340] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672097machine # [ 104.895161] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2098machine # [ 104.898649] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2099machine # [ 104.899947] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2100machine # [ 104.901341] systemd[1]: Reached target Remote File Systems.2101machine # [ 104.902131] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2102machine # [ 104.903362] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2103machine # [ 105.225274] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2104machine # [ 105.227457] systemd[1]: Finished Create SUID/SGID Wrappers.2105machine # [ 105.232483] systemd[1]: Started autorestart.service.2106machine # [ 105.236662] systemd[1]: Starting linger-users.service...2107machine # [ 105.282500] systemd[1]: autorestart.service: Deactivated successfully.2108machine # [ 105.310778] systemd[1]: linger-users.service: Deactivated successfully.2109machine # [ 105.313788] systemd[1]: Finished linger-users.service.2110machine # [ 105.573517] nixos[4594]: finished switching to system configuration /nix/store/0asv451czl87jqz932gryjjxsqadvhk9-nixos-system-machine-test2111machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 2.97 seconds)2112machine # [ 105.662056] systemd[1]: Stopped autorestart.service.2113machine: must fail: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&12114machine # [ 106.299635] nixos[4741]: switching to system configuration /nix/store/qiwr0cv2qj9pjmxlfnd38rp609kq9h8i-nixos-system-machine-test2115machine # [ 106.303243] systemd[1]: Stopped target Local File Systems.2116machine # [ 106.304526] systemd[1]: Stopped target Remote File Systems.2117machine # [ 106.814896] systemd[1]: Reload requested from client PID 4741 ('.switch-to-conf') (unit backdoor.service)...2118machine # [ 106.817060] systemd[1]: Reloading...2119machine # [ 107.495298] systemd[1]: Reloading finished in 675 ms.2120machine # [ 107.643016] systemd[576]: Reexecution requested from client PID 4810 ('switch-to-confi')...2121machine # [ 107.645262] systemd[576]: Reexecuting.2122machine # [ 107.701633] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2123machine # [ 107.885986] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2124machine # [ 107.892429] systemd[576]: Reload requested from client PID 4810 ('switch-to-confi')...2125machine # [ 107.895082] systemd[576]: Reloading...2126machine # [ 108.098816] systemd[576]: Reloading finished in 202 ms.2127machine # [ 108.113702] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2128machine # [ 108.122072] systemd[576]: Stopped Run user-specific NixOS activation.2129machine # [ 108.124556] systemd[576]: Stopping Run user-specific NixOS activation...2130machine # [ 108.127588] systemd[576]: Starting Run user-specific NixOS activation...2131machine # [ 108.175453] systemd[576]: Finished Run user-specific NixOS activation.2132machine # [ 108.179814] systemd[1]: Stopped target Reactivate sysinit units.2133machine # [ 108.183489] systemd[1]: Stopping Reactivate sysinit units...2134machine # [ 108.188168] systemd[1]: Reached target Reactivate sysinit units.2135machine # [ 108.189162] systemd[1]: Reached target Remote File Systems.2136machine # [ 108.192205] systemd[1]: Started autorestart.service.2137machine # [ 108.195966] systemd[1]: Starting linger-users.service...2138machine # [ 108.204639] systemd[1]: Reached target Local File Systems.2139machine # [ 108.214675] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2140machine # [ 108.225860] systemd[1]: Starting Create SUID/SGID Wrappers...2141machine # [ 108.226756] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2142machine # [ 108.227960] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672143machine # [ 108.232633] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2144machine # [ 108.243478] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2145machine # [ 108.245857] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2146machine # [ 108.247607] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2147machine # [ 108.250340] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2148machine # [ 108.279664] systemd[1]: autorestart.service: Main process exited, code=exited, status=1/FAILURE2149machine # [ 108.282228] systemd[1]: autorestart.service: Failed with result 'exit-code'.2150machine # [ 108.314835] systemd[1]: linger-users.service: Deactivated successfully.2151machine # [ 108.317744] systemd[1]: Finished linger-users.service.2152machine # [ 108.603274] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2153machine # [ 108.606511] systemd[1]: Finished Create SUID/SGID Wrappers.2154machine # [ 108.921352] nixos[4741]: switching to system configuration /nix/store/qiwr0cv2qj9pjmxlfnd38rp609kq9h8i-nixos-system-machine-test failed (status 4)2155machine: (finished: must fail: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&1, in 3.26 seconds)2156(finished: subtest: failing units, in 15.93 seconds)2157subtest: unit file parser2158machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&12159machine # [ 109.525331] nixos[4884]: switching to system configuration /nix/store/9gkxac46y07mli01920h4isghs5ifx4y-nixos-system-machine-test2160machine # [ 109.527970] systemd[1]: Stopped autorestart.service.2161machine # [ 109.531072] systemd[1]: Stopped target Local File Systems.2162machine # [ 109.532246] systemd[1]: Stopped target Remote File Systems.2163machine # [ 109.980506] systemd[1]: Reload requested from client PID 4884 ('.switch-to-conf') (unit backdoor.service)...2164machine # [ 109.982453] systemd[1]: Reloading...2165machine # [ 110.581896] systemd[1]: Reloading finished in 597 ms.2166machine # [ 110.730486] systemd[576]: Reexecution requested from client PID 4953 ('switch-to-confi')...2167machine # [ 110.731920] systemd[576]: Reexecuting.2168machine # [ 110.794384] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2169machine # [ 110.991502] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2170machine # [ 110.998546] systemd[576]: Reload requested from client PID 4953 ('switch-to-confi')...2171machine # [ 111.001311] systemd[576]: Reloading...2172machine # [ 111.193072] systemd[576]: Reloading finished in 190 ms.2173machine # [ 111.208186] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2174machine # [ 111.218632] systemd[576]: Stopped Run user-specific NixOS activation.2175machine # [ 111.221315] systemd[576]: Stopping Run user-specific NixOS activation...2176machine # [ 111.224583] systemd[576]: Starting Run user-specific NixOS activation...2177machine # [ 111.274319] systemd[576]: Finished Run user-specific NixOS activation.2178machine # [ 111.279157] systemd[1]: Stopped target Reactivate sysinit units.2179machine # [ 111.281297] systemd[1]: Stopping Reactivate sysinit units...2180machine # [ 111.282731] systemd[1]: Reached target Reactivate sysinit units.2181machine # [ 111.286932] systemd[1]: Reached target Local File Systems.2182machine # [ 111.287860] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2183machine # [ 111.293940] systemd[1]: Starting Create SUID/SGID Wrappers...2184machine # [ 111.298044] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2185machine # [ 111.304465] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672186machine # [ 111.306776] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2187machine # [ 111.313521] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2188machine # [ 111.318330] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2189machine # [ 111.319568] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2190machine # [ 111.322126] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2191machine # [ 111.323456] systemd[1]: Reached target Remote File Systems.2192machine # [ 111.640980] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2193machine # [ 111.644437] systemd[1]: Finished Create SUID/SGID Wrappers.2194machine # [ 111.649771] systemd[1]: Starting linger-users.service...2195machine # [ 111.653964] systemd[1]: Starting test.service...2196machine # [ 111.697497] systemd[1]: Finished test.service.2197machine # [ 111.726481] systemd[1]: linger-users.service: Deactivated successfully.2198machine # [ 111.729405] systemd[1]: Finished linger-users.service.2199machine # [ 111.991249] nixos[4884]: finished switching to system configuration /nix/store/9gkxac46y07mli01920h4isghs5ifx4y-nixos-system-machine-test2200machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 3.07 seconds)2201machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&12202machine # [ 112.656485] nixos[5026]: switching to system configuration /nix/store/fyvfy5c4mahcndpr2vvzm500phhp2mmz-nixos-system-machine-test2203machine # [ 112.659986] systemd[1]: Stopped target Local File Systems.2204machine # [ 112.661445] systemd[1]: Stopped target Remote File Systems.2205machine # [ 113.178994] systemd[1]: Reload requested from client PID 5026 ('.switch-to-conf') (unit backdoor.service)...2206machine # [ 113.181353] systemd[1]: Reloading...2207machine # [ 113.866327] systemd[1]: Reloading finished in 682 ms.2208machine # [ 114.032101] systemd[576]: Reexecution requested from client PID 5095 ('switch-to-confi')...2209machine # [ 114.033520] systemd[576]: Reexecuting.2210machine # [ 114.094331] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2211machine # [ 114.300464] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2212machine # [ 114.308114] systemd[576]: Reload requested from client PID 5095 ('switch-to-confi')...2213machine # [ 114.310501] systemd[576]: Reloading...2214machine # [ 114.507015] systemd[576]: Reloading finished in 195 ms.2215machine # [ 114.524207] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2216machine # [ 114.534869] systemd[576]: Stopped Run user-specific NixOS activation.2217machine # [ 114.537666] systemd[576]: Stopping Run user-specific NixOS activation...2218machine # [ 114.540872] systemd[576]: Starting Run user-specific NixOS activation...2219machine # [ 114.590721] systemd[576]: Finished Run user-specific NixOS activation.2220machine # [ 114.595041] systemd[1]: Stopped target Reactivate sysinit units.2221machine # [ 114.596406] systemd[1]: Stopping Reactivate sysinit units...2222machine # [ 114.597216] systemd[1]: Reached target Reactivate sysinit units.2223machine # [ 114.599506] systemd[1]: test.service: Deactivated successfully.2224machine # [ 114.602807] systemd[1]: Stopped test.service.2225machine # [ 114.603553] systemd[1]: Stopping test.service...2226machine # [ 114.605998] systemd[1]: Starting test.service...2227machine # [ 114.644601] systemd[1]: Finished test.service.2228machine # [ 114.647519] systemd[1]: Reached target Local File Systems.2229machine # [ 114.650787] systemd[1]: Reached target Remote File Systems.2230machine # [ 114.653338] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2231machine # [ 114.655727] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2232machine # [ 114.661094] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2233machine # [ 114.668215] systemd[1]: Starting Create SUID/SGID Wrappers...2234machine # [ 114.669127] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2235machine # [ 114.670396] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672236machine # [ 114.675773] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2237machine # [ 114.681783] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2238machine # [ 114.683066] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2239machine # [ 115.006449] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2240machine # [ 115.010871] systemd[1]: Finished Create SUID/SGID Wrappers.2241machine # [ 115.013359] systemd[1]: Starting linger-users.service...2242machine # [ 115.075804] systemd[1]: linger-users.service: Deactivated successfully.2243machine # [ 115.078647] systemd[1]: Finished linger-users.service.2244machine # [ 115.337148] nixos[5026]: finished switching to system configuration /nix/store/fyvfy5c4mahcndpr2vvzm500phhp2mmz-nixos-system-machine-test2245machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&1, in 3.35 seconds)2246machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&12247machine # [ 115.999875] nixos[5168]: switching to system configuration /nix/store/v82swqfawf9fakcy2wmx6pqncjynw5xi-nixos-system-machine-test2248machine # [ 116.003148] systemd[1]: Stopped target Local File Systems.2249machine # [ 116.004504] systemd[1]: Stopped target Remote File Systems.2250machine # [ 116.525708] systemd[1]: Reload requested from client PID 5168 ('.switch-to-conf') (unit backdoor.service)...2251machine # [ 116.527658] systemd[1]: Reloading...2252machine # [ 117.168520] systemd[1]: Reloading finished in 638 ms.2253machine # [ 117.329707] systemd[576]: Reexecution requested from client PID 5237 ('switch-to-confi')...2254machine # [ 117.333789] systemd[576]: Reexecuting.2255machine # [ 117.398356] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2256machine # [ 117.599462] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2257machine # [ 117.607210] systemd[576]: Reload requested from client PID 5237 ('switch-to-confi')...2258machine # [ 117.609872] systemd[576]: Reloading...2259machine # [ 117.798192] systemd[576]: Reloading finished in 187 ms.2260machine # [ 117.812145] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2261machine # [ 117.822005] systemd[576]: Stopped Run user-specific NixOS activation.2262machine # [ 117.824359] systemd[576]: Stopping Run user-specific NixOS activation...2263machine # [ 117.827227] systemd[576]: Starting Run user-specific NixOS activation...2264machine # [ 117.873176] systemd[576]: Finished Run user-specific NixOS activation.2265machine # [ 117.877614] systemd[1]: Stopped target Reactivate sysinit units.2266machine # [ 117.879547] systemd[1]: Stopping Reactivate sysinit units...2267machine # [ 117.881076] systemd[1]: Reached target Reactivate sysinit units.2268machine # [ 117.884388] systemd[1]: test.service: Deactivated successfully.2269machine # [ 117.885299] systemd[1]: Stopped test.service.2270machine # [ 117.885993] systemd[1]: Stopping test.service...2271machine # [ 117.888598] systemd[1]: Starting test.service...2272machine # [ 117.923573] systemd[1]: Finished test.service.2273machine # [ 117.927583] systemd[1]: Reached target Remote File Systems.2274machine # [ 117.932803] systemd[1]: Starting linger-users.service...2275machine # [ 117.936645] systemd[1]: Reached target Local File Systems.2276machine # [ 117.943311] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2277machine # [ 117.950723] systemd[1]: Starting Create SUID/SGID Wrappers...2278machine # [ 117.951637] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2279machine # [ 117.957084] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672280machine # [ 117.964450] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2281machine # [ 117.966113] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2282machine # [ 117.971400] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2283machine # [ 117.974236] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2284machine # [ 117.975831] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2285machine # [ 118.037864] systemd[1]: linger-users.service: Deactivated successfully.2286machine # [ 118.040969] systemd[1]: Finished linger-users.service.2287machine # [ 118.295095] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2288machine # [ 118.298280] systemd[1]: Finished Create SUID/SGID Wrappers.2289machine # [ 118.554880] nixos[5168]: finished switching to system configuration /nix/store/v82swqfawf9fakcy2wmx6pqncjynw5xi-nixos-system-machine-test2290machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&1, in 3.22 seconds)2291machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&12292machine # [ 119.130030] nixos[5310]: switching to system configuration /nix/store/9gkxac46y07mli01920h4isghs5ifx4y-nixos-system-machine-test2293machine # [ 119.133084] systemd[1]: Stopped target Local File Systems.2294machine # [ 119.135637] systemd[1]: Stopped target Remote File Systems.2295machine # [ 119.598502] systemd[1]: Reload requested from client PID 5310 ('.switch-to-conf') (unit backdoor.service)...2296machine # [ 119.600800] systemd[1]: Reloading...2297machine # [ 120.203978] systemd[1]: Reloading finished in 600 ms.2298machine # [ 120.356296] systemd[576]: Reexecution requested from client PID 5379 ('switch-to-confi')...2299machine # [ 120.357788] systemd[576]: Reexecuting.2300machine # [ 120.405902] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2301machine # [ 120.598143] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2302machine # [ 120.605331] systemd[576]: Reload requested from client PID 5379 ('switch-to-confi')...2303machine # [ 120.607972] systemd[576]: Reloading...2304machine # [ 120.800537] systemd[576]: Reloading finished in 191 ms.2305machine # [ 120.816167] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2306machine # [ 120.825727] systemd[576]: Stopped Run user-specific NixOS activation.2307machine # [ 120.828076] systemd[576]: Stopping Run user-specific NixOS activation...2308machine # [ 120.832074] systemd[576]: Starting Run user-specific NixOS activation...2309machine # [ 120.877448] systemd[576]: Finished Run user-specific NixOS activation.2310machine # [ 120.881867] systemd[1]: Stopped target Reactivate sysinit units.2311machine # [ 120.882846] systemd[1]: Stopping Reactivate sysinit units...2312machine # [ 120.883687] systemd[1]: Reached target Reactivate sysinit units.2313machine # [ 120.886387] systemd[1]: test.service: Deactivated successfully.2314machine # [ 120.889574] systemd[1]: Stopped test.service.2315machine # [ 120.890307] systemd[1]: Stopping test.service...2316machine # [ 120.892648] systemd[1]: Starting test.service...2317machine # [ 120.928005] systemd[1]: Finished test.service.2318machine # [ 120.932799] systemd[1]: Reached target Local File Systems.2319machine # [ 120.935846] systemd[1]: Reached target Remote File Systems.2320machine # [ 120.939176] systemd[1]: Starting linger-users.service...2321machine # [ 120.948710] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2322machine # [ 120.954791] systemd[1]: Starting Create SUID/SGID Wrappers...2323machine # [ 120.960957] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2324machine # [ 120.963713] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672325machine # [ 120.973102] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2326machine # [ 120.975595] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2327machine # [ 120.977803] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2328machine # [ 120.979900] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2329machine # [ 120.981884] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2330machine # [ 121.044855] systemd[1]: linger-users.service: Deactivated successfully.2331machine # [ 121.047949] systemd[1]: Finished linger-users.service.2332machine # [ 121.351078] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2333machine # [ 121.354850] systemd[1]: Finished Create SUID/SGID Wrappers.2334machine # [ 121.611900] nixos[5310]: finished switching to system configuration /nix/store/9gkxac46y07mli01920h4isghs5ifx4y-nixos-system-machine-test2335machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 3.06 seconds)2336machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&12337machine # [ 122.302757] nixos[5452]: switching to system configuration /nix/store/xiis9fgyhz467c40fwx73gjyv8qlp645-nixos-system-machine-test2338machine # [ 122.306225] systemd[1]: Stopped target Local File Systems.2339machine # [ 122.307678] systemd[1]: Stopped target Remote File Systems.2340machine # [ 122.832394] systemd[1]: Reload requested from client PID 5452 ('.switch-to-conf') (unit backdoor.service)...2341machine # [ 122.834396] systemd[1]: Reloading...2342machine # [ 123.498581] systemd[1]: Reloading finished in 662 ms.2343machine # [ 123.658606] systemd[576]: Reexecution requested from client PID 5521 ('switch-to-confi')...2344machine # [ 123.662356] systemd[576]: Reexecuting.2345machine # [ 123.718024] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2346machine # [ 123.925701] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2347machine # [ 123.931979] systemd[576]: Reload requested from client PID 5521 ('switch-to-confi')...2348machine # [ 123.934436] systemd[576]: Reloading...2349machine # [ 124.132098] systemd[576]: Reloading finished in 196 ms.2350machine # [ 124.148162] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2351machine # [ 124.158264] systemd[576]: Stopped Run user-specific NixOS activation.2352machine # [ 124.159309] systemd[576]: Stopping Run user-specific NixOS activation...2353machine # [ 124.162271] systemd[576]: Starting Run user-specific NixOS activation...2354machine # [ 124.209661] systemd[576]: Finished Run user-specific NixOS activation.2355machine # [ 124.214105] systemd[1]: Stopped target Reactivate sysinit units.2356machine # [ 124.217006] systemd[1]: Stopping Reactivate sysinit units...2357machine # [ 124.217919] systemd[1]: Reached target Reactivate sysinit units.2358machine # [ 124.222876] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2359machine # [ 124.225708] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2360machine # [ 124.229900] systemd[1]: Reached target Local File Systems.2361machine # [ 124.232865] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2362machine # [ 124.238373] systemd[1]: Starting Create SUID/SGID Wrappers...2363machine # [ 124.239261] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2364machine # [ 124.242050] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672365machine # [ 124.247743] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2366machine # [ 124.249725] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2367machine # [ 124.253446] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2368machine # [ 124.254703] systemd[1]: Reached target Remote File Systems.2369machine # [ 124.569561] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2370machine # [ 124.571733] systemd[1]: Finished Create SUID/SGID Wrappers.2371machine # [ 124.576677] systemd[1]: Starting linger-users.service...2372machine # [ 124.633415] systemd[1]: linger-users.service: Deactivated successfully.2373machine # [ 124.636349] systemd[1]: Finished linger-users.service.2374machine # [ 124.894694] nixos[5452]: finished switching to system configuration /nix/store/xiis9fgyhz467c40fwx73gjyv8qlp645-nixos-system-machine-test2375machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&1, in 3.28 seconds)2376machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&12377machine # [ 125.525139] nixos[5592]: switching to system configuration /nix/store/9k2qjvnf60d4dp1y4k9ymcb4y42j274h-nixos-system-machine-test2378machine # [ 125.528562] systemd[1]: Stopped target Local File Systems.2379machine # [ 125.530025] systemd[1]: Stopped target Remote File Systems.2380machine # [ 126.045851] systemd[1]: Reload requested from client PID 5592 ('.switch-to-conf') (unit backdoor.service)...2381machine # [ 126.047723] systemd[1]: Reloading...2382machine # [ 126.705602] systemd[1]: Reloading finished in 655 ms.2383machine # [ 126.848782] systemd[576]: Reexecution requested from client PID 5661 ('switch-to-confi')...2384machine # [ 126.852133] systemd[576]: Reexecuting.2385machine # [ 126.905468] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2386machine # [ 127.092688] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2387machine # [ 127.099835] systemd[576]: Reload requested from client PID 5661 ('switch-to-confi')...2388machine # [ 127.102584] systemd[576]: Reloading...2389machine # [ 127.292765] systemd[576]: Reloading finished in 189 ms.2390machine # [ 127.308184] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2391machine # [ 127.317385] systemd[576]: Stopped Run user-specific NixOS activation.2392machine # [ 127.319798] systemd[576]: Stopping Run user-specific NixOS activation...2393machine # [ 127.322799] systemd[576]: Starting Run user-specific NixOS activation...2394machine # [ 127.371801] systemd[576]: Finished Run user-specific NixOS activation.2395machine # [ 127.376344] systemd[1]: Stopped target Reactivate sysinit units.2396machine # [ 127.378376] systemd[1]: Stopping Reactivate sysinit units...2397machine # [ 127.379754] systemd[1]: Reached target Reactivate sysinit units.2398machine # [ 127.383570] systemd[1]: test.service: Deactivated successfully.2399machine # [ 127.384633] systemd[1]: Stopped test.service.2400machine # [ 127.385634] systemd[1]: Stopping test.service...2401machine # [ 127.387647] systemd[1]: Starting test.service...2402machine # [ 127.424122] systemd[1]: Finished test.service.2403machine # [ 127.428857] systemd[1]: Reached target Local File Systems.2404machine # [ 127.432615] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2405machine # [ 127.436435] systemd[1]: Starting Create SUID/SGID Wrappers...2406machine # [ 127.437337] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2407machine # [ 127.438561] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672408machine # [ 127.449107] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2409machine # [ 127.450773] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2410machine # [ 127.456251] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2411machine # [ 127.457528] systemd[1]: Reached target Remote File Systems.2412machine # [ 127.458305] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2413machine # [ 127.459562] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2414machine # [ 127.796469] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2415machine # [ 127.800209] systemd[1]: Finished Create SUID/SGID Wrappers.2416machine # [ 127.805068] systemd[1]: Starting linger-users.service...2417machine # [ 127.869360] systemd[1]: linger-users.service: Deactivated successfully.2418machine # [ 127.872592] systemd[1]: Finished linger-users.service.2419machine # [ 128.130921] nixos[5592]: finished switching to system configuration /nix/store/9k2qjvnf60d4dp1y4k9ymcb4y42j274h-nixos-system-machine-test2420machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&1, in 3.24 seconds)2421machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&12422machine # [ 128.782256] nixos[5734]: switching to system configuration /nix/store/zc8pmq6bxsaxq82zi3n17p8lbnai1kfy-nixos-system-machine-test2423machine # [ 128.784692] systemd[1]: Stopped target Remote File Systems.2424machine # [ 128.786748] systemd[1]: Stopped target Local File Systems.2425machine # [ 129.310568] systemd[1]: Reload requested from client PID 5734 ('.switch-to-conf') (unit backdoor.service)...2426machine # [ 129.312684] systemd[1]: Reloading...2427machine # [ 129.976592] systemd[1]: Reloading finished in 661 ms.2428machine # [ 130.117900] systemd[576]: Reexecution requested from client PID 5803 ('switch-to-confi')...2429machine # [ 130.121664] systemd[576]: Reexecuting.2430machine # [ 130.186016] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2431machine # [ 130.379767] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2432machine # [ 130.387387] systemd[576]: Reload requested from client PID 5803 ('switch-to-confi')...2433machine # [ 130.390594] systemd[576]: Reloading...2434machine # [ 130.578272] systemd[576]: Reloading finished in 188 ms.2435machine # [ 130.592212] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2436machine # [ 130.603738] systemd[576]: Stopped Run user-specific NixOS activation.2437machine # [ 130.604893] systemd[576]: Stopping Run user-specific NixOS activation...2438machine # [ 130.608076] systemd[576]: Starting Run user-specific NixOS activation...2439machine # [ 130.655096] systemd[576]: Finished Run user-specific NixOS activation.2440machine # [ 130.659681] systemd[1]: Stopped target Reactivate sysinit units.2441machine # [ 130.662171] systemd[1]: Stopping Reactivate sysinit units...2442machine # [ 130.663041] systemd[1]: Reached target Reactivate sysinit units.2443machine # [ 130.666541] systemd[1]: test.service: Deactivated successfully.2444machine # [ 130.667464] systemd[1]: Stopped test.service.2445machine # [ 130.668463] systemd[1]: Stopping test.service...2446machine # [ 130.670708] systemd[1]: Starting test.service...2447machine # [ 130.707131] systemd[1]: Finished test.service.2448machine # [ 130.712398] systemd[1]: Reached target Remote File Systems.2449machine # [ 130.716946] systemd[1]: Starting linger-users.service...2450machine # [ 130.727967] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2451machine # [ 130.731970] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2452machine # [ 130.733885] systemd[1]: Reached target Local File Systems.2453machine # [ 130.735800] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2454machine # [ 130.744389] systemd[1]: Starting Create SUID/SGID Wrappers...2455machine # [ 130.745304] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2456machine # [ 130.746559] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672457machine # [ 130.749156] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2458machine # [ 130.751272] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2459machine # [ 130.753399] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2460machine # [ 130.814312] systemd[1]: linger-users.service: Deactivated successfully.2461machine # [ 130.816079] systemd[1]: Finished linger-users.service.2462machine # [ 131.086091] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2463machine # [ 131.089598] systemd[1]: Finished Create SUID/SGID Wrappers.2464machine # [ 131.346676] nixos[5734]: finished switching to system configuration /nix/store/zc8pmq6bxsaxq82zi3n17p8lbnai1kfy-nixos-system-machine-test2465machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&1, in 3.22 seconds)2466machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&12467machine # [ 131.994679] nixos[5876]: switching to system configuration /nix/store/05db6mfpf162d1z392dxdf2nl0llgvnp-nixos-system-machine-test2468machine # [ 131.998228] systemd[1]: Stopped target Local File Systems.2469machine # [ 131.999684] systemd[1]: Stopped target Remote File Systems.2470machine # [ 132.530952] systemd[1]: Reload requested from client PID 5876 ('.switch-to-conf') (unit backdoor.service)...2471machine # [ 132.533203] systemd[1]: Reloading...2472machine # [ 133.214875] systemd[1]: Reloading finished in 679 ms.2473machine # [ 133.367880] systemd[576]: Reexecution requested from client PID 5945 ('switch-to-confi')...2474machine # [ 133.371832] systemd[576]: Reexecuting.2475machine # [ 133.425694] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2476machine # [ 133.615921] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2477machine # [ 133.622535] systemd[576]: Reload requested from client PID 5945 ('switch-to-confi')...2478machine # [ 133.624969] systemd[576]: Reloading...2479machine # [ 133.816519] systemd[576]: Reloading finished in 190 ms.2480machine # [ 133.832160] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2481machine # [ 133.841353] systemd[576]: Stopped Run user-specific NixOS activation.2482machine # [ 133.843830] systemd[576]: Stopping Run user-specific NixOS activation...2483machine # [ 133.846787] systemd[576]: Starting Run user-specific NixOS activation...2484machine # [ 133.898031] systemd[576]: Finished Run user-specific NixOS activation.2485machine # [ 133.903119] systemd[1]: Stopped target Reactivate sysinit units.2486machine # [ 133.905242] systemd[1]: Stopping Reactivate sysinit units...2487machine # [ 133.906142] systemd[1]: Reached target Reactivate sysinit units.2488machine # [ 133.908809] systemd[1]: test.service: Deactivated successfully.2489machine # [ 133.912347] systemd[1]: Stopped test.service.2490machine # [ 133.913563] systemd[1]: Stopping test.service...2491machine # [ 133.915703] systemd[1]: Starting test.service...2492machine # [ 133.952657] systemd[1]: Finished test.service.2493machine # [ 133.958235] systemd[1]: Reached target Remote File Systems.2494machine # [ 133.965484] systemd[1]: Starting linger-users.service...2495machine # [ 133.969398] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2496machine # [ 133.980561] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2497machine # [ 133.981978] systemd[1]: Reached target Local File Systems.2498machine # [ 133.982768] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2499machine # [ 133.989167] systemd[1]: Starting Create SUID/SGID Wrappers...2500machine # [ 133.998932] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2501machine # [ 134.003232] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672502machine # [ 134.007597] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2503machine # [ 134.010476] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2504machine # [ 134.012642] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2505machine # [ 134.064099] systemd[1]: linger-users.service: Deactivated successfully.2506machine # [ 134.066865] systemd[1]: Finished linger-users.service.2507machine # [ 134.324566] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2508machine # [ 134.328005] systemd[1]: Finished Create SUID/SGID Wrappers.2509machine # [ 134.582394] nixos[5876]: finished switching to system configuration /nix/store/05db6mfpf162d1z392dxdf2nl0llgvnp-nixos-system-machine-test2510machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&1, in 3.23 seconds)2511machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&12512machine # [ 135.179640] nixos[6018]: switching to system configuration /nix/store/9gkxac46y07mli01920h4isghs5ifx4y-nixos-system-machine-test2513machine # [ 135.182194] systemd[1]: Stopped target Local File Systems.2514machine # [ 135.184572] systemd[1]: Stopped target Remote File Systems.2515machine # [ 135.642482] systemd[1]: Reload requested from client PID 6018 ('.switch-to-conf') (unit backdoor.service)...2516machine # [ 135.644530] systemd[1]: Reloading...2517machine # [ 136.248919] systemd[1]: Reloading finished in 602 ms.2518machine # [ 136.394824] systemd[576]: Reexecution requested from client PID 6087 ('switch-to-confi')...2519machine # [ 136.398569] systemd[576]: Reexecuting.2520machine # [ 136.453857] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2521machine # [ 136.646733] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2522machine # [ 136.653413] systemd[576]: Reload requested from client PID 6087 ('switch-to-confi')...2523machine # [ 136.655939] systemd[576]: Reloading...2524machine # [ 136.845960] systemd[576]: Reloading finished in 188 ms.2525machine # [ 136.860173] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2526machine # [ 136.870705] systemd[576]: Stopped Run user-specific NixOS activation.2527machine # [ 136.871791] systemd[576]: Stopping Run user-specific NixOS activation...2528machine # [ 136.875517] systemd[576]: Starting Run user-specific NixOS activation...2529machine # [ 136.922172] systemd[576]: Finished Run user-specific NixOS activation.2530machine # [ 136.926589] systemd[1]: Stopped target Reactivate sysinit units.2531machine # [ 136.928512] systemd[1]: Stopping Reactivate sysinit units...2532machine # [ 136.932820] systemd[1]: Reached target Reactivate sysinit units.2533machine # [ 136.934887] systemd[1]: test.service: Deactivated successfully.2534machine # [ 136.935763] systemd[1]: Stopped test.service.2535machine # [ 136.936696] systemd[1]: Stopping test.service...2536machine # [ 136.937700] systemd[1]: Starting test.service...2537machine # [ 136.972002] systemd[1]: Finished test.service.2538machine # [ 136.975658] systemd[1]: Reached target Remote File Systems.2539machine # [ 136.980753] systemd[1]: Starting linger-users.service...2540machine # [ 136.988720] systemd[1]: Reached target Local File Systems.2541machine # [ 136.991357] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2542machine # [ 137.002035] systemd[1]: Starting Create SUID/SGID Wrappers...2543machine # [ 137.002942] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2544machine # [ 137.008812] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672545machine # [ 137.016496] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2546machine # [ 137.018112] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2547machine # [ 137.019333] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2548machine # [ 137.023633] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2549machine # [ 137.025897] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2550machine # [ 137.076751] systemd[1]: linger-users.service: Deactivated successfully.2551machine # [ 137.079341] systemd[1]: Finished linger-users.service.2552machine # [ 137.355885] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2553machine # [ 137.359474] systemd[1]: Finished Create SUID/SGID Wrappers.2554machine # [ 137.618010] nixos[6018]: finished switching to system configuration /nix/store/9gkxac46y07mli01920h4isghs5ifx4y-nixos-system-machine-test2555machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 3.04 seconds)2556machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&12557machine # [ 138.287792] nixos[6160]: switching to system configuration /nix/store/caw4xcr60iavi3w6j9gc9k5hfkhx3ncv-nixos-system-machine-test2558machine # [ 138.291314] systemd[1]: Stopped target Local File Systems.2559machine # [ 138.292616] systemd[1]: Stopped target Remote File Systems.2560machine # [ 138.807342] systemd[1]: Reload requested from client PID 6160 ('.switch-to-conf') (unit backdoor.service)...2561machine # [ 138.809560] systemd[1]: Reloading...2562machine # [ 139.459144] systemd[1]: Reloading finished in 647 ms.2563machine # [ 139.606248] systemd[576]: Reexecution requested from client PID 6229 ('switch-to-confi')...2564machine # [ 139.610013] systemd[576]: Reexecuting.2565machine # [ 139.665476] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2566machine # [ 139.854078] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2567machine # [ 139.861275] systemd[576]: Reload requested from client PID 6229 ('switch-to-confi')...2568machine # [ 139.863795] systemd[576]: Reloading...2569machine # [ 140.053203] systemd[576]: Reloading finished in 188 ms.2570machine # [ 140.068159] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2571machine # [ 140.078182] systemd[576]: Stopped Run user-specific NixOS activation.2572machine # [ 140.080569] systemd[576]: Stopping Run user-specific NixOS activation...2573machine # [ 140.083456] systemd[576]: Starting Run user-specific NixOS activation...2574machine # [ 140.128894] systemd[576]: Finished Run user-specific NixOS activation.2575machine # [ 140.133187] systemd[1]: Stopped target Reactivate sysinit units.2576machine # [ 140.136261] systemd[1]: Stopping Reactivate sysinit units...2577machine # [ 140.137105] systemd[1]: Reached target Reactivate sysinit units.2578machine # [ 140.140877] systemd[1]: Reloading test.service...2579machine # [ 140.174492] systemd[1]: Reloaded test.service.2580machine # [ 140.178169] systemd[1]: Reached target Remote File Systems.2581machine # [ 140.180663] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2582machine # [ 140.184868] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2583machine # [ 140.186520] systemd[1]: Reached target Local File Systems.2584machine # [ 140.191907] systemd[1]: Starting linger-users.service...2585machine # [ 140.202202] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2586machine # [ 140.215141] systemd[1]: Starting Create SUID/SGID Wrappers...2587machine # [ 140.217522] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2588machine # [ 140.219370] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672589machine # [ 140.222484] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2590machine # [ 140.224736] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2591machine # [ 140.225976] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2592machine # [ 140.285476] systemd[1]: linger-users.service: Deactivated successfully.2593machine # [ 140.287064] systemd[1]: Finished linger-users.service.2594machine # [ 140.560133] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2595machine # [ 140.563298] systemd[1]: Finished Create SUID/SGID Wrappers.2596machine # [ 140.820293] nixos[6160]: finished switching to system configuration /nix/store/caw4xcr60iavi3w6j9gc9k5hfkhx3ncv-nixos-system-machine-test2597machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 3.20 seconds)2598machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&12599machine # [ 141.457564] nixos[6302]: switching to system configuration /nix/store/y3sclajmdwbgnp2di31z4aapphvzvcgk-nixos-system-machine-test2600machine # [ 141.460578] systemd[1]: Stopped target Local File Systems.2601machine # [ 141.463227] systemd[1]: Stopped target Remote File Systems.2602machine # [ 142.013195] systemd[1]: Reload requested from client PID 6302 ('.switch-to-conf') (unit backdoor.service)...2603machine # [ 142.015266] systemd[1]: Reloading...2604machine # [ 142.774223] systemd[1]: Reloading finished in 756 ms.2605machine # [ 142.944558] systemd[576]: Reexecution requested from client PID 6371 ('switch-to-confi')...2606machine # [ 142.946136] systemd[576]: Reexecuting.2607machine # [ 143.003667] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2608machine # [ 143.227312] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2609machine # [ 143.234525] systemd[576]: Reload requested from client PID 6371 ('switch-to-confi')...2610machine # [ 143.237514] systemd[576]: Reloading...2611machine # [ 143.451922] systemd[576]: Reloading finished in 213 ms.2612machine # [ 143.464194] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2613machine # [ 143.475299] systemd[576]: Stopped Run user-specific NixOS activation.2614machine # [ 143.478387] systemd[576]: Stopping Run user-specific NixOS activation...2615machine # [ 143.482629] systemd[576]: Starting Run user-specific NixOS activation...2616machine # [ 143.534592] systemd[576]: Finished Run user-specific NixOS activation.2617machine # [ 143.539999] systemd[1]: Stopped target Reactivate sysinit units.2618machine # [ 143.542074] systemd[1]: Stopping Reactivate sysinit units...2619machine # [ 143.542914] systemd[1]: Reached target Reactivate sysinit units.2620machine # [ 143.549168] systemd[1]: Reloading test.service...2621machine # [ 143.589530] systemd[1]: Reloaded test.service.2622machine # [ 143.593470] systemd[1]: Reached target Local File Systems.2623machine # [ 143.595678] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2624machine # [ 143.598876] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2625machine # [ 143.601785] systemd[1]: Reached target Remote File Systems.2626machine # [ 143.610366] systemd[1]: Starting linger-users.service...2627machine # [ 143.622264] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2628machine # [ 143.627005] systemd[1]: Starting Create SUID/SGID Wrappers...2629machine # [ 143.627938] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2630machine # [ 143.629348] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672631machine # [ 143.631907] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2632machine # [ 143.639797] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2633machine # [ 143.641567] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2634machine # [ 143.705260] systemd[1]: linger-users.service: Deactivated successfully.2635machine # [ 143.709398] systemd[1]: Finished linger-users.service.2636machine # [ 144.000087] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2637machine # [ 144.002872] systemd[1]: Finished Create SUID/SGID Wrappers.2638machine # [ 144.262374] nixos[6302]: finished switching to system configuration /nix/store/y3sclajmdwbgnp2di31z4aapphvzvcgk-nixos-system-machine-test2639machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&1, in 3.44 seconds)2640machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&12641machine # [ 144.906260] nixos[6444]: switching to system configuration /nix/store/564sp6k0d6y9csq3wm5wximvzn6rjffk-nixos-system-machine-test2642machine # [ 144.910283] systemd[1]: Stopped target Local File Systems.2643machine # [ 144.911866] systemd[1]: Stopped target Remote File Systems.2644machine # [ 145.453832] systemd[1]: Reload requested from client PID 6444 ('.switch-to-conf') (unit backdoor.service)...2645machine # [ 145.456304] systemd[1]: Reloading...2646machine # [ 146.166107] systemd[1]: Reloading finished in 707 ms.2647machine # [ 146.321378] systemd[576]: Reexecution requested from client PID 6513 ('switch-to-confi')...2648machine # [ 146.325587] systemd[576]: Reexecuting.2649machine # [ 146.374130] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2650machine # [ 146.577587] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2651machine # [ 146.586840] systemd[576]: Reload requested from client PID 6513 ('switch-to-confi')...2652machine # [ 146.589344] systemd[576]: Reloading...2653machine # [ 146.786156] systemd[576]: Reloading finished in 193 ms.2654machine # [ 146.801695] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2655machine # [ 146.810616] systemd[576]: Stopped Run user-specific NixOS activation.2656machine # [ 146.813259] systemd[576]: Stopping Run user-specific NixOS activation...2657machine # [ 146.816392] systemd[576]: Starting Run user-specific NixOS activation...2658machine # [ 146.865694] systemd[576]: Finished Run user-specific NixOS activation.2659machine # [ 146.869919] systemd[1]: Stopped target Reactivate sysinit units.2660machine # [ 146.871891] systemd[1]: Stopping Reactivate sysinit units...2661machine # [ 146.873406] systemd[1]: Reached target Reactivate sysinit units.2662machine # [ 146.876556] systemd[1]: test.service: Deactivated successfully.2663machine # [ 146.877513] systemd[1]: Stopped test.service.2664machine # [ 146.878143] systemd[1]: Stopping test.service...2665machine # [ 146.881401] systemd[1]: Starting test.service...2666machine # [ 146.915698] systemd[1]: Finished test.service.2667machine # [ 146.920997] systemd[1]: Reached target Local File Systems.2668machine # [ 146.924499] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2669machine # [ 146.929131] systemd[1]: Starting Create SUID/SGID Wrappers...2670machine # [ 146.930043] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2671machine # [ 146.931261] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672672machine # [ 146.934453] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2673machine # [ 146.947604] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2674machine # [ 146.952104] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2675machine # [ 146.953335] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2676machine # [ 146.954611] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2677machine # [ 146.955860] systemd[1]: Reached target Remote File Systems.2678machine # [ 147.294252] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2679machine # [ 147.299084] systemd[1]: Finished Create SUID/SGID Wrappers.2680machine # [ 147.302320] systemd[1]: Starting linger-users.service...2681machine # [ 147.370830] systemd[1]: linger-users.service: Deactivated successfully.2682machine # [ 147.373737] systemd[1]: Finished linger-users.service.2683machine # [ 147.628645] nixos[6444]: finished switching to system configuration /nix/store/564sp6k0d6y9csq3wm5wximvzn6rjffk-nixos-system-machine-test2684machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&1, in 3.36 seconds)2685machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&12686machine # [ 148.222663] nixos[6586]: switching to system configuration /nix/store/9k2qjvnf60d4dp1y4k9ymcb4y42j274h-nixos-system-machine-test2687machine # [ 148.225246] systemd[1]: Stopped target Remote File Systems.2688machine # [ 148.227679] systemd[1]: Stopped target Local File Systems.2689machine # [ 148.699600] systemd[1]: Reload requested from client PID 6586 ('.switch-to-conf') (unit backdoor.service)...2690machine # [ 148.701947] systemd[1]: Reloading...2691machine # [ 149.309373] systemd[1]: Reloading finished in 605 ms.2692machine # [ 149.459011] systemd[576]: Reexecution requested from client PID 6655 ('switch-to-confi')...2693machine # [ 149.461448] systemd[576]: Reexecuting.2694machine # [ 149.530396] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2695machine # [ 149.736259] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2696machine # [ 149.743062] systemd[576]: Reload requested from client PID 6655 ('switch-to-confi')...2697machine # [ 149.745739] systemd[576]: Reloading...2698machine # [ 149.933135] systemd[576]: Reloading finished in 186 ms.2699machine # [ 149.948180] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2700machine # [ 149.957855] systemd[576]: Stopped Run user-specific NixOS activation.2701machine # [ 149.960145] systemd[576]: Stopping Run user-specific NixOS activation...2702machine # [ 149.963424] systemd[576]: Starting Run user-specific NixOS activation...2703machine # [ 150.011750] systemd[576]: Finished Run user-specific NixOS activation.2704machine # [ 150.016902] systemd[1]: Stopped target Reactivate sysinit units.2705machine # [ 150.017883] systemd[1]: Stopping Reactivate sysinit units...2706machine # [ 150.018676] systemd[1]: Reached target Reactivate sysinit units.2707machine # [ 150.022145] systemd[1]: Reached target Remote File Systems.2708machine # [ 150.027855] systemd[1]: Starting linger-users.service...2709machine # [ 150.038987] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2710machine # [ 150.043081] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2711machine # [ 150.049946] systemd[1]: Reached target Local File Systems.2712machine # [ 150.050868] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2713machine # [ 150.056812] systemd[1]: Starting Create SUID/SGID Wrappers...2714machine # [ 150.057718] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2715machine # [ 150.058906] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672716machine # [ 150.068814] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2717machine # [ 150.070984] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2718machine # [ 150.072803] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2719machine # [ 150.134349] systemd[1]: linger-users.service: Deactivated successfully.2720machine # [ 150.137844] systemd[1]: Finished linger-users.service.2721machine # [ 150.422548] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2722machine # [ 150.426256] systemd[1]: Finished Create SUID/SGID Wrappers.2723machine # [ 150.683313] nixos[6586]: finished switching to system configuration /nix/store/9k2qjvnf60d4dp1y4k9ymcb4y42j274h-nixos-system-machine-test2724machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&1, in 3.06 seconds)2725(finished: subtest: unit file parser, in 41.76 seconds)2726subtest: restart and reload by activation script2727machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&12728machine # [ 151.303030] nixos[6726]: switching to system configuration /nix/store/c3q2ln63wnh9sc1xkp6590g4lgrv1mnn-nixos-system-machine-test2729machine # [ 151.305923] systemd[1]: Stopped target Local File Systems.2730machine # [ 151.308319] systemd[1]: Stopped target Remote File Systems.2731machine # [ 151.761335] systemd[1]: Reload requested from client PID 6726 ('.switch-to-conf') (unit backdoor.service)...2732machine # [ 151.763266] systemd[1]: Reloading...2733machine # [ 152.361818] systemd[1]: Reloading finished in 596 ms.2734machine # [ 152.488428] systemd[576]: Reexecution requested from client PID 6795 ('switch-to-confi')...2735machine # [ 152.492122] systemd[576]: Reexecuting.2736machine # [ 152.550074] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2737machine # [ 152.749650] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2738machine # [ 152.756355] systemd[576]: Reload requested from client PID 6795 ('switch-to-confi')...2739machine # [ 152.758917] systemd[576]: Reloading...2740machine # [ 152.951265] systemd[576]: Reloading finished in 190 ms.2741machine # [ 152.964170] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2742machine # [ 152.974359] systemd[576]: Stopped Run user-specific NixOS activation.2743machine # [ 152.977017] systemd[576]: Stopping Run user-specific NixOS activation...2744machine # [ 152.979967] systemd[576]: Starting Run user-specific NixOS activation...2745machine # [ 153.026873] systemd[576]: Finished Run user-specific NixOS activation.2746machine # [ 153.031447] systemd[1]: Stopped target Reactivate sysinit units.2747machine # [ 153.032921] systemd[1]: Stopping Reactivate sysinit units...2748machine # [ 153.033812] systemd[1]: Reached target Reactivate sysinit units.2749machine # [ 153.037676] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2750machine # [ 153.041399] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2751machine # [ 153.045791] systemd[1]: Reached target Remote File Systems.2752machine # [ 153.051640] systemd[1]: Starting linger-users.service...2753machine # [ 153.055916] systemd[1]: Reached target Local File Systems.2754machine # [ 153.066452] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2755machine # [ 153.074329] systemd[1]: Starting Create SUID/SGID Wrappers...2756machine # [ 153.075240] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2757machine # [ 153.078508] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672758machine # [ 153.081606] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2759machine # [ 153.088460] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2760machine # [ 153.089867] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2761machine # [ 153.156710] systemd[1]: linger-users.service: Deactivated successfully.2762machine # [ 153.160278] systemd[1]: Finished linger-users.service.2763machine # [ 153.441530] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2764machine # [ 153.445011] systemd[1]: Finished Create SUID/SGID Wrappers.2765machine # [ 153.702027] nixos[6726]: finished switching to system configuration /nix/store/c3q2ln63wnh9sc1xkp6590g4lgrv1mnn-nixos-system-machine-test2766machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 3.02 seconds)2767machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&12768machine # [ 154.380371] nixos[6866]: switching to system configuration /nix/store/y2nc7375x7m7c30zw3hy1r3cqbadl2nc-nixos-system-machine-test2769machine # [ 154.384392] systemd[1]: Stopped target Local File Systems.2770machine # [ 154.386979] systemd[1]: Stopped target Remote File Systems.2771machine # [ 154.388121] systemd[1]: test.service: Deactivated successfully.2772machine # [ 154.388976] systemd[1]: Stopped test.service.2773machine # [ 154.988779] systemd[1]: Reload requested from client PID 6866 ('.switch-to-conf') (unit backdoor.service)...2774machine # [ 154.990908] systemd[1]: Reloading...2775machine # [ 155.711805] systemd[1]: Reloading finished in 718 ms.2776machine # [ 155.858143] systemd[576]: Reexecution requested from client PID 6937 ('switch-to-confi')...2777machine # [ 155.862044] systemd[576]: Reexecuting.2778machine # [ 155.928744] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2779machine # [ 156.128694] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2780machine # [ 156.136289] systemd[576]: Reload requested from client PID 6937 ('switch-to-confi')...2781machine # [ 156.138759] systemd[576]: Reloading...2782machine # [ 156.336550] systemd[576]: Reloading finished in 196 ms.2783machine # [ 156.352170] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2784machine # [ 156.361682] systemd[576]: Stopped Run user-specific NixOS activation.2785machine # [ 156.364127] systemd[576]: Stopping Run user-specific NixOS activation...2786machine # [ 156.367068] systemd[576]: Starting Run user-specific NixOS activation...2787machine # [ 156.414293] systemd[576]: Finished Run user-specific NixOS activation.2788machine # [ 156.419120] systemd[1]: Stopped target Reactivate sysinit units.2789machine # [ 156.420645] systemd[1]: Stopping Reactivate sysinit units...2790machine # [ 156.421514] systemd[1]: Reached target Reactivate sysinit units.2791machine # [ 156.429997] systemd[1]: Created slice Slice /system/templated-reload-triggers.2792machine # [ 156.432565] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart.2793machine # [ 156.435961] systemd[1]: Reached target Remote File Systems.2794machine # [ 156.438580] systemd[1]: Starting linger-users.service...2795machine # [ 156.444419] systemd[1]: Starting reload-triggers-and-restart.service...2796machine # [ 156.448399] systemd[1]: Starting reload-triggers.service...2797machine # [ 156.455059] systemd[1]: Starting simple-restart-service.service...2798machine # [ 156.463363] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service...2799machine # [ 156.478601] systemd[1]: Starting templated-reload-triggers@instance.service...2800machine # [ 156.500642] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart-by-as.2801machine # [ 156.512279] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service...2802machine # [ 156.529981] systemd[1]: Starting reload-triggers-and-restart-by-as.service...2803machine # [ 156.548423] systemd[1]: Created slice Slice /system/templated-simple-service.2804machine # [ 156.563740] systemd[1]: Starting templated-simple-service@instance.service...2805machine # [ 156.584275] systemd[1]: Created slice Slice /system/templated-simple-reload-service.2806machine # [ 156.593405] systemd[1]: Starting templated-simple-reload-service@instance.service...2807machine # [ 156.619846] systemd[1]: Created slice Slice /system/templated-no-restart-service.2808machine # [ 156.645775] systemd[1]: Starting templated-no-restart-service@instance.service...2809machine # [ 156.668346] systemd[1]: Reached target Local File Systems.2810machine # [ 156.669407] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2811machine # [ 156.670964] systemd[1]: Starting Create SUID/SGID Wrappers...2812machine # [ 156.671752] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2813machine # [ 156.693941] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672814machine # [ 156.713070] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2815machine # [ 156.734193] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2816machine # [ 156.751896] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2817machine # [ 156.762794] systemd[1]: Finished reload-triggers.service.2818machine # [ 156.766526] systemd[1]: Finished reload-triggers-and-restart.service.2819machine # [ 156.767444] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service.2820machine # [ 156.772909] systemd[1]: Finished simple-restart-service.service.2821machine # [ 156.774295] systemd[1]: Finished templated-reload-triggers@instance.service.2822machine # [ 156.776671] systemd[1]: Created slice Slice /system/templated-simple-restart-service.2823machine # [ 156.778180] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2824machine # [ 156.780804] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2825machine # [ 156.782601] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service.2826machine # [ 156.787116] systemd[1]: Finished reload-triggers-and-restart-by-as.service.2827machine # [ 156.790544] systemd[1]: Finished templated-simple-service@instance.service.2828machine # [ 156.793411] systemd[1]: Finished templated-simple-reload-service@instance.service.2829machine # [ 156.795562] systemd[1]: Finished templated-no-restart-service@instance.service.2830machine # [ 156.805449] systemd[1]: linger-users.service: Deactivated successfully.2831machine # [ 156.807125] systemd[1]: Finished linger-users.service.2832machine # [ 157.098387] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2833machine # [ 157.103085] systemd[1]: Finished Create SUID/SGID Wrappers.2834machine # [ 157.106862] systemd[1]: Starting no-restart-service.service...2835machine # [ 157.111024] systemd[1]: Starting simple-reload-service.service...2836machine # [ 157.115109] systemd[1]: Starting simple-service.service...2837machine # [ 157.122370] systemd[1]: Starting templated-simple-restart-service@instance.service...2838machine # [ 157.180097] systemd[1]: Finished no-restart-service.service.2839machine # [ 157.184293] systemd[1]: Finished simple-reload-service.service.2840machine # [ 157.185252] systemd[1]: Finished simple-service.service.2841machine # [ 157.185993] systemd[1]: Finished templated-simple-restart-service@instance.service.2842machine # [ 157.448667] nixos[6866]: finished switching to system configuration /nix/store/y2nc7375x7m7c30zw3hy1r3cqbadl2nc-nixos-system-machine-test2843machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 3.75 seconds)2844machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&12845machine # [ 158.199038] nixos[7036]: switching to system configuration /nix/store/y2nc7375x7m7c30zw3hy1r3cqbadl2nc-nixos-system-machine-test2846machine # [ 158.203015] systemd[1]: Stopped target Local File Systems.2847machine # [ 158.204606] systemd[1]: Stopped target Remote File Systems.2848machine # [ 158.755916] systemd[1]: Reload requested from client PID 7036 ('.switch-to-conf') (unit backdoor.service)...2849machine # [ 158.758185] systemd[1]: Reloading...2850machine # [ 159.429708] systemd[1]: Reloading finished in 669 ms.2851machine # [ 159.572732] systemd[576]: Reexecution requested from client PID 7107 ('switch-to-confi')...2852machine # [ 159.577283] systemd[576]: Reexecuting.2853machine # [ 159.626640] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2854machine # [ 159.832419] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2855machine # [ 159.840399] systemd[576]: Reload requested from client PID 7107 ('switch-to-confi')...2856machine # [ 159.842909] systemd[576]: Reloading...2857machine # [ 160.047553] systemd[576]: Reloading finished in 203 ms.2858machine # [ 160.060341] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2859machine # [ 160.071897] systemd[576]: Stopped Run user-specific NixOS activation.2860machine # [ 160.075258] systemd[576]: Stopping Run user-specific NixOS activation...2861machine # [ 160.078538] systemd[576]: Starting Run user-specific NixOS activation...2862machine # [ 160.130754] systemd[576]: Finished Run user-specific NixOS activation.2863machine # [ 160.135820] systemd[1]: Stopped target Reactivate sysinit units.2864machine # [ 160.137965] systemd[1]: Stopping Reactivate sysinit units...2865machine # [ 160.138868] systemd[1]: Reached target Reactivate sysinit units.2866machine # [ 160.146885] systemd[1]: Reloading reload-triggers-and-restart.service...2867machine # [ 160.159633] systemd[1]: Reloading reload-triggers.service...2868machine # [ 160.166187] systemd[1]: Reloading simple-reload-service.service...2869machine # [ 160.183532] systemd[1]: Reloading templated-reload-triggers-and-restart@instance.service...2870machine # [ 160.200317] systemd[1]: Reloading templated-reload-triggers@instance.service...2871machine # [ 160.213100] systemd[1]: Reloading templated-simple-reload-service@instance.service...2872machine # [ 160.261259] systemd[1]: Reloaded reload-triggers-and-restart.service.2873machine # [ 160.282161] systemd[1]: Reloaded simple-reload-service.service.2874machine # [ 160.291471] systemd[1]: Reloaded reload-triggers.service.2875machine # [ 160.304524] systemd[1]: Reloaded templated-reload-triggers-and-restart@instance.service.2876machine # [ 160.315819] systemd[1]: Reloaded templated-reload-triggers@instance.service.2877machine # [ 160.319341] systemd[1]: Reloaded templated-simple-reload-service@instance.service.2878machine # [ 160.323183] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully.2879machine # [ 160.327931] systemd[1]: Stopped reload-triggers-and-restart-by-as.service.2880machine # [ 160.329644] systemd[1]: Stopping reload-triggers-and-restart-by-as.service...2881machine # [ 160.332729] systemd[1]: Starting reload-triggers-and-restart-by-as.service...2882machine # [ 160.337262] systemd[1]: simple-restart-service.service: Deactivated successfully.2883machine # [ 160.339370] systemd[1]: Stopped simple-restart-service.service.2884machine # [ 160.345632] systemd[1]: Stopping simple-restart-service.service...2885machine # [ 160.347470] systemd[1]: Starting simple-restart-service.service...2886machine # [ 160.352760] systemd[1]: simple-service.service: Deactivated successfully.2887machine # [ 160.363490] systemd[1]: Stopped simple-service.service.2888machine # [ 160.376807] systemd[1]: Stopping simple-service.service...2889machine # [ 160.377649] systemd[1]: Starting simple-service.service...2890machine # [ 160.378526] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully.2891machine # [ 160.397846] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service.2892machine # [ 160.399177] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service...2893machine # [ 160.410318] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service...2894machine # [ 160.411800] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully.2895machine # [ 160.428786] systemd[1]: Stopped templated-simple-restart-service@instance.service.2896machine # [ 160.440725] systemd[1]: Stopping templated-simple-restart-service@instance.service...2897machine # [ 160.455745] systemd[1]: Starting templated-simple-restart-service@instance.service...2898machine # [ 160.466972] systemd[1]: templated-simple-service@instance.service: Deactivated successfully.2899machine # [ 160.470705] systemd[1]: Stopped templated-simple-service@instance.service.2900machine # [ 160.478683] systemd[1]: Stopping templated-simple-service@instance.service...2901machine # [ 160.481657] systemd[1]: Starting templated-simple-service@instance.service...2902machine # [ 160.497965] systemd[1]: Finished simple-service.service.2903machine # [ 160.505728] systemd[1]: Finished reload-triggers-and-restart-by-as.service.2904machine # [ 160.507926] systemd[1]: Finished simple-restart-service.service.2905machine # [ 160.510506] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service.2906machine # [ 160.513991] systemd[1]: Finished templated-simple-restart-service@instance.service.2907machine # [ 160.521493] systemd[1]: Finished templated-simple-service@instance.service.2908machine # [ 160.526471] systemd[1]: Reached target Local File Systems.2909machine # [ 160.533834] systemd[1]: Reached target Remote File Systems.2910machine # [ 160.540510] systemd[1]: Starting linger-users.service...2911machine # [ 160.543828] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2912machine # [ 160.547151] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2913machine # [ 160.555990] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2914machine # [ 160.564745] systemd[1]: Starting Create SUID/SGID Wrappers...2915machine # [ 160.567616] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2916machine # [ 160.572271] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672917machine # [ 160.574672] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2918machine # [ 160.580901] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2919machine # [ 160.583048] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2920machine # [ 160.656682] systemd[1]: linger-users.service: Deactivated successfully.2921machine # [ 160.661267] systemd[1]: Finished linger-users.service.2922machine # [ 160.936984] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2923machine # [ 160.940373] systemd[1]: Finished Create SUID/SGID Wrappers.2924machine # [ 161.198128] nixos[7036]: finished switching to system configuration /nix/store/y2nc7375x7m7c30zw3hy1r3cqbadl2nc-nixos-system-machine-test2925machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 3.75 seconds)2926machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&12927machine # [ 162.058322] nixos[7202]: switching to system configuration /nix/store/jdl175bvka3fmb6gf949gvy3w9sj3sjf-nixos-system-machine-test2928machine # [ 162.061989] systemd[1]: Stopped target Local File Systems.2929machine # [ 162.063536] systemd[1]: Stopped target Remote File Systems.2930machine # [ 162.677206] systemd[1]: Reload requested from client PID 7202 ('.switch-to-conf') (unit backdoor.service)...2931machine # [ 162.679394] systemd[1]: Reloading...2932machine # [ 163.423102] systemd[1]: Reloading finished in 740 ms.2933machine # [ 163.580707] systemd[576]: Reexecution requested from client PID 7273 ('switch-to-confi')...2934machine # [ 163.584724] systemd[576]: Reexecuting.2935machine # [ 163.650236] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2936machine # [ 163.850302] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2937machine # [ 163.857949] systemd[576]: Reload requested from client PID 7273 ('switch-to-confi')...2938machine # [ 163.860814] systemd[576]: Reloading...2939machine # [ 164.054917] systemd[576]: Reloading finished in 193 ms.2940machine # [ 164.072191] systemd[576]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2941machine # [ 164.082862] systemd[576]: Stopped Run user-specific NixOS activation.2942machine # [ 164.085469] systemd[576]: Stopping Run user-specific NixOS activation...2943machine # [ 164.088502] systemd[576]: Starting Run user-specific NixOS activation...2944machine # [ 164.138864] systemd[576]: Finished Run user-specific NixOS activation.2945machine # [ 164.143369] systemd[1]: Stopped target Reactivate sysinit units.2946machine # [ 164.144884] systemd[1]: Stopping Reactivate sysinit units...2947machine # [ 164.145764] systemd[1]: Reached target Reactivate sysinit units.2948machine # [ 164.152201] systemd[1]: Reloading reload-triggers.service...2949machine # [ 164.162564] systemd[1]: Reloading simple-reload-service.service...2950machine # [ 164.176168] systemd[1]: Reloading templated-reload-triggers@instance.service...2951machine # [ 164.181415] systemd[1]: Reloading templated-simple-reload-service@instance.service...2952machine # [ 164.221253] systemd[1]: Reloaded reload-triggers.service.2953machine # [ 164.228899] systemd[1]: Reloaded simple-reload-service.service.2954machine # [ 164.245550] systemd[1]: Reloaded templated-simple-reload-service@instance.service.2955machine # [ 164.251144] systemd[1]: Reloaded templated-reload-triggers@instance.service.2956machine # [ 164.254550] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully.2957machine # [ 164.258327] systemd[1]: Stopped reload-triggers-and-restart-by-as.service.2958machine # [ 164.259573] systemd[1]: Stopping reload-triggers-and-restart-by-as.service...2959machine # [ 164.262910] systemd[1]: Starting reload-triggers-and-restart-by-as.service...2960machine # [ 164.266938] systemd[1]: reload-triggers-and-restart.service: Deactivated successfully.2961machine # [ 164.270400] systemd[1]: Stopped reload-triggers-and-restart.service.2962machine # [ 164.271353] systemd[1]: Stopping reload-triggers-and-restart.service...2963machine # [ 164.275820] systemd[1]: Starting reload-triggers-and-restart.service...2964machine # [ 164.284609] systemd[1]: simple-restart-service.service: Deactivated successfully.2965machine # [ 164.296315] systemd[1]: Stopped simple-restart-service.service.2966machine # [ 164.298466] systemd[1]: Stopping simple-restart-service.service...2967machine # [ 164.308661] systemd[1]: Starting simple-restart-service.service...2968machine # [ 164.318928] systemd[1]: simple-service.service: Deactivated successfully.2969machine # [ 164.334035] systemd[1]: Stopped simple-service.service.2970machine # [ 164.343395] systemd[1]: Stopping simple-service.service...2971machine # [ 164.355175] systemd[1]: Starting simple-service.service...2972machine # [ 164.366694] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully.2973machine # [ 164.382217] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service.2974machine # [ 164.391108] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service...2975machine # [ 164.407358] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service...2976machine # [ 164.429069] systemd[1]: templated-reload-triggers-and-restart@instance.service: Deactivated successfully.2977machine # [ 164.430499] systemd[1]: Stopped templated-reload-triggers-and-restart@instance.service.2978machine # [ 164.431561] systemd[1]: Stopping templated-reload-triggers-and-restart@instance.service...2979machine # [ 164.438674] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service...2980machine # [ 164.442200] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully.2981machine # [ 164.446173] systemd[1]: Stopped templated-simple-restart-service@instance.service.2982machine # [ 164.449276] systemd[1]: Stopping templated-simple-restart-service@instance.service...2983machine # [ 164.458497] systemd[1]: Starting templated-simple-restart-service@instance.service...2984machine # [ 164.462760] systemd[1]: templated-simple-service@instance.service: Deactivated successfully.2985machine # [ 164.467065] systemd[1]: Stopped templated-simple-service@instance.service.2986machine # [ 164.471612] systemd[1]: Stopping templated-simple-service@instance.service...2987machine # [ 164.473806] systemd[1]: Starting templated-simple-service@instance.service...2988machine # [ 164.474918] systemd[1]: Finished reload-triggers-and-restart.service.2989machine # [ 164.475914] systemd[1]: Finished simple-restart-service.service.2990machine # [ 164.479513] systemd[1]: Finished reload-triggers-and-restart-by-as.service.2991machine # [ 164.484322] systemd[1]: Finished simple-service.service.2992machine # [ 164.485176] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service.2993machine # [ 164.486308] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service.2994machine # [ 164.487355] systemd[1]: Finished templated-simple-restart-service@instance.service.2995machine # [ 164.488740] systemd[1]: Finished templated-simple-service@instance.service.2996machine # [ 164.492882] systemd[1]: Reached target Remote File Systems.2997machine # [ 164.497668] systemd[1]: Starting linger-users.service...2998machine # [ 164.511561] systemd[1]: Reached target Local File Systems.2999machine # [ 164.513573] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore3000machine # [ 164.515407] systemd[1]: Starting Create SUID/SGID Wrappers...3001machine # [ 164.520528] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.3002machine # [ 164.521821] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc673003machine # [ 164.523992] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore3004machine # [ 164.529819] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os3005machine # [ 164.531630] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os3006machine # [ 164.534469] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os3007machine # [ 164.536899] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki3008machine # [ 164.598451] systemd[1]: linger-users.service: Deactivated successfully.3009machine # [ 164.599550] systemd[1]: Finished linger-users.service.3010machine # [ 164.874945] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.3011machine # [ 164.878617] systemd[1]: Finished Create SUID/SGID Wrappers.3012machine # [ 165.136086] nixos[7202]: finished switching to system configuration /nix/store/jdl175bvka3fmb6gf949gvy3w9sj3sjf-nixos-system-machine-test3013machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&1, in 3.94 seconds)3014machine: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration dry-activate 2>&13015machine: (finished: must succeed: /nix/store/qb7gx9bcai5zmvmb8qms1jzvbvz1pq68-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration dry-activate 2>&1, in 0.88 seconds)3016(finished: subtest: restart and reload by activation script, in 15.34 seconds)3017(finished: run the VM test script, in 166.84 seconds)3018test script finished in 166.89s3019cleanup3020kill QemuMachine (pid 45)3021machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14)3022(finished: cleanup, in 0.21 seconds)3023additionally exposed symbols:3024 machine, other,3025 vlan1,3026 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