tribuchet: building on eliza Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script machine: waiting for unit multi-user.target machine: waiting for the VM to finish booting machine: starting vm machine: QEMU running (pid 45) machine # Disk image does not exist, creating the virtualisation disk image... machine # Formatting '/build/vm-state-machine/tmp.ZhosWQQfDc', fmt=raw size=1073741824 machine # mke2fs 1.47.4 (6-Mar-2025) machine # Discarding device blocks: 0/262144 done machine # Creating filesystem with 262144 4k blocks and 65536 inodes machine # Filesystem UUID: 58ac4414-71be-4040-9cb2-b9964d9c515b machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 machine # machine # Allocating group tables: 0/8 done machine # Writing inode tables: 0/8 done machine # Creating journal (8192 blocks): done machine # Writing superblocks and filesystem accounting information: 0/8 done machine # machine # Virtualisation disk image created. machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 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 2026 machine # [ 0.000000] KASLR enabled machine # [ 0.000000] random: crng init done machine # [ 0.000000] Machine model: linux,dummy-virt machine # [ 0.000000] efi: UEFI not found. machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f] machine # [ 0.000000] Zone ranges: machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] DMA32 empty machine # [ 0.000000] Normal empty machine # [ 0.000000] Device empty machine # [ 0.000000] Movable zone start for each node machine # [ 0.000000] Early memory node ranges machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 machine # [ 0.000000] psci: probing for conduit method from DT. machine # [ 0.000000] psci: PSCIv1.3 detected in firmware. machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs machine # [ 0.000000] psci: Trusted OS migration not required machine # [ 0.000000] psci: SMC Calling Convention v1.1 machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u311296 machine # [ 0.000000] Detected PIPT I-cache on CPU0 machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) machine # [ 0.000000] CPU features: detected: GICv3 CPU interface machine # [ 0.000000] CPU features: detected: Spectre-v4 machine # [ 0.000000] CPU features: detected: Spectre-BHB machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 machine # [ 0.000000] alternatives: applying boot alternatives machine # [ 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/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/init regInfo=/nix/store/lqyfi30mf6p09gy4am7iays5w7i8yk71-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/lqyfi30mf6p09gy4am7iays5w7i8yk71-closure-info/registration", will be passed to user space. machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB machine # [ 0.000000] software IO TLB: area num 1. machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) machine # [ 0.000000] Fallback order for Node 0: 0 machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 machine # [ 0.000000] Policy zone: DMA machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.000000] allocated 2097152 bytes of page_ext machine # [ 0.000000] ftrace: allocating 74886 entries in 294 pages machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups machine # [ 0.000000] rcu: Hierarchical RCU implementation. machine # [ 0.000000] rcu: RCU event tracing is enabled. machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. machine # [ 0.000000] Trampoline variant of Tasks RCU enabled. machine # [ 0.000000] Rude variant of Tasks RCU enabled. machine # [ 0.000000] Tracing variant of Tasks RCU enabled. machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 machine # [ 0.000000] GICv3: 256 SPIs implemented machine # [ 0.000000] GICv3: 0 Extended SPIs implemented machine # [ 0.000000] Root IRQ handler: gic_handle_irq machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000 machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns machine # [ 0.000028] arm-pv: using stolen time PV machine # [ 0.000400] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000579] Console: colour dummy device 80x25 machine # [ 0.000587] printk: legacy console [tty0] enabled machine # [ 0.000777] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.000784] pid_max: default: 32768 minimum: 301 machine # [ 0.000854] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.000992] landlock: Up and running. machine # [ 0.000995] Yama: becoming mindful. machine # [ 0.001440] LSM support for eBPF active machine # [ 0.001560] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.001579] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.002668] cacheinfo: Unable to detect cache hierarchy for CPU 0 machine # [ 0.003414] rcu: Hierarchical SRCU implementation. machine # [ 0.003418] rcu: Max phase no-delay instances is 1000. machine # [ 0.004607] fsl-mc MSI: its@8080000 domain created machine # [ 0.004697] EFI services will not be available. machine # [ 0.004768] smp: Bringing up secondary CPUs ... machine # [ 0.004776] smp: Brought up 1 node, 1 CPU machine # [ 0.004779] SMP: Total of 1 processors activated. machine # [ 0.004782] CPU: All CPU(s) started at EL1 machine # [ 0.004794] CPU features: detected: Branch Target Identification machine # [ 0.004799] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.004802] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.004806] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.004809] CPU features: detected: Common not Private translations machine # [ 0.004813] CPU features: detected: CRC32 instructions machine # [ 0.004815] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.004819] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.004822] CPU features: detected: Data independent timing control (DIT) machine # [ 0.004825] CPU features: detected: E0PD machine # [ 0.004828] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.004831] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.004834] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.004837] CPU features: detected: Fine Grained Traps machine # [ 0.004840] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.004845] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.004849] CPU features: detected: LSE atomic instructions machine # [ 0.004852] CPU features: detected: Privileged Access Never machine # [ 0.004854] CPU features: detected: PMUv3 machine # [ 0.004856] CPU features: detected: RAS Extension Support machine # [ 0.004859] CPU features: detected: RASv1p1 Extension Support machine # [ 0.004862] CPU features: detected: Random Number Generator machine # [ 0.004864] CPU features: detected: Speculation barrier (SB) machine # [ 0.004867] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.004870] CPU features: detected: TLB range maintenance instructions machine # [ 0.004875] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.004912] alternatives: applying system-wide alternatives machine # [ 0.007794] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.008049] Memory: 894312K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 112964K reserved, 32768K cma-reserved) machine # [ 0.008362] devtmpfs: initialized machine # [ 0.010041] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.010063] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.010251] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.010255] 0 pages in range for non-PLT usage machine # [ 0.010255] 508288 pages in range for PLT usage machine # [ 0.010370] pinctrl core: initialized pinctrl subsystem machine # [ 0.011085] DMI not present or invalid. machine # [ 0.014175] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.016739] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.016878] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.017034] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.017057] audit: initializing netlink subsys (disabled) machine # [ 0.017605] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.017607] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.017610] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.017613] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.017618] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.017641] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 machine # [ 0.017651] cpuidle: using governor ladder machine # [ 0.017656] cpuidle: using governor menu machine # [ 0.017840] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.017855] ASID allocator initialised with 65536 entries machine # [ 0.019015] Serial: AMBA PL011 UART driver machine # [ 0.024062] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 machine # [ 0.024228] printk: console [ttyAMA0] enabled machine # [ 0.145492] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.145510] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.145515] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.145520] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.145524] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.145528] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.145533] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.145537] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.152959] fbcon: Taking over console machine # [ 0.152973] ACPI: Interpreter disabled. machine # [ 0.154811] iommu: Default domain type: Translated machine # [ 0.154821] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.156443] SCSI subsystem initialized machine # [ 0.161840] usbcore: registered new interface driver usbfs machine # [ 0.161872] usbcore: registered new interface driver hub machine # [ 0.161893] usbcore: registered new device driver usb machine # [ 0.162161] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.162167] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.162177] PTP clock support registered machine # [ 0.162222] EDAC MC: Ver: 3.0.0 machine # [ 0.166746] scmi_core: SCMI protocol bus registered machine # [ 0.167680] FPGA manager framework machine # [ 0.168621] vgaarb: loaded machine # [ 0.169216] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.172927] VFS: Disk quotas dquot_6.6.0 machine # [ 0.172956] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.176519] netfs: FS-Cache loaded machine # [ 0.176638] pnp: PnP ACPI: disabled machine # [ 0.180488] NET: Registered PF_INET protocol family machine # [ 0.180647] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.208476] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.208515] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.208537] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.208578] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.208653] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.208727] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.208757] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.208781] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.208860] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.208885] NET: Registered PF_XDP protocol family machine # [ 0.208904] PCI: CLS 0 bytes, default 64 machine # [ 0.209118] Trying to unpack rootfs image as initramfs... machine # [ 0.226683] kvm [1]: HYP mode not available machine # [ 0.310786] Initialise system trusted keyrings machine # [ 0.311521] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.317635] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.318429] 9p: Installing v9fs 9p2000 file system support machine # [ 0.339001] Key type asymmetric registered machine # [ 0.339016] Asymmetric key parser 'x509' registered machine # [ 0.339080] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.341163] io scheduler mq-deadline registered machine # [ 0.341174] io scheduler kyber registered machine # [ 0.354079] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.355325] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.355723] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.355739] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.355754] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.355763] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.355788] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.355815] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.355908] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.355918] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.355924] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.355929] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.355934] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.355996] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.356423] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.356606] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.356624] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.356654] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.356670] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.357117] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.373940] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.373960] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.373991] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.374441] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.374620] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.374636] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.374666] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.375127] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.375305] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.375322] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.375351] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.375806] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.375989] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.376005] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.376036] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.376477] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.376656] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.376673] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.376703] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.377145] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.401369] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.401398] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.401428] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.401445] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.401936] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.402124] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.402154] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.402604] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.402788] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.402819] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.403200] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.403378] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.403626] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.403870] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.403899] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.403929] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.404379] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.404559] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.404576] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.404606] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.405176] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.405188] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.405194] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.405260] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.405307] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.405353] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.405399] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.405445] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.405492] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.405539] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.405585] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.405631] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.405703] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.405749] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.405771] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.405792] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.405814] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.405836] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.405861] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.405883] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.405905] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.405927] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.405950] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.405972] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.405995] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.406017] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.406039] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.406060] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned machine # [ 0.406082] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.406104] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.406126] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned machine # [ 0.406148] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned machine # [ 0.406170] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned machine # [ 0.406192] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned machine # [ 0.406219] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.406229] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.406233] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.407383] pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.479137] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.485310] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.487527] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.497403] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.499291] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) machine # [ 0.501157] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) machine # [ 0.511251] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) machine # [ 0.521396] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.525077] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.526810] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.528911] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.546479] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.548838] msm_serial: driver initialized machine # [ 0.548977] SuperH (H)SCI(F) driver initialized machine # [ 0.549030] STM32 USART driver initialized machine # [ 0.582355] loop: module loaded machine # [ 0.582517] virtio_blk virtio5: 1/0/0 default/read/poll queues machine # [ 0.583241] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.589763] megasas: 07.734.00.00-rc1 machine # [ 0.590441] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.592485] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.592509] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.601759] Using buffer write method machine # [ 0.601818] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.604647] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.604671] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.609194] Using buffer write method machine # [ 0.617277] Concatenating MTD devices: machine # [ 0.617284] (0): "0.flash" machine # [ 0.617288] (1): "0.flash" machine # [ 0.617291] into device "0.flash" machine # [ 0.835026] Freeing initrd memory: 26104K machine # [ 0.840931] tun: Universal TUN/TAP device driver, 1.6 machine # [ 0.844481] thunder_xcv, ver 1.0 machine # [ 0.844520] thunder_bgx, ver 1.0 machine # [ 0.844544] nicpf, ver 1.0 machine # [ 0.845070] e1000: Intel(R) PRO/1000 Network Driver machine # [ 0.845077] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 0.845103] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 0.845111] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 0.845139] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 0.845144] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 0.845165] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 0.845171] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 0.851966] sky2: driver version 1.30 machine # [ 0.853865] ehci-pci 0000:00:0a.0: EHCI Host Controller machine # [ 0.853891] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 machine # [ 0.854120] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 machine # [ 0.857145] usbcore: registered new interface driver usb-storage machine # [ 0.857198] usbcore: registered new interface driver usbserial_generic machine # [ 0.857211] usbserial: USB Serial support registered for generic machine # [ 0.860057] hv_vmbus: registering driver hyperv_keyboard machine # [ 0.861481] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 0.861504] rtc-pl031 9010000.pl031: setting system clock to 2026-09-06T22:46:54 UTC (1788734814) machine # [ 0.861796] i2c_dev: i2c /dev entries driver machine # [ 0.866543] sdhci: Secure Digital Host Controller Interface driver machine # [ 0.866552] sdhci: Copyright(c) Pierre Ossman machine # [ 0.866811] Synopsys Designware Multimedia Card Interface Driver machine # [ 0.867173] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 0.868791] hid: raw HID events driver (C) Jiri Kosina machine # [ 0.869013] usbcore: registered new interface driver usbhid machine # [ 0.869019] usbhid: USB HID core driver machine # [ 0.873679] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 machine # [ 0.874008] hub 1-0:1.0: USB hub found machine # [ 0.874039] hub 1-0:1.0: 6 ports detected machine # [ 0.876858] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 0.879398] drop_monitor: Initializing network drop monitor service machine # [ 0.879555] NET: Registered PF_INET6 protocol family machine # [ 0.881396] Segment Routing with IPv6 machine # [ 0.881413] In-situ OAM (IOAM) with IPv6 machine # [ 0.881460] NET: Registered PF_PACKET protocol family machine # [ 0.883065] 9pnet: Installing 9P2000 support machine # [ 0.885096] Key type dns_resolver registered machine # [ 0.891683] registered taskstats version 1 machine # [ 0.891822] Loading compiled-in X.509 certificates machine # [ 0.900232] Demotion targets for Node 0: null machine # [ 0.900330] Key type .fscrypt registered machine # [ 0.900336] Key type fscrypt-provisioning registered machine # [ 0.900421] ima: No TPM chip found, activating TPM-bypass! machine # [ 0.900440] ima: Allocated hash algorithm: sha1 machine # [ 0.900459] ima: No architecture policies found machine # [ 0.904466] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 0.921850] clk: Disabling unused clocks machine # [ 0.921869] PM: genpd: Disabling unused power domains machine # [ 0.925995] Freeing unused kernel memory: 4736K machine # [ 0.926191] Run /init as init process machine # [ 0.943288] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.121290] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.273567] 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/input1 machine # [ 1.279434] 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) machine # [ 1.279492] systemd[1]: Detected virtualization qemu. machine # [ 1.279562] systemd[1]: Detected architecture arm64. machine # [ 1.279585] systemd[1]: Running in initrd. machine # [ 1.280475] systemd[1]: Initializing machine ID from random generator. machine # [ 1.280740] systemd[1]: Hostname set to . machine # [ 1.353480] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 machine # [ 1.441482] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.473274] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.492875] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.499354] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.500423] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.501674] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.502687] systemd[1]: Reached target Path Units. machine # [ 1.503424] systemd[1]: Reached target Slice Units. machine # [ 1.504180] systemd[1]: Reached target Swaps. machine # [ 1.504861] systemd[1]: Reached target Timer Units. machine # [ 1.505824] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.507009] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.508077] systemd[1]: Listening on Journal Sockets. machine # [ 1.508926] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.509807] systemd[1]: Reached target Socket Units. machine # [ 1.511998] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.522148] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 1.522268] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.541338] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.544318] systemd[1]: Starting Journal Service... machine # [ 1.557210] systemd[1]: Starting Load Kernel Modules... machine # [ 1.557354] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.562360] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.581481] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.582591] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 1.582874] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 1.583088] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.597697] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.609869] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.623669] systemd-journald[73]: Collecting audit messages is disabled. machine # [ 1.628893] 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/input2 machine # [ 1.629054] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 machine # [ 1.638132] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.642530] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 machine # [ 1.642749] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.642759] [drm] features: -context_init machine # [ 1.643444] [drm] number of scanouts: 1 machine # [ 1.643461] [drm] number of cap sets: 0 machine # [ 1.653348] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.657541] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.665511] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic machine # [ 1.665528] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 machine # [ 1.685535] Console: switching to colour frame buffer device 160x50 machine # [ 1.717789] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.717945] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.717967] systemd[1]: Reached target Local File Systems. machine # [ 1.721710] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.725824] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.737767] systemd[1]: Finished Load Kernel Modules. machine # [ 1.745720] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.769818] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.801503] systemd[1]: Started Journal Service. machine # [ 1.792331] systemd-modules-load[74]: Inserted module 'dm_mod' machine # [ 1.793540] systemd-modules-load[74]: Module 'virtio_balloon' is built in machine # [ 1.799136] systemd-modules-load[74]: Module 'virtio_console' is built in machine # [ 1.804362] systemd-modules-load[74]: Inserted module 'virtio_gpu' machine # [ 1.813860] systemd-modules-load[74]: Module 'virtio_rng' is built in machine # [ 1.817394] systemd[1]: Starting Create System Files and Directories... machine # [ 1.823325] systemd-udevd[80]: Using default interface naming scheme 'v261'. machine # [ 1.836106] systemd[1]: Finished Create System Files and Directories. machine # [ 1.852115] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.937613] systemd[1]: Starting Virtual Console Setup... machine # [ 2.000433] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.003659] systemd[1]: Finished Virtual Console Setup. machine # [ 2.597871] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.599030] systemd[1]: Reached target System Initialization. machine # [ 2.599840] systemd[1]: Reached target Basic System. machine # [ 2.743316] (udev-worker)[92]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.757267] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.768798] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.815623] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.818727] systemd[1]: Reached target Initrd Root Device. machine # [ 2.822330] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.870517] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 2.875697] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 2.887030] systemd[1]: Mounting /sysroot... machine # [ 2.926902] EXT4-fs (vda): mounted filesystem 58ac4414-71be-4040-9cb2-b9964d9c515b r/w with ordered data mode. Quota mode: none. machine # [ 2.918888] systemd[1]: Mounted /sysroot. machine # [ 2.924098] systemd[1]: Reached target Initrd Root File System. machine # [ 2.925821] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 2.947771] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 2.954463] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 2.959532] systemd[1]: Reloading... machine # [ 3.140972] systemd[1]: Reloading finished in 183 ms. machine # [ 3.171013] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace... machine # [ 3.176166] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace... machine # [ 3.180160] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 3.183749] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.186458] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.188302] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.208085] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 3.209802] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 3.556194] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.567241] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.578260] systemd[1]: Mounting /sysroot/run... machine # [ 3.600198] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.618618] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.639972] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.661650] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.673262] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.674765] systemd[1]: Mounted /sysroot/run. machine # [ 3.681329] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.695298] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.702212] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.704335] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.708989] systemd[1]: Mounting /sysroot/nix/store... machine # [ 3.741093] systemd[1]: Mounted /sysroot/nix/store. machine # [ 3.742212] systemd[1]: Reached target Initrd File Systems. machine # [ 3.744227] systemd[1]: Starting Find NixOS closure... machine # [ 3.753700] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 3.782084] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 3.803334] systemd[1]: Finished Find NixOS closure. machine # [ 3.804232] systemd[1]: Reached target Initrd Default Target. machine # [ 3.806076] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 3.834978] systemd[1]: Stopped target Initrd Default Target. machine # [ 3.840327] systemd[1]: Stopped target Basic System. machine # [ 3.842263] systemd[1]: Stopped target Initrd Root Device. machine # [ 3.843097] systemd[1]: Stopped target Path Units. machine # [ 3.846069] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 3.850464] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 3.852638] systemd[1]: Stopped target Slice Units. machine # [ 3.856698] systemd[1]: Stopped target Socket Units. machine # [ 3.858569] systemd[1]: Stopped target System Initialization. machine # [ 3.860383] systemd[1]: Stopped target Swaps. machine # [ 3.861601] systemd[1]: Stopped target Timer Units. machine # [ 3.864723] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 3.867100] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 3.871989] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 3.874697] systemd[1]: Stopped Find NixOS closure. machine # [ 3.877907] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 3.879417] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.883679] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 3.893870] systemd[1]: Stopped Apply Kernel Variables. machine # [ 3.894782] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 3.895827] systemd[1]: Stopped Load Kernel Modules. machine # [ 3.908341] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 3.909480] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 3.910510] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 3.911507] systemd[1]: Stopped Create System Files and Directories. machine # [ 3.917952] systemd[1]: Stopped target Local File Systems. machine # [ 3.920247] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 3.921389] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 3.922391] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 3.923177] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 3.924434] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.925449] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.926176] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 3.927111] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 3.927993] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 3.936414] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 3.937298] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.938263] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.939067] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 3.939966] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 3.941916] systemd[1]: systemd-udevd.service: Consumed 1.111s CPU time over 2.222s wall clock time, 17.8M memory peak. machine # [ 3.943333] systemd[1]: Starting Cleanup udev Database... machine # [ 3.944181] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 3.945218] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 3.946072] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 3.947216] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 3.956179] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 3.957182] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 3.981245] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 3.984485] systemd[1]: Finished Cleanup udev Database. machine # [ 3.985288] systemd[1]: Reached target Switch Root. machine # [ 3.987761] systemd[1]: Starting NixOS Activation... machine # [ 4.143324] initrd-nixos-activation-start[173]: booting system configuration /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test machine # [ 4.204859] initrd-nixos-activation-start[173]: running activation script... machine # [ 4.611667] initrd-nixos-activation-start[196]: setting up /etc... machine # [ 4.863735] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 4.866498] systemd[1]: Finished NixOS Activation. machine # [ 4.868082] systemd[1]: Starting Switch Root... machine # [ 4.891045] systemd[1]: Switching root. machine # [ 5.076346] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). machine # [ 5.664393] 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) machine # [ 5.676527] systemd[1]: Detected virtualization qemu. machine # [ 5.679483] systemd[1]: Detected architecture arm64. machine # [ 5.682967] systemd[1]: Detected first boot. machine # [ 5.688863] systemd[1]: Initializing machine ID from random generator. machine # [ 5.846911] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 5.991696] systemd[1]: Applying preset policy. machine # [ 6.467764] systemd[1]: Populated /etc with preset unit settings. machine # [ 6.951861] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 6.953136] systemd[1]: Stopped initrd-switch-root.service. machine # [ 6.956392] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 6.959893] systemd[1]: Created slice Slice /system/getty. machine # [ 6.961850] systemd[1]: Created slice User and Session Slice. machine # [ 6.963084] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 6.965851] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 6.967178] systemd[1]: Expecting device /dev/hvc0... machine # [ 6.968656] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 6.970272] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 6.971923] systemd[1]: Stopped target initrd-fs.target. machine # [ 6.974216] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 6.975285] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 6.977533] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 6.978715] systemd[1]: Reached target Path Units. machine # [ 6.980041] systemd[1]: Reached target Remote File Systems. machine # [ 6.981553] systemd[1]: Reached target Slice Units. machine # [ 6.983659] systemd[1]: Reached target Swaps. machine # [ 6.987143] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 6.991665] systemd[1]: Listening on Process Core Dump Socket. machine # [ 6.995988] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 6.999325] systemd[1]: Listening on Factory Reset Management. machine # [ 7.000542] systemd[1]: Listening on Hostname Service Socket. machine # [ 7.005873] systemd[1]: Starting Journal Log Access Socket... machine # [ 7.007903] systemd[1]: Listening on Journal Audit Socket. machine # [ 7.011787] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 7.014053] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 7.016313] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.018756] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 7.029341] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 7.031421] systemd[1]: Listening on udev Varlink Socket. machine # [ 7.035883] systemd[1]: Mounting Huge Pages File System... machine # [ 7.040928] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 7.048329] systemd[1]: Mounting Kernel Debug File System... machine # [ 7.061400] systemd[1]: Mounting Kernel Trace File System... machine # [ 7.069728] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 7.091141] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 7.093647] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 7.104281] systemd[1]: Mounting Kernel Configuration File System... machine # [ 7.106407] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 7.108983] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 7.168246] systemd[1]: Starting Load Kernel Module fuse... machine # [ 7.172074] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 7.201660] systemd[1]: Starting Journal Service... machine # [ 7.214667] systemd[1]: Starting Load Kernel Modules... machine # [ 7.240574] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 7.262338] fuse: init (API version 7.45) machine # [ 7.265178] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 7.265616] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.281683] systemd[1]: Starting Coldplug All udev Devices... machine # [ 7.312580] systemd[1]: Listening on Journal Log Access Socket. machine # [ 7.317816] systemd[1]: Mounted Huge Pages File System. machine # [ 7.321487] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 7.326102] systemd[1]: Mounted Kernel Debug File System. machine # [ 7.334103] systemd[1]: Mounted Kernel Trace File System. machine # [ 7.339867] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 7.340746] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 7.347948] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 7.352935] systemd[1]: Mounted Kernel Configuration File System. machine # [ 7.354765] systemd[1]: modprobe@fuse.service: Deactivated successfully. machine # [ 7.358902] systemd-journald[269]: Collecting audit messages is enabled. machine # [ 7.362040] systemd[1]: Finished Load Kernel Module fuse. machine # [ 7.362882] systemd[1]: Finished Load Kernel Modules. machine # [ 7.362953] systemd[1]: Queued start job for default target Multi-User System. machine # [ 7.378913] systemd[1]: Mounting FUSE Control File System... machine # [ 7.369004] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 7.389413] systemd[1]: Starting Firewall... machine # [ 7.378029] systemd-modules-load[270]: Module 'atkbd' is built in machine # [ 7.383734] systemd-modules-load[270]: Module 'loop' is built in machine # [ 7.412038] EXT4-fs (vda): re-mounted 58ac4414-71be-4040-9cb2-b9964d9c515b. machine # [ 7.426390] systemd[1]: Starting Apply Kernel Variables... machine # [ 7.442406] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 7.454560] systemd[1]: Started Journal Service. machine # [ 7.452130] systemd-oomd[271]: No swap; memory pressure usage will be degraded machine # [ 7.480708] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 7.497785] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 7.553611] systemd[1]: Finished Apply Kernel Variables. machine # [ 7.554618] systemd[1]: Mounted FUSE Control File System. machine # [ 7.561492] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 7.569073] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 7.576282] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 7.579854] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 7.581557] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.606167] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 7.614698] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 7.685416] systemd-journald[269]: Received client request to flush runtime journal. machine # [ 7.733395] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 7.735075] systemd[1]: Reached target First Boot Complete. machine # [ 7.741405] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 7.758754] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 7.762413] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 7.766324] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 7.949721] systemd[1]: Mounting /run/wrappers... machine # [ 8.009952] systemd-udevd[310]: Using default interface naming scheme 'v261'. machine # [ 8.026089] systemd[1]: Mounted /run/wrappers. machine # [ 8.029228] systemd[1]: Reached target Local File Systems. machine # [ 8.035815] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 8.048301] systemd[1]: Starting register-nix-paths.service... machine # [ 8.052687] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 8.055300] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 8.080570] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 8.103965] systemd[1]: Starting Create System Files and Directories... machine # [ 8.184408] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 8.204416] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 8.339480] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 8.376677] systemd[1]: Finished Create System Files and Directories. machine # [ 8.393978] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 8.399935] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 8.589614] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 8.639794] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 8.657341] systemd[1]: Starting Update is Completed... machine # [ 8.782345] systemd[1]: Finished Update is Completed. machine # [ 9.231839] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 9.236995] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 9.763983] systemd[1]: Finished Coldplug All udev Devices. machine # [ 9.937825] systemd[1]: Finished Firewall. machine # [ 9.993354] systemd[1]: Finished register-nix-paths.service. machine # [ 9.995397] systemd[1]: Reached target System Initialization. machine # [ 9.998944] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 10.002696] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 10.003754] systemd[1]: Reached target Timer Units. machine # [ 10.008371] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 10.010259] systemd[1]: Listening on Nix Daemon Socket. machine # [ 10.014668] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 10.031501] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 machine # [ 10.021846] systemd[1]: Reached target Socket Units. machine # [ 10.024285] systemd[1]: Reached target Basic System. machine # [ 10.026726] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 10.032204] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 10.034356] systemd[1]: Starting Post-Boot Actions... machine # [ 10.064119] systemd[1]: Started Reset console on configuration changes. machine # [ 10.070414] systemd[1]: Starting resolvconf update... machine # [ 10.125735] systemd[1]: Starting D-Bus System Message Bus... machine # [ 10.149543] systemd[1]: Finished Post-Boot Actions. machine # [ 10.187131] nsncd[480]: Sep 06 22:47:03.832 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.201702] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 10.216300] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 10.217533] systemd[1]: Reached target User and Group Name Lookups. machine # [ 10.224888] systemd[1]: Starting User Login Management... machine # [ 10.257491] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 10.357630] dbus-broker-launch[486]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 10.364194] dbus-broker-launch[486]: NSS returned no entry for 'systemd-timesync' machine # [ 10.366083] dbus-broker-launch[486]: Invalid user-name in /nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 10.389735] systemd-logind[502]: New seat seat0. machine # [ 10.397776] systemd[1]: Started User Login Management. machine # [ 10.408583] systemd[1]: Starting linger-users.service... machine # [ 10.413403] systemd[1]: Started D-Bus System Message Bus. machine # [ 10.444904] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 10.448720] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 10.457783] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 10.461399] systemd[1]: Stopping User and Group Name Lookups... machine # [ 10.467419] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 10.475095] systemd[1]: nscd.service: Deactivated successfully. machine # [ 10.475973] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 10.480756] dbus-broker-launch[486]: Ready machine # [ 10.492150] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 10.555435] systemd[1]: Created slice Slice /user/1001. machine # [ 10.572101] systemd[1]: Starting User Runtime Directory /run/user/1001... machine # [ 10.584412] nsncd[549]: Sep 06 22:47:04.227 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.597806] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 10.606267] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 10.611540] systemd[1]: Finished linger-users.service. machine # [ 10.617947] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 10.623579] systemd[1]: Reached target User and Group Name Lookups. machine # [ 10.657739] systemd[1]: Finished resolvconf update. machine # [ 10.661428] systemd[1]: Reached target Preparation for Network. machine # [ 10.671266] systemd[1]: Starting DHCP Client... machine # [ 10.677568] systemd[1]: Starting Extra networking commands.... machine # [ 10.693801] systemd[1]: Finished User Runtime Directory /run/user/1001. machine # [ 10.733681] systemd[1]: Starting User Manager for UID 1001... machine # [ 10.869897] (systemd)[581]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 10.902881] systemd-logind[502]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'. machine # [ 10.940524] systemd[1]: Starting Virtual Console Setup... machine # [ 10.974422] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 11.064156] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 11.075729] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 11.085639] systemd[1]: Stopped Virtual Console Setup. machine # [ 11.093923] systemd[1]: Starting Virtual Console Setup... machine # [ 11.197471] systemd[581]: Queued start job for default target Main User Target. machine # [ 11.205871] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 11.219962] systemd[581]: Created slice User Application Slice. machine # [ 11.228607] systemd[581]: Started Daily Cleanup of User's Temporary Directories. machine # [ 11.237849] systemd[581]: Reached target Paths. machine # [ 11.246521] systemd[581]: Reached target Timers. machine # [ 11.259819] systemd[581]: Starting D-Bus User Message Bus Socket... machine # [ 11.266440] systemd[581]: Starting Create User Files and Directories... machine # [ 11.303622] systemd[581]: Finished Create User Files and Directories. machine # [ 11.369476] systemd[581]: Listening on D-Bus User Message Bus Socket. machine # [ 11.399154] systemd[581]: Reached target Sockets. machine # [ 11.421016] systemd[581]: Reached target Basic System. machine # [ 11.441178] systemd[1]: Started User Manager for UID 1001. machine # [ 11.452285] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 11.453327] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 11.454290] systemd[581]: Reached target Main User Target. machine # [ 11.455100] systemd[581]: Startup finished in 494ms. machine # [ 11.550831] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 11.578923] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 11.601732] dhcpcd[598]: dhcpcd-10.3.2 starting machine # [ 11.618603] systemd[1]: Started backdoor.service. machine # [ 11.629424] dhcpcd[662]: dev: loaded udev machine # [ 11.690957] 8021q: 802.1Q VLAN Support v1.8 machine # [ 11.697435] (udev-worker)[640]: Network interface NamePolicy= disabled on kernel command line. machine # [ 11.732323] (udev-worker)[627]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 11.796047] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 11.798447] (udev-worker)[627]: Network interface NamePolicy= disabled on kernel command line. machine # [ 11.831338] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 11.831867] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 11.837336] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 11.837683] cfg80211: failed to load regulatory.db machine # [ 11.835011] systemd[1]: Finished Extra networking commands.. machine # [ 11.856628] systemd[1]: Reached target Network. machine # [ 11.886113] systemd[1]: Starting Permit User Sessions... machine # [ 11.955617] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 11.981587] systemd[1]: Starting Address configuration of eth1... machine # [ 12.059351] systemd-logind[502]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # connecting to host... machine # [ 12.103639] systemd[1]: Finished Permit User Sessions. machine # [ 12.118066] systemd[1]: Started Getty on tty1. machine # [ 12.118754] systemd[1]: Reached target Login Prompts. machine # [ 12.230974] mousedev: PS/2 mouse device common for all mice machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine: (connecting took 12.54 seconds) machine: (finished: waiting for the VM to finish booting, in 12.97 seconds) machine # [ 12.257434] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 12.249740] dhcpcd[662]: eth0: waiting for carrier machine # [ 12.269003] dhcpcd[662]: eth0: waiting for carrier machine # [ 12.270410] dhcpcd[662]: eth0: carrier acquired machine # [ 12.310664] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 12.302864] dhcpcd[662]: DUID 00:01:00:01:32:30:a9:e9:52:54:00:12:34:56 machine # [ 12.305534] dhcpcd[662]: eth0: IAID 00:12:34:56 machine # [ 12.309645] dhcpcd[662]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 12.381117] network-addresses-eth1-start[682]: adding address 192.168.1.1/24... done machine # [ 12.475339] systemd-logind[502]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 12.494413] network-addresses-eth1-start[682]: adding address 2001:db8:1::1/64... done machine # [ 12.579452] systemd[1]: Finished Address configuration of eth1. machine # [ 12.808484] systemd-vconsole-setup[596]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 12.833141] systemd[1]: Finished Virtual Console Setup. machine # [ 13.280782] dhcpcd[662]: eth0: soliciting a DHCP lease machine # [ 13.296087] dhcpcd[662]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 13.296996] dhcpcd[662]: eth0: probing address 10.0.2.15/24 machine # [ 13.529263] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 13.560272] systemd[1]: Stopped Virtual Console Setup. machine # [ 13.561091] systemd[1]: Stopping Virtual Console Setup... machine # [ 13.561847] systemd[1]: Starting Virtual Console Setup... machine # [ 13.562639] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 13.588313] systemd-logind[502]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 13.597193] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 13.598421] systemd[1]: Stopped Virtual Console Setup. machine # [ 13.600959] systemd[1]: Starting Virtual Console Setup... machine # [ 13.628676] dhcpcd[662]: eth0: soliciting an IPv6 router machine # [ 13.630152] dhcpcd[662]: eth0: Router Advertisement from fe80::2 machine # [ 13.631035] dhcpcd[662]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 13.631945] dhcpcd[662]: eth0: adding route to fec0::/64 machine # [ 13.634071] dhcpcd[662]: eth0: adding default route via fe80::2 machine # [ 13.989067] systemd-vconsole-setup[719]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 13.994631] systemd[1]: Finished Virtual Console Setup. machine # [ 18.624555] dhcpcd[662]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 18.628123] dhcpcd[662]: eth0: adding route to 10.0.2.0/24 machine # [ 18.630827] dhcpcd[662]: eth0: adding default route via 10.0.2.2 machine # [ 18.783410] systemd[1]: Started DHCP Client. machine # [ 18.785705] systemd[1]: Reached target Multi-User System. machine # [ 18.786586] systemd[1]: Startup finished in 917ms (kernel) + 4.263s (initrd) + 13.604s (userspace) = 18.785s. machine: (finished: waiting for unit multi-user.target, in 20.19 seconds) machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/bin/switch-to-configuration test machine # Checking switch inhibitors... done machine # [ 20.022408] nixos[836]: switching to system configuration /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test machine # activating the configuration... machine # [ 20.026558] systemd[1]: Stopped target Remote File Systems. machine # [ 20.027623] systemd[1]: Stopped target Local File Systems. machine # setting up /etc... machine # [ 20.459623] systemd[1]: Reload requested from client PID 836 ('.switch-to-conf') (unit backdoor.service)... machine # [ 20.461785] systemd[1]: Reloading... machine # [ 21.018984] systemd[1]: Reloading finished in 555 ms. machine # reloading user units for usertest... machine # [ 21.065422] systemd[581]: Created slice User Core Session Slice. machine # [ 21.068650] systemd[581]: Starting D-Bus User Message Bus... machine # [ 21.127835] dbus-broker-launch[906]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.import1.service' machine # [ 21.131018] dbus-broker-launch[906]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.machine1.service' machine # [ 21.134145] dbus-broker-launch[906]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.portable1.service' machine # [ 21.137507] dbus-broker-launch[906]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.systemd1.service' machine # [ 21.141177] dbus-broker-launch[906]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored machine # [ 21.143644] dbus-broker-launch[906]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored machine # [ 21.146027] systemd[581]: Started D-Bus User Message Bus. machine # [ 21.152595] dbus-broker-launch[906]: Ready machine # [ 21.252075] systemd[581]: Reexecution requested from client PID 904 ('switch-to-confi')... machine # [ 21.254680] systemd[581]: Reexecuting. machine # [ 21.313661] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 21.493889] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 21.499846] systemd[581]: Reload requested from client PID 904 ('switch-to-confi')... machine # [ 21.502241] systemd[581]: Reloading... machine # [ 21.680115] systemd[581]: Reloading finished in 177 ms. machine # [ 21.696130] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 21.705304] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 21.707415] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 21.709977] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 21.762288] systemd[581]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 21.781855] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... machine # [ 21.900166] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. machine # [ 21.901306] systemd[1]: Reached target Reactivate sysinit units. machine # [ 21.905126] systemd[1]: Reached target Remote File Systems. machine # [ 21.910172] systemd[1]: Reached target Local File Systems. machine # [ 21.913146] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 21.917476] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 21.918333] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 21.919523] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 21.922955] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 21.932157] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 21.933449] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 21.934605] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 21.935833] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 22.235691] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 22.237638] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 22.243631] systemd[1]: Starting linger-users.service... machine # [ 22.302181] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 22.304591] systemd[1]: Finished linger-users.service. machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service machine # [ 22.567781] nixos[836]: finished switching to system configuration /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/bin/switch-to-configuration test, in 3.13 seconds) machine: must succeed: rm /etc/NIXOS machine: (finished: must succeed: rm /etc/NIXOS, in 0.05 seconds) machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/h7cjjcq34gik896rnm34qkpr3psnxwz8-nixos-system-other-test/bin/switch-to-configuration test machine # this should succeed (config: /nix/store/h7cjjcq34gik896rnm34qkpr3psnxwz8-nixos-system-other-test, action: test) machine # Checking switch inhibitors... done machine # [ 23.220960] nixos[984]: switching to system configuration /nix/store/h7cjjcq34gik896rnm34qkpr3psnxwz8-nixos-system-other-test machine # [ 23.223637] systemd[1]: Stopped target Local File Systems. machine # activating the configuration... machine # [ 23.226319] systemd[1]: Stopped target Remote File Systems. machine # removing user ‘usertest’ machine # setting up /etc... machine # [ 23.693262] systemd[1]: Reload requested from client PID 984 ('.switch-to-conf') (unit backdoor.service)... machine # [ 23.695028] systemd[1]: Reloading... machine # [ 24.299871] systemd[1]: Reloading finished in 602 ms. machine # reloading user units for usertest... machine # [ 24.451840] systemd[581]: Reexecution requested from client PID 1055 ('switch-to-confi')... machine # [ 24.454088] systemd[581]: Reexecuting. machine # [ 24.518188] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.713398] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.719860] systemd[581]: Reload requested from client PID 1055 ('switch-to-confi')... machine # [ 24.722407] systemd[581]: Reloading... machine # [ 24.903686] systemd[581]: Reloading finished in 180 ms. machine # [ 24.916156] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 24.926328] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 24.928681] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 24.931586] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 24.975651] systemd[581]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 24.980127] systemd[1]: Stopped target Reactivate sysinit units. machine # reloading the following units: dbus-broker.service machine # [ 24.982950] systemd[1]: Stopping Reactivate sysinit units... machine # [ 24.983816] systemd[1]: Reached target Reactivate sysinit units. machine # [ 24.986683] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 24.988573] dbus-broker-launch[486]: Caught SIGHUP, trigger reload. machine # [ 25.007108] dbus-broker-launch[486]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 25.009192] dbus-broker-launch[486]: NSS returned no entry for 'systemd-timesync' machine # [ 25.010373] dbus-broker-launch[486]: Invalid user-name in /nix/store/2d37a0ag8593x6bsc14irprkj2vpg80c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 25.045749] systemd[1]: Reloaded D-Bus System Message Bus. machine # restarting the following units: network-addresses-eth1.service machine # [ 25.052121] systemd[1]: Stopping Address configuration of eth1... machine # [ 25.115114] network-addresses-eth1-pre-stop[1067]: deleting address 192.168.1.1/24... done machine # [ 25.127236] network-addresses-eth1-pre-stop[1067]: deleting address 2001:db8:1::1/64... done machine # [ 25.138491] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 25.142607] systemd[1]: Stopped Address configuration of eth1. machine # [ 25.144828] systemd[1]: Starting Address configuration of eth1... machine # [ 25.217428] network-addresses-eth1-start[1072]: adding address 192.168.1.2/24... done machine # [ 25.228823] network-addresses-eth1-start[1072]: adding address 2001:db8:1::2/64... done machine # [ 25.243302] systemd[1]: Finished Address configuration of eth1. machine # [ 25.247929] systemd[1]: Reached target Local File Systems. machine # [ 25.251477] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 25.255560] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 25.258168] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 25.265006] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 25.267654] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 25.273386] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 25.274790] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 25.276401] systemd[1]: Reached target Remote File Systems. machine # [ 25.277618] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 25.282892] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 25.631589] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 25.634173] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 25.638870] systemd[1]: Starting linger-users.service... machine # [ 25.684217] linger-users-start[1145]: id: ‘usertest’: no such user machine # [ 25.686582] linger-users-start[1143]: Removing linger for missing user usertest machine # [ 25.695152] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 25.697115] systemd[1]: Finished linger-users.service. machine # [ 25.956557] nixos[984]: finished switching to system configuration /nix/store/h7cjjcq34gik896rnm34qkpr3psnxwz8-nixos-system-other-test machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/h7cjjcq34gik896rnm34qkpr3psnxwz8-nixos-system-other-test/bin/switch-to-configuration test, in 3.34 seconds) subtest: services machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 26.512257] nixos[1151]: switching to system configuration /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test machine # [ 26.514602] systemd[1]: Stopped target Remote File Systems. machine # [ 26.516743] systemd[1]: Stopped target Local File Systems. machine # [ 26.930009] systemd[1]: Reload requested from client PID 1151 ('.switch-to-conf') (unit backdoor.service)... machine # [ 26.931895] systemd[1]: Reloading... machine # [ 27.474596] systemd[1]: Reloading finished in 540 ms. machine # [ 27.609237] systemd[581]: Reexecution requested from client PID 1220 ('switch-to-confi')... machine # [ 27.612539] systemd[581]: Reexecuting. machine # [ 27.673906] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.858152] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.863949] systemd[581]: Reload requested from client PID 1220 ('switch-to-confi')... machine # [ 27.866331] systemd[581]: Reloading... machine # [ 28.043267] systemd[581]: Reloading finished in 175 ms. machine # [ 28.056130] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.064071] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 28.065030] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 28.068399] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 28.109417] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 28.113413] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 28.114706] systemd[1]: Stopping Reactivate sysinit units... machine # [ 28.115532] systemd[1]: Reached target Reactivate sysinit units. machine # [ 28.118621] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 28.120348] dbus-broker-launch[486]: Caught SIGHUP, trigger reload. machine # [ 28.133092] dbus-broker-launch[486]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 28.135064] dbus-broker-launch[486]: NSS returned no entry for 'systemd-timesync' machine # [ 28.136274] dbus-broker-launch[486]: Invalid user-name in /nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 28.160206] systemd[1]: Reloaded D-Bus System Message Bus. machine # [ 28.165601] systemd[1]: Stopping Address configuration of eth1... machine # [ 28.208655] network-addresses-eth1-pre-stop[1232]: deleting address 192.168.1.2/24... done machine # [ 28.218834] network-addresses-eth1-pre-stop[1232]: deleting address 2001:db8:1::2/64... done machine # [ 28.227572] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 28.231181] systemd[1]: Stopped Address configuration of eth1. machine # [ 28.233433] systemd[1]: Starting Address configuration of eth1... machine # [ 28.296465] network-addresses-eth1-start[1237]: adding address 192.168.1.1/24... done machine # [ 28.306946] network-addresses-eth1-start[1237]: adding address 2001:db8:1::1/64... done machine # [ 28.320804] systemd[1]: Finished Address configuration of eth1. machine # [ 28.323535] systemd[1]: Reached target Local File Systems. machine # [ 28.325742] systemd[1]: Reached target Remote File Systems. machine # [ 28.327519] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.330185] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 28.334984] systemd[1]: Starting linger-users.service... machine # [ 28.346775] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 28.350734] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 28.351574] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 28.355500] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 28.360328] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 28.361870] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.365678] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.415624] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 28.418388] systemd[1]: Finished linger-users.service. machine # [ 28.695823] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 28.698484] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 28.957814] nixos[1151]: finished switching to system configuration /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.00 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 29.544640] nixos[1307]: switching to system configuration /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test machine # [ 29.548788] systemd[1]: Stopped target Local File Systems. machine # [ 29.549936] systemd[1]: Stopped target Remote File Systems. machine # [ 29.997770] systemd[1]: Reload requested from client PID 1307 ('.switch-to-conf') (unit backdoor.service)... machine # [ 29.999992] systemd[1]: Reloading... machine # [ 30.609016] systemd[1]: Reloading finished in 606 ms. machine # [ 30.753622] systemd[581]: Reexecution requested from client PID 1376 ('switch-to-confi')... machine # [ 30.758108] systemd[581]: Reexecuting. machine # [ 30.821954] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.022702] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.031674] systemd[581]: Reload requested from client PID 1376 ('switch-to-confi')... machine # [ 31.034547] systemd[581]: Reloading... machine # [ 31.267322] systemd[581]: Reloading finished in 231 ms. machine # [ 31.280254] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.293826] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 31.294861] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 31.298149] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 31.353234] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 31.358995] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 31.361528] systemd[1]: Stopping Reactivate sysinit units... machine # [ 31.362377] systemd[1]: Reached target Reactivate sysinit units. machine # [ 31.369954] systemd[1]: Reached target Local File Systems. machine # [ 31.372622] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 31.379517] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 31.382841] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 31.388579] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 31.390772] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 31.402027] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.403467] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.407853] systemd[1]: Reached target Remote File Systems. machine # [ 31.408790] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.410065] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 31.855716] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 31.863867] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 31.867583] systemd[1]: Starting linger-users.service... machine # [ 31.954918] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 31.957738] systemd[1]: Finished linger-users.service. machine # [ 32.223767] nixos[1307]: finished switching to system configuration /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.27 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1 machine # [ 33.023766] nixos[1448]: switching to system configuration /nix/store/lxdwfgd5c934743c3whv68kagkbv8rj8-nixos-system-machine-test machine # [ 33.027483] systemd[1]: Stopped target Local File Systems. machine # [ 33.028914] systemd[1]: Stopped target Remote File Systems. machine # [ 33.528245] systemd[1]: Reload requested from client PID 1448 ('.switch-to-conf') (unit backdoor.service)... machine # [ 33.530179] systemd[1]: Reloading... machine # [ 34.151436] systemd[1]: Reloading finished in 618 ms. machine # [ 34.290802] systemd[581]: Reexecution requested from client PID 1517 ('switch-to-confi')... machine # [ 34.292837] systemd[581]: Reexecuting. machine # [ 34.362014] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.549636] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.555715] systemd[581]: Reload requested from client PID 1517 ('switch-to-confi')... machine # [ 34.558162] systemd[581]: Reloading... machine # [ 34.735922] systemd[581]: Reloading finished in 177 ms. machine # [ 34.748135] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.757988] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 34.760154] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 34.762806] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 34.807138] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 34.810815] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 34.812712] systemd[1]: Stopping Reactivate sysinit units... machine # [ 34.813949] systemd[1]: Reached target Reactivate sysinit units. machine # [ 34.818007] systemd[1]: Reached target Remote File Systems. machine # [ 34.820338] systemd[1]: Starting linger-users.service... machine # [ 34.823971] systemd[1]: Starting test.service... machine # [ 34.840586] systemd[1]: Reached target Local File Systems. machine # [ 34.841575] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 34.846025] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 34.846889] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 34.853107] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 34.859165] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 34.862704] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.865411] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.867779] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.870298] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 34.896073] systemd[1]: Finished test.service. machine # [ 34.930381] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 34.933323] systemd[1]: Finished linger-users.service. machine # [ 35.213171] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 35.215842] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 35.473919] nixos[1448]: finished switching to system configuration /nix/store/lxdwfgd5c934743c3whv68kagkbv8rj8-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 3.24 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1 machine # [ 36.032109] nixos[1591]: switching to system configuration /nix/store/lxdwfgd5c934743c3whv68kagkbv8rj8-nixos-system-machine-test machine # [ 36.035381] systemd[1]: Stopped target Local File Systems. machine # [ 36.036634] systemd[1]: Stopped target Remote File Systems. machine # [ 36.449167] systemd[1]: Reload requested from client PID 1591 ('.switch-to-conf') (unit backdoor.service)... machine # [ 36.451020] systemd[1]: Reloading... machine # [ 37.013847] systemd[1]: Reloading finished in 560 ms. machine # [ 37.151267] systemd[581]: Reexecution requested from client PID 1659 ('switch-to-confi')... machine # [ 37.153374] systemd[581]: Reexecuting. machine # [ 37.218615] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.404192] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.411162] systemd[581]: Reload requested from client PID 1659 ('switch-to-confi')... machine # [ 37.413258] systemd[581]: Reloading... machine # [ 37.594247] systemd[581]: Reloading finished in 179 ms. machine # [ 37.608129] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.617900] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 37.618831] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 37.621496] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 37.661835] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 37.665686] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 37.667453] systemd[1]: Stopping Reactivate sysinit units... machine # [ 37.669271] systemd[1]: Reached target Reactivate sysinit units. machine # [ 37.672351] systemd[1]: Reached target Local File Systems. machine # [ 37.674428] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.677689] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 37.682142] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 37.685926] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 37.686755] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 37.687951] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 37.691726] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 37.697001] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.699317] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.703985] systemd[1]: Reached target Remote File Systems. machine # [ 38.000702] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 38.004420] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 38.007636] systemd[1]: Starting linger-users.service... machine # [ 38.063116] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 38.065208] systemd[1]: Finished linger-users.service. machine # [ 38.323034] nixos[1591]: finished switching to system configuration /nix/store/lxdwfgd5c934743c3whv68kagkbv8rj8-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 2.85 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&1 machine # [ 38.951937] nixos[1731]: switching to system configuration /nix/store/57cymh966qv38v1hbfbxxw2iyvk92vn8-nixos-system-machine-test machine # [ 38.954626] systemd[1]: Stopped target Local File Systems. machine # [ 38.956891] systemd[1]: Stopped target Remote File Systems. machine # [ 39.436710] systemd[1]: Reload requested from client PID 1731 ('.switch-to-conf') (unit backdoor.service)... machine # [ 39.438602] systemd[1]: Reloading... machine # [ 40.054943] systemd[1]: Reloading finished in 614 ms. machine # [ 40.190257] systemd[581]: Reexecution requested from client PID 1801 ('switch-to-confi')... machine # [ 40.193476] systemd[581]: Reexecuting. machine # [ 40.241293] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.418508] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.424620] systemd[581]: Reload requested from client PID 1801 ('switch-to-confi')... machine # [ 40.426697] systemd[581]: Reloading... machine # [ 40.609236] systemd[581]: Reloading finished in 181 ms. machine # [ 40.624143] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.632534] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 40.634680] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 40.637353] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 40.682537] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 40.686261] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 40.688290] systemd[1]: Stopping Reactivate sysinit units... machine # [ 40.689847] systemd[1]: Reached target Reactivate sysinit units. machine # [ 40.692540] systemd[1]: Reached target Remote File Systems. machine # [ 40.696383] systemd[1]: Starting linger-users.service... machine # [ 40.699400] systemd[1]: Reached target Local File Systems. machine # [ 40.705390] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 40.706753] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 40.713406] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 40.717965] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 40.718885] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 40.728430] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 40.730591] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 40.733287] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 40.734936] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 40.791276] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 40.792774] systemd[1]: Finished linger-users.service. machine # [ 41.055086] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 41.058564] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 41.314993] nixos[1731]: finished switching to system configuration /nix/store/57cymh966qv38v1hbfbxxw2iyvk92vn8-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&1, in 2.99 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 41.964486] nixos[1872]: switching to system configuration /nix/store/r7ly5vgiiy16h1wf0db10pqxzlzffnss-nixos-system-machine-test machine # [ 41.966805] systemd[1]: Stopped target Local File Systems. machine # [ 41.969978] systemd[1]: Stopped target Remote File Systems. machine # [ 41.970938] systemd[1]: test.service: Deactivated successfully. machine # [ 41.971938] systemd[1]: Stopped Test unit. machine # [ 42.452703] systemd[1]: Reload requested from client PID 1872 ('.switch-to-conf') (unit backdoor.service)... machine # [ 42.454574] systemd[1]: Reloading... machine # [ 43.065637] systemd[1]: Reloading finished in 608 ms. machine # [ 43.200685] systemd[581]: Reexecution requested from client PID 1942 ('switch-to-confi')... machine # [ 43.204273] systemd[581]: Reexecuting. machine # [ 43.253945] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.438235] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.444502] systemd[581]: Reload requested from client PID 1942 ('switch-to-confi')... machine # [ 43.446857] systemd[581]: Reloading... machine # [ 43.628950] systemd[581]: Reloading finished in 180 ms. machine # [ 43.644143] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.652968] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 43.655188] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 43.657836] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 43.703951] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 43.708718] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 43.709669] systemd[1]: Stopping Reactivate sysinit units... machine # [ 43.710462] systemd[1]: Reached target Reactivate sysinit units. machine # [ 43.715614] systemd[1]: Reached target Local File Systems. machine # [ 43.718887] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 43.722845] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 43.723722] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 43.729481] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 43.736680] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 43.738243] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.739486] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.743594] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.747109] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 43.748995] systemd[1]: Reached target Remote File Systems. machine # [ 44.045355] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 44.049525] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 44.052374] systemd[1]: Starting linger-users.service... machine # [ 44.056819] systemd[1]: Starting test.service... machine # [ 44.099241] systemd[1]: Finished test.service. machine # [ 44.127392] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 44.129875] systemd[1]: Finished linger-users.service. machine # [ 44.388763] nixos[1872]: finished switching to system configuration /nix/store/r7ly5vgiiy16h1wf0db10pqxzlzffnss-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 3.07 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 44.996516] nixos[2015]: switching to system configuration /nix/store/8w1qkrbnln7x21ymczhvirs6win356ij-nixos-system-machine-test machine # [ 44.999121] systemd[1]: Stopped target Local File Systems. machine # [ 45.001221] systemd[1]: Stopped target Remote File Systems. machine # [ 45.516758] systemd[1]: Reload requested from client PID 2015 ('.switch-to-conf') (unit backdoor.service)... machine # [ 45.518625] systemd[1]: Reloading... machine # [ 46.125677] systemd[1]: Reloading finished in 604 ms. machine # [ 46.261968] systemd[581]: Reexecution requested from client PID 2084 ('switch-to-confi')... machine # [ 46.265393] systemd[581]: Reexecuting. machine # [ 46.325506] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.505825] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.511922] systemd[581]: Reload requested from client PID 2084 ('switch-to-confi')... machine # [ 46.514331] systemd[581]: Reloading... machine # [ 46.691763] systemd[581]: Reloading finished in 176 ms. machine # [ 46.708131] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.717122] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 46.718076] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 46.720623] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 46.757767] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 46.762262] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 46.763936] systemd[1]: Stopping Reactivate sysinit units... machine # [ 46.765449] systemd[1]: Reached target Reactivate sysinit units. machine # [ 46.768291] systemd[1]: test.service: Deactivated successfully. machine # [ 46.769130] systemd[1]: Stopped test.service. machine # [ 46.769756] systemd[1]: Stopping test.service... machine # [ 46.772002] systemd[1]: Starting test.service... machine # [ 46.804638] systemd[1]: Finished test.service. machine # [ 46.808336] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.810614] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 46.815467] systemd[1]: Reached target Remote File Systems. machine # [ 46.820179] systemd[1]: Starting linger-users.service... machine # [ 46.829274] systemd[1]: Reached target Local File Systems. machine # [ 46.830796] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 46.837140] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 46.837984] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 46.839184] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 46.842814] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 46.845173] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.849974] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.899643] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 46.901326] systemd[1]: Finished linger-users.service. machine # [ 47.162803] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 47.165420] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 47.421783] nixos[2015]: finished switching to system configuration /nix/store/8w1qkrbnln7x21ymczhvirs6win356ij-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 3.03 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&1 machine # [ 48.016553] nixos[2158]: switching to system configuration /nix/store/pna5fpaf2z3xilzibhcrvrc3gjq9zq12-nixos-system-machine-test machine # [ 48.018816] systemd[1]: Stopped target Local File Systems. machine # [ 48.020876] systemd[1]: Stopped target Remote File Systems. machine # [ 48.494295] systemd[1]: Reload requested from client PID 2158 ('.switch-to-conf') (unit backdoor.service)... machine # [ 48.496165] systemd[1]: Reloading... machine # [ 49.117338] systemd[1]: Reloading finished in 619 ms. machine # [ 49.252374] systemd[581]: Reexecution requested from client PID 2226 ('switch-to-confi')... machine # [ 49.253671] systemd[581]: Reexecuting. machine # [ 49.313408] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.495482] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.501557] systemd[581]: Reload requested from client PID 2226 ('switch-to-confi')... machine # [ 49.503824] systemd[581]: Reloading... machine # [ 49.680889] systemd[581]: Reloading finished in 175 ms. machine # [ 49.697489] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.706537] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 49.708742] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 49.711450] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 49.754210] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 49.758236] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 49.762072] systemd[1]: Stopping Reactivate sysinit units... machine # [ 49.763244] systemd[1]: Reached target Reactivate sysinit units. machine # [ 49.765519] systemd[1]: Reloading test.service... machine # [ 49.796235] systemd[1]: Reloaded test.service. machine # [ 49.801932] systemd[1]: Reached target Local File Systems. machine # [ 49.804824] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 49.809729] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 49.810601] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 49.811769] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 49.816854] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 49.820417] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 49.829479] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 49.830670] systemd[1]: Reached target Remote File Systems. machine # [ 49.831411] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 49.832795] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 50.130578] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 50.134535] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 50.136758] systemd[1]: Starting linger-users.service... machine # [ 50.191087] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 50.193180] systemd[1]: Finished linger-users.service. machine # [ 50.450669] nixos[2158]: finished switching to system configuration /nix/store/pna5fpaf2z3xilzibhcrvrc3gjq9zq12-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&1, in 3.03 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1 machine # [ 51.046321] nixos[2300]: switching to system configuration /nix/store/12zh8y7plvai6dd6gnzisvgwx1593956-nixos-system-machine-test machine # [ 51.048956] systemd[1]: Stopped target Local File Systems. machine # [ 51.051320] systemd[1]: Stopped target Remote File Systems. machine # [ 51.540759] systemd[1]: Reload requested from client PID 2300 ('.switch-to-conf') (unit backdoor.service)... machine # [ 51.542712] systemd[1]: Reloading... machine # [ 52.190356] systemd[1]: Reloading finished in 645 ms. machine # [ 52.338109] systemd[581]: Reexecution requested from client PID 2369 ('switch-to-confi')... machine # [ 52.342434] systemd[581]: Reexecuting. machine # [ 52.410020] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.605861] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.613294] systemd[581]: Reload requested from client PID 2369 ('switch-to-confi')... machine # [ 52.614661] systemd[581]: Reloading... machine # [ 52.803378] systemd[581]: Reloading finished in 187 ms. machine # [ 52.816157] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.825122] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 52.827474] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 52.830241] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 52.876075] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 52.880190] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 52.882365] systemd[1]: Stopping Reactivate sysinit units... machine # [ 52.884326] systemd[1]: Reached target Reactivate sysinit units. machine # [ 52.889134] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 52.891542] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 52.896529] systemd[1]: Reached target Remote File Systems. machine # [ 52.898920] systemd[1]: Starting linger-users.service... machine # [ 52.903197] systemd[1]: Reached target Local File Systems. machine # [ 52.908328] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 52.914012] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 52.914976] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 52.922347] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 52.926770] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 52.929529] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 52.930845] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 52.988530] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 52.991562] systemd[1]: Finished linger-users.service. machine # [ 53.248188] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 53.251241] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 53.511053] nixos[2300]: finished switching to system configuration /nix/store/12zh8y7plvai6dd6gnzisvgwx1593956-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 3.06 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&1 machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&1, in 0.69 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 54.737801] nixos[2450]: switching to system configuration /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test machine # [ 54.741647] systemd[1]: Stopped target Local File Systems. machine # [ 54.743348] systemd[1]: Stopped target Remote File Systems. machine # [ 54.744792] systemd[1]: test.service: Deactivated successfully. machine # [ 54.745617] systemd[1]: Stopped test.service. machine # [ 55.181181] systemd[1]: Reload requested from client PID 2450 ('.switch-to-conf') (unit backdoor.service)... machine # [ 55.183082] systemd[1]: Reloading... machine # [ 55.757801] systemd[1]: Reloading finished in 572 ms. machine # [ 55.892804] systemd[581]: Reexecution requested from client PID 2518 ('switch-to-confi')... machine # [ 55.896947] systemd[581]: Reexecuting. machine # [ 55.942519] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.122528] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.128716] systemd[581]: Reload requested from client PID 2518 ('switch-to-confi')... machine # [ 56.130975] systemd[581]: Reloading... machine # [ 56.310682] systemd[581]: Reloading finished in 178 ms. machine # [ 56.324141] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.332644] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 56.334795] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 56.337462] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 56.379774] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 56.383658] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 56.385304] systemd[1]: Stopping Reactivate sysinit units... machine # [ 56.386133] systemd[1]: Reached target Reactivate sysinit units. machine # [ 56.387635] systemd[1]: Reached target Remote File Systems. machine # [ 56.393549] systemd[1]: Starting linger-users.service... machine # [ 56.402865] systemd[1]: Reached target Local File Systems. machine # [ 56.403965] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 56.412386] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 56.413248] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 56.414437] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 56.424874] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 56.426651] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.428546] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.430630] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.432243] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 56.485065] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 56.487456] systemd[1]: Finished linger-users.service. machine # [ 56.737644] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 56.741006] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 56.999194] nixos[2450]: finished switching to system configuration /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.80 seconds) machine: must succeed: /nix/store/d6ryfp12kfvzvs5yhn3y5nagh1ilpa08-separate-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 57.582820] nixos[2590]: switching to system configuration /nix/store/9jjx92m9ym6whrg55xyvnjf1krr5frad-nixos-system-machine-test machine # [ 57.585507] systemd[1]: Stopped target Local File Systems. machine # [ 57.587644] systemd[1]: Stopped target Remote File Systems. machine # [ 58.070962] systemd[1]: Reload requested from client PID 2590 ('.switch-to-conf') (unit backdoor.service)... machine # [ 58.073026] systemd[1]: Reloading... machine # [ 58.644675] systemd[1]: Reloading finished in 569 ms. machine # [ 58.794446] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 58.798781] systemd[581]: Reexecution requested from client PID 2658 ('switch-to-confi')... machine # [ 58.800516] systemd[581]: Reexecuting. machine # [ 58.853483] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.041596] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.047950] systemd[581]: Reload requested from client PID 2658 ('switch-to-confi')... machine # [ 59.050340] systemd[581]: Reloading... machine # [ 59.229448] systemd[581]: Reloading finished in 176 ms. machine # [ 59.240131] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.249994] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 59.250934] systemd[1]: Stopping Reactivate sysinit units... machine # [ 59.251725] systemd[1]: Reached target Reactivate sysinit units. machine # [ 59.254319] systemd[1]: Reached target Remote File Systems. machine # [ 59.256847] systemd[1]: Reached target Local File Systems. machine # [ 59.259995] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 59.263900] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 59.266318] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 59.273344] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 59.275550] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 59.281542] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.282817] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.285030] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.286311] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 59.578160] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 59.582650] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 59.586143] systemd[1]: Starting linger-users.service... machine # [ 59.589114] systemd[1]: Starting test.service... machine # [ 59.631183] systemd[1]: Finished test.service. machine # [ 59.656139] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 59.658744] systemd[1]: Finished linger-users.service. machine # [ 59.916557] nixos[2590]: finished switching to system configuration /nix/store/9jjx92m9ym6whrg55xyvnjf1krr5frad-nixos-system-machine-test machine: (finished: must succeed: /nix/store/d6ryfp12kfvzvs5yhn3y5nagh1ilpa08-separate-activation-script/bin/switch-to-configuration test 2>&1, in 2.92 seconds) machine: must succeed: ! test -e /run/current-system/activate machine: (finished: must succeed: ! test -e /run/current-system/activate, in 0.03 seconds) machine: must succeed: ! test -e /run/current-system/dry-activate machine: (finished: must succeed: ! test -e /run/current-system/dry-activate, in 0.03 seconds) machine: must succeed: ! test -e /run/current-system/bin/switch-to-configuration machine: (finished: must succeed: ! test -e /run/current-system/bin/switch-to-configuration, in 0.02 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&1 machine # [ 60.563875] nixos[2738]: switching to system configuration /nix/store/zzz8fhr5yfj7x3lqx0bmd9zcbq46prmy-nixos-system-machine-test machine # [ 60.567405] systemd[1]: Stopped target Local File Systems. machine # [ 60.569129] systemd[1]: Stopped target Remote File Systems. machine # [ 60.569907] systemd[1]: test.service: Deactivated successfully. machine # [ 60.570705] systemd[1]: Stopped test.service. machine # [ 61.053516] systemd[1]: Reload requested from client PID 2738 ('.switch-to-conf') (unit backdoor.service)... machine # [ 61.055380] systemd[1]: Reloading... machine # [ 61.684572] systemd[1]: Reloading finished in 627 ms. machine # [ 61.815762] systemd[581]: Reexecution requested from client PID 2806 ('switch-to-confi')... machine # [ 61.819259] systemd[581]: Reexecuting. machine # [ 61.881540] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.061297] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.067653] systemd[581]: Reload requested from client PID 2806 ('switch-to-confi')... machine # [ 62.070041] systemd[581]: Reloading... machine # [ 62.246801] systemd[581]: Reloading finished in 175 ms. machine # [ 62.260136] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.270836] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 62.314200] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 62.316769] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 62.318803] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 62.321600] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 62.324716] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 62.325571] systemd[1]: Stopping Reactivate sysinit units... machine # [ 62.326369] systemd[1]: Reached target Reactivate sysinit units. machine # [ 62.333322] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.339131] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 62.340979] systemd[1]: Reached target Remote File Systems. machine # [ 62.344003] systemd[1]: Starting linger-users.service... machine # [ 62.348221] systemd[1]: Reached target Local File Systems. machine # [ 62.367545] systemd[1]: Started test.service. machine # [ 62.379270] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 62.394369] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 62.395206] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 62.401072] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 62.403842] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 62.406480] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.409171] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.411443] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 62.444496] systemd[1]: test.service: Deactivated successfully. machine # [ 62.465167] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 62.468197] systemd[1]: Finished linger-users.service. machine # [ 62.733158] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 62.736256] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 62.993043] nixos[2738]: finished switching to system configuration /nix/store/zzz8fhr5yfj7x3lqx0bmd9zcbq46prmy-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&1, in 3.00 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&1 machine # [ 63.595027] nixos[2883]: switching to system configuration /nix/store/9rdif6mjxhcqb7zv79czddfxz8vy96ng-nixos-system-machine-test machine # [ 63.597776] systemd[1]: Stopped target Local File Systems. machine # [ 63.600141] systemd[1]: Stopped target Remote File Systems. machine # [ 64.085209] systemd[1]: Reload requested from client PID 2883 ('.switch-to-conf') (unit backdoor.service)... machine # [ 64.087081] systemd[1]: Reloading... machine # [ 64.706212] systemd[1]: Reloading finished in 616 ms. machine # [ 64.847320] systemd[581]: Reexecution requested from client PID 2951 ('switch-to-confi')... machine # [ 64.850909] systemd[581]: Reexecuting. machine # [ 64.913691] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.105000] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.111744] systemd[581]: Reload requested from client PID 2951 ('switch-to-confi')... machine # [ 65.114809] systemd[581]: Reloading... machine # [ 65.298629] systemd[581]: Reloading finished in 184 ms. machine # [ 65.312141] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.320632] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 65.322836] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 65.325757] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 65.371490] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 65.375381] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 65.377053] systemd[1]: Stopping Reactivate sysinit units... machine # [ 65.377824] systemd[1]: Reached target Reactivate sysinit units. machine # [ 65.381437] systemd[1]: Reached target Local File Systems. machine # [ 65.384553] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 65.388811] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 65.389659] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 65.390876] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 65.403862] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 65.406735] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 65.408988] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 65.410187] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 65.411532] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 65.414874] systemd[1]: Reached target Remote File Systems. machine # [ 65.717610] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 65.720698] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 65.724392] systemd[1]: Starting escaped\x2ddash.service... machine # [ 65.728720] systemd[1]: Starting linger-users.service... machine # [ 65.771555] systemd[1]: Finished escaped\x2ddash.service. machine # [ 65.799074] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 65.801918] systemd[1]: Finished linger-users.service. machine # [ 66.060939] nixos[2883]: finished switching to system configuration /nix/store/9rdif6mjxhcqb7zv79czddfxz8vy96ng-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&1, in 3.07 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&1 machine # [ 66.723467] nixos[3025]: switching to system configuration /nix/store/qxjgf5pnzfivsn440rn8zbjz6p9das1x-nixos-system-machine-test machine # [ 66.727852] systemd[1]: Stopped target Local File Systems. machine # [ 66.729681] systemd[1]: Stopped target Remote File Systems. machine # [ 66.730510] systemd[1]: escaped\x2ddash.service: Deactivated successfully. machine # [ 66.731464] systemd[1]: Stopped escaped\x2ddash.service. machine # [ 67.230292] systemd[1]: Reload requested from client PID 3025 ('.switch-to-conf') (unit backdoor.service)... machine # [ 67.232779] systemd[1]: Reloading... machine # [ 67.910572] systemd[1]: Reloading finished in 675 ms. machine # [ 68.067828] systemd[581]: Reexecution requested from client PID 3094 ('switch-to-confi')... machine # [ 68.070307] systemd[581]: Reexecuting. machine # [ 68.138800] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.332117] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.338828] systemd[581]: Reload requested from client PID 3094 ('switch-to-confi')... machine # [ 68.341198] systemd[581]: Reloading... machine # [ 68.530700] systemd[581]: Reloading finished in 188 ms. machine # [ 68.548149] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.557460] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 68.559745] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 68.562564] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 68.609348] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 68.613628] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 68.615529] systemd[1]: Stopping Reactivate sysinit units... machine # [ 68.617558] systemd[1]: Reached target Reactivate sysinit units. machine # [ 68.619929] systemd[1]: Reached target Local File Systems. machine # [ 68.623538] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 68.628326] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 68.629187] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 68.630471] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 68.634545] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 68.644240] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.647993] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.649772] systemd[1]: Reached target Remote File Systems. machine # [ 68.651579] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.654511] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 68.961574] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 68.966097] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 68.969694] systemd[1]: Starting escaped\x2ddash.service... machine # [ 68.972849] systemd[1]: Starting linger-users.service... machine # [ 69.016461] systemd[1]: Finished escaped\x2ddash.service. machine # [ 69.042937] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 69.045559] systemd[1]: Finished linger-users.service. machine # [ 69.303517] nixos[3025]: finished switching to system configuration /nix/store/qxjgf5pnzfivsn440rn8zbjz6p9das1x-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&1, in 3.24 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&1 machine # [ 69.935250] nixos[3167]: switching to system configuration /nix/store/qxznba6gr7f2v16rrc458a401zgxyyy8-nixos-system-machine-test machine # [ 69.939002] systemd[1]: Stopped target Local File Systems. machine # [ 69.940828] systemd[1]: Stopped target Remote File Systems. machine # [ 69.941657] systemd[1]: escaped\x2ddash.service: Deactivated successfully. machine # [ 69.942633] systemd[1]: Stopped escaped\x2ddash.service. machine # [ 70.454253] systemd[1]: Reload requested from client PID 3167 ('.switch-to-conf') (unit backdoor.service)... machine # [ 70.456356] systemd[1]: Reloading... machine # [ 71.093766] systemd[1]: Reloading finished in 635 ms. machine # [ 71.246842] systemd[581]: Reexecution requested from client PID 3236 ('switch-to-confi')... machine # [ 71.249945] systemd[581]: Reexecuting. machine # [ 71.298441] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.497026] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.503770] systemd[581]: Reload requested from client PID 3236 ('switch-to-confi')... machine # [ 71.506087] systemd[581]: Reloading... machine # [ 71.696264] systemd[581]: Reloading finished in 189 ms. machine # [ 71.713544] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.720737] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 71.722967] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 71.725780] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 71.773558] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 71.777633] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 71.780742] systemd[1]: Stopping Reactivate sysinit units... machine # [ 71.781609] systemd[1]: Reached target Reactivate sysinit units. machine # [ 71.782463] systemd[1]: Reached target Local File Systems. machine # [ 71.785221] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.786580] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 71.792437] systemd[1]: Reached target Remote File Systems. machine # [ 71.798033] systemd[1]: Starting -.service... machine # [ 71.801376] systemd[1]: Starting linger-users.service... machine # [ 71.806441] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 71.814308] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 71.815166] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 71.824980] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 71.827181] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 71.833936] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.837352] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.866615] systemd[1]: Finished -.service. machine # [ 71.902685] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 71.904994] systemd[1]: Finished linger-users.service. machine # [ 72.166767] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 72.169605] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 72.429381] nixos[3167]: finished switching to system configuration /nix/store/qxznba6gr7f2v16rrc458a401zgxyyy8-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&1, in 3.12 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&1 machine # [ 73.068842] nixos[3309]: switching to system configuration /nix/store/2bq1ggm9kl3hx7n2mm77yhs9l86k6h0n-nixos-system-machine-test machine # [ 73.072048] systemd[1]: Stopped target Local File Systems. machine # [ 73.073399] systemd[1]: Stopped target Remote File Systems. machine # [ 73.562623] systemd[1]: Reload requested from client PID 3309 ('.switch-to-conf') (unit backdoor.service)... machine # [ 73.564704] systemd[1]: Reloading... machine # [ 74.219654] systemd[1]: Reloading finished in 652 ms. machine # [ 74.368970] systemd[581]: Reexecution requested from client PID 3378 ('switch-to-confi')... machine # [ 74.372644] systemd[581]: Reexecuting. machine # [ 74.421625] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 74.610464] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 74.616616] systemd[581]: Reload requested from client PID 3378 ('switch-to-confi')... machine # [ 74.619010] systemd[581]: Reloading... machine # [ 74.808066] systemd[581]: Reloading finished in 187 ms. machine # [ 74.824157] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 74.834793] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 74.835859] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 74.838857] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 74.885507] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 74.889423] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 74.898844] systemd[1]: Stopping Reactivate sysinit units... machine # [ 74.900391] systemd[1]: Reached target Reactivate sysinit units. machine # [ 74.902350] systemd[1]: Reloading -.service... machine # [ 74.941348] systemd[1]: Reloaded -.service. machine # [ 74.943767] systemd[1]: Reached target Remote File Systems. machine # [ 74.946617] systemd[1]: Reached target Local File Systems. machine # [ 74.949970] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 74.954655] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 74.955546] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 74.959074] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 74.965961] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 74.967559] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 74.976003] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 74.977277] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 74.978586] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 75.291907] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 75.296328] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 75.298999] systemd[1]: Starting linger-users.service... machine # [ 75.362377] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 75.363943] systemd[1]: Finished linger-users.service. machine # [ 75.622629] nixos[3309]: finished switching to system configuration /nix/store/2bq1ggm9kl3hx7n2mm77yhs9l86k6h0n-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&1, in 3.19 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&1 machine # [ 76.228088] nixos[3451]: switching to system configuration /nix/store/slxsx8psy74kvvhxzah9bvqh53wxvrxv-nixos-system-machine-test machine # [ 76.230557] systemd[1]: -.service: Deactivated successfully. machine # [ 76.233701] systemd[1]: Stopped -.service. machine # [ 76.234585] systemd[1]: Stopped target Local File Systems. machine # [ 76.235423] systemd[1]: Stopped target Remote File Systems. machine # [ 76.724866] systemd[1]: Reload requested from client PID 3451 ('.switch-to-conf') (unit backdoor.service)... machine # [ 76.726794] systemd[1]: Reloading... machine # [ 77.379852] systemd[1]: Reloading finished in 650 ms. machine # [ 77.526031] systemd[581]: Reexecution requested from client PID 3520 ('switch-to-confi')... machine # [ 77.529909] systemd[581]: Reexecuting. machine # [ 77.593782] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 77.782024] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 77.789157] systemd[581]: Reload requested from client PID 3520 ('switch-to-confi')... machine # [ 77.791596] systemd[581]: Reloading... machine # [ 77.975997] systemd[581]: Reloading finished in 183 ms. machine # [ 77.992172] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.002491] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 78.003546] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 78.006375] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 78.053303] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 78.057692] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 78.059510] systemd[1]: Stopping Reactivate sysinit units... machine # [ 78.061223] systemd[1]: Reached target Reactivate sysinit units. machine # [ 78.062941] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 78.065578] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 78.069354] systemd[1]: Reached target Remote File Systems. machine # [ 78.072887] systemd[1]: Reached target Local File Systems. machine # [ 78.076388] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 78.080540] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 78.081406] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 78.082605] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 78.094606] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 78.096372] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 78.097610] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 78.409107] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 78.412220] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 78.417962] systemd[1]: Starting linger-users.service... machine # [ 78.421916] systemd[1]: Starting required-service.service... machine # [ 78.425513] systemd[1]: Starting test-service.service... machine # [ 78.473393] systemd[1]: Finished required-service.service. machine # [ 78.477680] systemd[1]: Finished test-service.service. machine # [ 78.504726] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 78.507279] systemd[1]: Finished linger-users.service. machine # [ 78.767036] nixos[3451]: finished switching to system configuration /nix/store/slxsx8psy74kvvhxzah9bvqh53wxvrxv-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&1, in 3.14 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&1 machine # [ 79.418251] nixos[3595]: switching to system configuration /nix/store/4gr70zn9x7ryj2inpk5nddn4yjnba0cz-nixos-system-machine-test machine # [ 79.422996] systemd[1]: Stopped target Local File Systems. machine # [ 79.425796] systemd[1]: Stopped target Remote File Systems. machine # [ 79.426819] systemd[1]: required-service.service: Deactivated successfully. machine # [ 79.427864] systemd[1]: Stopped required-service.service. machine # [ 79.428959] systemd[1]: test-service.service: Deactivated successfully. machine # [ 79.430086] systemd[1]: Stopped test-service.service. machine # [ 79.920923] systemd[1]: Reload requested from client PID 3595 ('.switch-to-conf') (unit backdoor.service)... machine # [ 79.922867] systemd[1]: Reloading... machine # [ 80.567101] systemd[1]: Reloading finished in 641 ms. machine # [ 80.713856] systemd[581]: Reexecution requested from client PID 3664 ('switch-to-confi')... machine # [ 80.717481] systemd[581]: Reexecuting. machine # [ 80.769726] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.959288] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.966596] systemd[581]: Reload requested from client PID 3664 ('switch-to-confi')... machine # [ 80.967871] systemd[581]: Reloading... machine # [ 81.153029] systemd[581]: Reloading finished in 183 ms. machine # [ 81.168153] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 81.177980] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 81.179019] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 81.181822] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 81.227914] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 81.231847] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 81.233505] systemd[1]: Stopping Reactivate sysinit units... machine # [ 81.234318] systemd[1]: Reached target Reactivate sysinit units. machine # [ 81.242474] systemd[1]: Reached target Remote File Systems. machine # [ 81.248309] systemd[1]: Starting linger-users.service... machine # [ 81.251762] systemd[1]: Starting required-service.service... machine # [ 81.254941] systemd[1]: Starting test-service.service... machine # [ 81.267040] systemd[1]: Reached target Local File Systems. machine # [ 81.267938] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 81.277093] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 81.282441] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 81.293780] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 81.300335] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 81.303594] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 81.307956] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 81.311530] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 81.315111] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 81.347027] systemd[1]: Finished required-service.service. machine # [ 81.349028] systemd[1]: Finished test-service.service. machine # [ 81.380321] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 81.383626] systemd[1]: Finished linger-users.service. machine # [ 81.653162] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 81.655747] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 81.915217] nixos[3595]: finished switching to system configuration /nix/store/4gr70zn9x7ryj2inpk5nddn4yjnba0cz-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&1, in 3.15 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&1 machine # [ 82.543643] nixos[3739]: switching to system configuration /nix/store/k110hnwbj1h4v3f3bwwxwvs6gai9br7r-nixos-system-machine-test machine # [ 82.546479] systemd[1]: Stopped target Local File Systems. machine # [ 82.548509] systemd[1]: Stopped target Remote File Systems. machine # [ 82.550466] systemd[1]: required-service.service: Deactivated successfully. machine # [ 82.551430] systemd[1]: Stopped required-service.service. machine # [ 82.552935] systemd[1]: test-service.service: Deactivated successfully. machine # [ 82.554073] systemd[1]: Stopped test-service.service. machine # [ 83.043328] systemd[1]: Reload requested from client PID 3739 ('.switch-to-conf') (unit backdoor.service)... machine # [ 83.045457] systemd[1]: Reloading... machine # [ 83.651811] systemd[1]: Reloading finished in 604 ms. machine # [ 83.794576] systemd[581]: Reexecution requested from client PID 3808 ('switch-to-confi')... machine # [ 83.798263] systemd[581]: Reexecuting. machine # [ 83.861602] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 84.053117] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 84.059757] systemd[581]: Reload requested from client PID 3808 ('switch-to-confi')... machine # [ 84.063405] systemd[581]: Reloading... machine # [ 84.258545] systemd[581]: Reloading finished in 196 ms. machine # [ 84.273547] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 84.281173] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 84.283489] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 84.286598] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 84.335194] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 84.339725] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 84.342664] systemd[1]: Stopping Reactivate sysinit units... machine # [ 84.343493] systemd[1]: Reached target Reactivate sysinit units. machine # [ 84.344931] systemd[1]: Reached target Local File Systems. machine # [ 84.347162] systemd[1]: Reached target Remote File Systems. machine # [ 84.352229] systemd[1]: Starting linger-users.service... machine # [ 84.357812] systemd[1]: Starting required-service.service... machine # [ 84.364825] systemd[1]: Starting test-service.service... machine # [ 84.369200] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 84.382563] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 84.385788] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 84.387081] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 84.401734] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 84.407237] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 84.411241] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 84.415085] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 84.419642] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 84.449012] systemd[1]: Finished required-service.service. machine # [ 84.457744] systemd[1]: Finished test-service.service. machine # [ 84.493220] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 84.495980] systemd[1]: Finished linger-users.service. machine # [ 84.768458] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 84.771859] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 85.028788] nixos[3739]: finished switching to system configuration /nix/store/k110hnwbj1h4v3f3bwwxwvs6gai9br7r-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&1, in 3.11 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&1 machine # [ 85.656004] nixos[3883]: switching to system configuration /nix/store/j3awzq1hl7gszm7w8xv0jl1xsxap4cgb-nixos-system-machine-test machine # [ 85.658997] systemd[1]: Stopped target Local File Systems. machine # [ 85.662306] systemd[1]: Stopped target Remote File Systems. machine # [ 85.663207] systemd[1]: test-service.service: Deactivated successfully. machine # [ 85.665624] systemd[1]: Stopped test-service.service. machine # [ 85.666721] systemd[1]: required-service.service: Deactivated successfully. machine # [ 85.667753] systemd[1]: Stopped required-service.service. machine # [ 86.170667] systemd[1]: Reload requested from client PID 3883 ('.switch-to-conf') (unit backdoor.service)... machine # [ 86.172756] systemd[1]: Reloading... machine # [ 86.821644] systemd[1]: Reloading finished in 646 ms. machine # [ 86.962134] systemd[581]: Reexecution requested from client PID 3952 ('switch-to-confi')... machine # [ 86.966378] systemd[581]: Reexecuting. machine # [ 87.025708] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 87.206181] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 87.213268] systemd[581]: Reload requested from client PID 3952 ('switch-to-confi')... machine # [ 87.215495] systemd[581]: Reloading... machine # [ 87.397827] systemd[581]: Reloading finished in 181 ms. machine # [ 87.412152] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 87.421200] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 87.423444] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 87.426141] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 87.473287] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 87.477373] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 87.479766] systemd[1]: Stopping Reactivate sysinit units... machine # [ 87.482978] systemd[1]: Reached target Reactivate sysinit units. machine # [ 87.483831] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 87.485915] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 87.490052] systemd[1]: Reached target Local File Systems. machine # [ 87.493036] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 87.498908] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 87.503321] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 87.509046] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 87.511236] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 87.517928] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 87.519292] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 87.520583] systemd[1]: Created slice Slice /system/instantiated. machine # [ 87.521413] systemd[1]: Reached target Remote File Systems. machine # [ 87.842708] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 87.847068] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 87.851211] systemd[1]: Starting instantiated@one.service... machine # [ 87.855400] systemd[1]: Starting instantiated@two.service... machine # [ 87.858622] systemd[1]: Starting linger-users.service... machine # [ 87.912562] systemd[1]: Finished instantiated@one.service. machine # [ 87.916376] systemd[1]: Finished instantiated@two.service. machine # [ 87.941583] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 87.943831] systemd[1]: Finished linger-users.service. machine # [ 88.203931] nixos[3883]: finished switching to system configuration /nix/store/j3awzq1hl7gszm7w8xv0jl1xsxap4cgb-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&1, in 3.17 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&1 machine # [ 88.842112] nixos[4027]: switching to system configuration /nix/store/kv4m8ibjy8gqkncp257qbfy4l8q4fz58-nixos-system-machine-test machine # [ 88.846729] systemd[1]: Stopped target Local File Systems. machine # [ 88.850124] systemd[1]: Stopped target Remote File Systems. machine # [ 88.851167] systemd[1]: instantiated@one.service: Deactivated successfully. machine # [ 88.852390] systemd[1]: Stopped instantiated@one.service. machine # [ 88.853380] systemd[1]: instantiated@two.service: Deactivated successfully. machine # [ 88.854632] systemd[1]: Stopped instantiated@two.service. machine # [ 89.386587] systemd[1]: Reload requested from client PID 4027 ('.switch-to-conf') (unit backdoor.service)... machine # [ 89.388629] systemd[1]: Reloading... machine # [ 90.059322] systemd[1]: Reloading finished in 668 ms. machine # [ 90.207644] systemd[581]: Reexecution requested from client PID 4096 ('switch-to-confi')... machine # [ 90.211249] systemd[581]: Reexecuting. machine # [ 90.274134] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 90.460841] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 90.467218] systemd[581]: Reload requested from client PID 4096 ('switch-to-confi')... machine # [ 90.469722] systemd[581]: Reloading... machine # [ 90.655722] systemd[581]: Reloading finished in 185 ms. machine # [ 90.668150] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 90.677424] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 90.679687] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 90.682450] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 90.728697] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 90.732879] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 90.734929] systemd[1]: Stopping Reactivate sysinit units... machine # [ 90.739312] systemd[1]: Reached target Reactivate sysinit units. machine # [ 90.740618] systemd[1]: Reached target Remote File Systems. machine # [ 90.743565] systemd[1]: Starting instantiated@one.service... machine # [ 90.747325] systemd[1]: Starting instantiated@two.service... machine # [ 90.750542] systemd[1]: Starting linger-users.service... machine # [ 90.759129] systemd[1]: Reached target Local File Systems. machine # [ 90.761902] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 90.772075] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 90.773001] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 90.774228] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 90.794586] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 90.797410] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 90.801331] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 90.804532] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 90.807655] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 90.840427] systemd[1]: Finished instantiated@one.service. machine # [ 90.842616] systemd[1]: Finished instantiated@two.service. machine # [ 90.877361] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 90.879621] systemd[1]: Finished linger-users.service. machine # [ 91.149296] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 91.152702] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 91.411859] nixos[4027]: finished switching to system configuration /nix/store/kv4m8ibjy8gqkncp257qbfy4l8q4fz58-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&1, in 3.21 seconds) (finished: subtest: services, in 65.45 seconds) subtest: failing units machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 91.986689] nixos[4171]: switching to system configuration /nix/store/r7ly5vgiiy16h1wf0db10pqxzlzffnss-nixos-system-machine-test machine # [ 91.990131] systemd[1]: instantiated@two.service: Deactivated successfully. machine # [ 91.991150] systemd[1]: Stopped instantiated@two.service. machine # [ 91.994552] systemd[1]: instantiated@one.service: Deactivated successfully. machine # [ 91.995667] systemd[1]: Stopped instantiated@one.service. machine # [ 91.996937] systemd[1]: Stopped target Remote File Systems. machine # [ 91.997916] systemd[1]: Stopped target Local File Systems. machine # [ 92.448116] systemd[1]: Reload requested from client PID 4171 ('.switch-to-conf') (unit backdoor.service)... machine # [ 92.450307] systemd[1]: Reloading... machine # [ 93.047513] systemd[1]: Reloading finished in 594 ms. machine # [ 93.192182] systemd[581]: Reexecution requested from client PID 4240 ('switch-to-confi')... machine # [ 93.195749] systemd[581]: Reexecuting. machine # [ 93.245393] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 93.437191] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 93.443955] systemd[581]: Reload requested from client PID 4240 ('switch-to-confi')... machine # [ 93.446529] systemd[581]: Reloading... machine # [ 93.637753] systemd[581]: Reloading finished in 190 ms. machine # [ 93.652156] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 93.661860] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 93.664124] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 93.667230] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 93.713062] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 93.717510] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 93.720962] systemd[1]: Stopping Reactivate sysinit units... machine # [ 93.723627] systemd[1]: Reached target Reactivate sysinit units. machine # [ 93.725844] systemd[1]: Reached target Local File Systems. machine # [ 93.726636] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 93.727904] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 93.731038] systemd[1]: Reached target Remote File Systems. machine # [ 93.733570] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 93.739965] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 93.740848] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 93.742135] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 93.746094] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 93.756121] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 93.757451] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 94.073499] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 94.076671] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 94.081902] systemd[1]: Starting linger-users.service... machine # [ 94.085025] systemd[1]: Starting test.service... machine # [ 94.128493] systemd[1]: Finished test.service. machine # [ 94.155952] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 94.158595] systemd[1]: Finished linger-users.service. machine # [ 94.418975] nixos[4171]: finished switching to system configuration /nix/store/r7ly5vgiiy16h1wf0db10pqxzlzffnss-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 3.01 seconds) machine: must fail: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 95.047760] nixos[4313]: switching to system configuration /nix/store/x7l56rw13xpa08mq7xh6vvs1sax6xblg-nixos-system-machine-test machine # [ 95.050881] systemd[1]: Stopped target Local File Systems. machine # [ 95.054192] systemd[1]: test.service: Deactivated successfully. machine # [ 95.055308] systemd[1]: Stopped test.service. machine # [ 95.056094] systemd[1]: Stopped target Remote File Systems. machine # [ 95.562538] systemd[1]: Reload requested from client PID 4313 ('.switch-to-conf') (unit backdoor.service)... machine # [ 95.564576] systemd[1]: Reloading... machine # [ 96.170528] systemd[1]: Reloading finished in 603 ms. machine # [ 96.322854] systemd[581]: Reexecution requested from client PID 4382 ('switch-to-confi')... machine # [ 96.324901] systemd[581]: Reexecuting. machine # [ 96.386021] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 96.573058] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 96.580170] systemd[581]: Reload requested from client PID 4382 ('switch-to-confi')... machine # [ 96.582562] systemd[581]: Reloading... machine # [ 96.776518] systemd[581]: Reloading finished in 193 ms. machine # [ 96.796169] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 96.805376] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 96.807754] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 96.810802] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 96.858141] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 96.862283] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 96.865573] systemd[1]: Stopping Reactivate sysinit units... machine # [ 96.866780] systemd[1]: Reached target Reactivate sysinit units. machine # [ 96.869692] systemd[1]: Reached target Remote File Systems. machine # [ 96.874824] systemd[1]: Starting linger-users.service... machine # [ 96.877798] systemd[1]: Starting test.service... machine # [ 96.884640] systemd[1]: Reached target Local File Systems. machine # [ 96.893133] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 96.894530] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 96.901012] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 96.909539] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 96.910444] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 96.911620] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 96.921135] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 96.923978] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 96.926741] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 96.957458] systemd[1]: test.service: Main process exited, code=exited, status=1/FAILURE machine # [ 96.961219] systemd[1]: test.service: Failed with result 'exit-code'. machine # [ 96.963608] systemd[1]: Failed to start test.service. machine # [ 96.996431] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 96.998765] systemd[1]: Finished linger-users.service. machine # [ 97.264169] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 97.267389] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 97.579601] nixos[4313]: switching to system configuration /nix/store/x7l56rw13xpa08mq7xh6vvs1sax6xblg-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&1, in 3.16 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1 machine # [ 98.187361] nixos[4456]: switching to system configuration /nix/store/dpffmlis1216n73zfyalckw46ipcp798-nixos-system-machine-test machine # [ 98.189955] systemd[1]: Stopped target Local File Systems. machine # [ 98.192191] systemd[1]: Stopped target Remote File Systems. machine # [ 98.700643] systemd[1]: Reload requested from client PID 4456 ('.switch-to-conf') (unit backdoor.service)... machine # [ 98.702615] systemd[1]: Reloading... machine # [ 99.353494] systemd[1]: Reloading finished in 648 ms. machine # [ 99.495649] systemd[581]: Reexecution requested from client PID 4525 ('switch-to-confi')... machine # [ 99.499519] systemd[581]: Reexecuting. machine # [ 99.553971] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 99.726440] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 99.732950] systemd[581]: Reload requested from client PID 4525 ('switch-to-confi')... machine # [ 99.735452] systemd[581]: Reloading... machine # [ 99.922143] systemd[581]: Reloading finished in 185 ms. machine # [ 99.936158] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 99.946096] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 99.948510] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 99.951453] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 99.996832] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 100.001295] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 100.003308] systemd[1]: Stopping Reactivate sysinit units... machine # [ 100.005241] systemd[1]: Reached target Reactivate sysinit units. machine # [ 100.007562] systemd[1]: Reached target Remote File Systems. machine # [ 100.016273] systemd[1]: Started autorestart.service. machine # [ 100.020439] systemd[1]: Starting linger-users.service... machine # [ 100.027039] systemd[1]: Reached target Local File Systems. machine # [ 100.034511] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 100.040800] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 100.045550] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 100.046810] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 100.052932] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 100.055358] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 100.058656] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 100.062110] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 100.064809] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 100.091730] systemd[1]: autorestart.service: Deactivated successfully. machine # [ 100.126156] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 100.129017] systemd[1]: Finished linger-users.service. machine # [ 100.391241] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 100.394672] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 100.654829] nixos[4456]: finished switching to system configuration /nix/store/dpffmlis1216n73zfyalckw46ipcp798-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 3.07 seconds) machine # [ 100.738260] systemd[1]: Stopped autorestart.service. machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1 machine # [ 101.297832] nixos[4603]: switching to system configuration /nix/store/dpffmlis1216n73zfyalckw46ipcp798-nixos-system-machine-test machine # [ 101.300920] systemd[1]: Stopped target Local File Systems. machine # [ 101.302637] systemd[1]: Stopped target Remote File Systems. machine # [ 101.774794] systemd[1]: Reload requested from client PID 4603 ('.switch-to-conf') (unit backdoor.service)... machine # [ 101.777069] systemd[1]: Reloading... machine # [ 102.409787] systemd[1]: Reloading finished in 630 ms. machine # [ 102.563094] systemd[581]: Reexecution requested from client PID 4672 ('switch-to-confi')... machine # [ 102.565183] systemd[581]: Reexecuting. machine # [ 102.625874] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 102.823218] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 102.830515] systemd[581]: Reload requested from client PID 4672 ('switch-to-confi')... machine # [ 102.833078] systemd[581]: Reloading... machine # [ 103.026486] systemd[581]: Reloading finished in 192 ms. machine # [ 103.040158] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 103.049334] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 103.051709] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 103.054524] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 103.103475] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 103.107851] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 103.110855] systemd[1]: Stopping Reactivate sysinit units... machine # [ 103.111704] systemd[1]: Reached target Reactivate sysinit units. machine # [ 103.114583] systemd[1]: Reached target Remote File Systems. machine # [ 103.120375] systemd[1]: Started autorestart.service. machine # [ 103.124940] systemd[1]: Starting linger-users.service... machine # [ 103.129045] systemd[1]: Reached target Local File Systems. machine # [ 103.140260] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 103.146906] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 103.147857] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 103.157514] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 103.164758] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 103.168998] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 103.171591] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 103.175238] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 103.177711] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 103.203467] systemd[1]: autorestart.service: Deactivated successfully. machine # [ 103.239495] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 103.242279] systemd[1]: Finished linger-users.service. machine # [ 103.523805] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 103.527562] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 103.787696] nixos[4603]: finished switching to system configuration /nix/store/dpffmlis1216n73zfyalckw46ipcp798-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 3.05 seconds) machine # [ 103.868934] systemd[1]: Stopped autorestart.service. machine: must fail: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 104.467989] nixos[4750]: switching to system configuration /nix/store/csn3d366lgsdbixlv4lqhw3amrknazx0-nixos-system-machine-test machine # [ 104.471554] systemd[1]: Stopped target Local File Systems. machine # [ 104.472722] systemd[1]: Stopped target Remote File Systems. machine # [ 104.969759] systemd[1]: Reload requested from client PID 4750 ('.switch-to-conf') (unit backdoor.service)... machine # [ 104.971840] systemd[1]: Reloading... machine # [ 105.658468] systemd[1]: Reloading finished in 684 ms. machine # [ 105.808449] systemd[581]: Reexecution requested from client PID 4819 ('switch-to-confi')... machine # [ 105.809927] systemd[581]: Reexecuting. machine # [ 105.874697] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 106.076748] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 106.084503] systemd[581]: Reload requested from client PID 4819 ('switch-to-confi')... machine # [ 106.087101] systemd[581]: Reloading... machine # [ 106.286158] systemd[581]: Reloading finished in 197 ms. machine # [ 106.304185] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 106.313331] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 106.315832] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 106.318851] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 106.367415] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 106.371946] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 106.374568] systemd[1]: Stopping Reactivate sysinit units... machine # [ 106.375426] systemd[1]: Reached target Reactivate sysinit units. machine # [ 106.377939] systemd[1]: Reached target Remote File Systems. machine # [ 106.384399] systemd[1]: Started autorestart.service. machine # [ 106.387162] systemd[1]: Starting linger-users.service... machine # [ 106.392622] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 106.393956] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 106.395195] systemd[1]: Reached target Local File Systems. machine # [ 106.407010] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 106.418147] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 106.419084] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 106.424794] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 106.427629] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 106.432857] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 106.434132] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 106.463846] systemd[1]: autorestart.service: Main process exited, code=exited, status=1/FAILURE machine # [ 106.466597] systemd[1]: autorestart.service: Failed with result 'exit-code'. machine # [ 106.502200] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 106.505621] systemd[1]: Finished linger-users.service. machine # [ 106.788917] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 106.791793] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 107.105670] nixos[4750]: switching to system configuration /nix/store/csn3d366lgsdbixlv4lqhw3amrknazx0-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&1, in 3.24 seconds) (finished: subtest: failing units, in 15.69 seconds) subtest: unit file parser machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 107.689847] nixos[4893]: switching to system configuration /nix/store/8w1qkrbnln7x21ymczhvirs6win356ij-nixos-system-machine-test machine # [ 107.692610] systemd[1]: Stopped target Local File Systems. machine # [ 107.695635] systemd[1]: Stopped target Remote File Systems. machine # [ 107.696947] systemd[1]: Stopped autorestart.service. machine # [ 108.139749] systemd[1]: Reload requested from client PID 4893 ('.switch-to-conf') (unit backdoor.service)... machine # [ 108.142059] systemd[1]: Reloading... machine # [ 108.706465] systemd[1]: Reloading finished in 562 ms. machine # [ 108.855774] systemd[581]: Reexecution requested from client PID 4962 ('switch-to-confi')... machine # [ 108.858083] systemd[581]: Reexecuting. machine # [ 108.921982] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 109.120734] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 109.128120] systemd[581]: Reload requested from client PID 4962 ('switch-to-confi')... machine # [ 109.130820] systemd[581]: Reloading... machine # [ 109.319474] systemd[581]: Reloading finished in 187 ms. machine # [ 109.336219] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 109.346003] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 109.348556] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 109.351698] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 109.399120] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 109.403615] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 109.405406] systemd[1]: Stopping Reactivate sysinit units... machine # [ 109.406260] systemd[1]: Reached target Reactivate sysinit units. machine # [ 109.411101] systemd[1]: Reached target Local File Systems. machine # [ 109.416543] systemd[1]: Reached target Remote File Systems. machine # [ 109.422793] systemd[1]: Starting linger-users.service... machine # [ 109.426611] systemd[1]: Starting test.service... machine # [ 109.433379] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 109.434820] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 109.446328] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 109.452233] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 109.453147] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 109.454386] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 109.459029] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 109.465191] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 109.471067] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 109.503510] systemd[1]: Finished test.service. machine # [ 109.543215] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 109.545825] systemd[1]: Finished linger-users.service. machine # [ 109.823165] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 109.826437] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 110.085391] nixos[4893]: finished switching to system configuration /nix/store/8w1qkrbnln7x21ymczhvirs6win356ij-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 2.98 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&1 machine # [ 110.740866] nixos[5035]: switching to system configuration /nix/store/0c26wv6v5gwff136lhnyawb2avnlxgh1-nixos-system-machine-test machine # [ 110.743342] systemd[1]: Stopped target Local File Systems. machine # [ 110.745699] systemd[1]: Stopped target Remote File Systems. machine # [ 111.259194] systemd[1]: Reload requested from client PID 5035 ('.switch-to-conf') (unit backdoor.service)... machine # [ 111.261378] systemd[1]: Reloading... machine # [ 111.923452] systemd[1]: Reloading finished in 660 ms. machine # [ 112.079127] systemd[581]: Reexecution requested from client PID 5104 ('switch-to-confi')... machine # [ 112.082630] systemd[581]: Reexecuting. machine # [ 112.150120] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 112.352588] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 112.359141] systemd[581]: Reload requested from client PID 5104 ('switch-to-confi')... machine # [ 112.361247] systemd[581]: Reloading... machine # [ 112.549744] systemd[581]: Reloading finished in 187 ms. machine # [ 112.564156] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 112.573314] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 112.575581] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 112.578413] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 112.624653] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 112.629210] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 112.630465] systemd[1]: Stopping Reactivate sysinit units... machine # [ 112.631291] systemd[1]: Reached target Reactivate sysinit units. machine # [ 112.633722] systemd[1]: test.service: Deactivated successfully. machine # [ 112.636329] systemd[1]: Stopped test.service. machine # [ 112.637078] systemd[1]: Stopping test.service... machine # [ 112.640004] systemd[1]: Starting test.service... machine # [ 112.675912] systemd[1]: Finished test.service. machine # [ 112.682409] systemd[1]: Reached target Local File Systems. machine # [ 112.683233] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 112.688146] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 112.689009] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 112.690213] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 112.702694] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 112.707073] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 112.708460] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 112.709629] systemd[1]: Reached target Remote File Systems. machine # [ 112.710393] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 112.711615] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 113.026955] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 113.031651] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 113.034375] systemd[1]: Starting linger-users.service... machine # [ 113.094748] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 113.097231] systemd[1]: Finished linger-users.service. machine # [ 113.355308] nixos[5035]: finished switching to system configuration /nix/store/0c26wv6v5gwff136lhnyawb2avnlxgh1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&1, in 3.27 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&1 machine # [ 114.010519] nixos[5177]: switching to system configuration /nix/store/rjfi4rgw28mamxf3wjgs4n5dpfhjyy0r-nixos-system-machine-test machine # [ 114.013980] systemd[1]: Stopped target Local File Systems. machine # [ 114.015432] systemd[1]: Stopped target Remote File Systems. machine # [ 114.529569] systemd[1]: Reload requested from client PID 5177 ('.switch-to-conf') (unit backdoor.service)... machine # [ 114.531571] systemd[1]: Reloading... machine # [ 115.213980] systemd[1]: Reloading finished in 679 ms. machine # [ 115.369965] systemd[581]: Reexecution requested from client PID 5246 ('switch-to-confi')... machine # [ 115.371414] systemd[581]: Reexecuting. machine # [ 115.438385] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 115.646169] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 115.652356] systemd[581]: Reload requested from client PID 5246 ('switch-to-confi')... machine # [ 115.654616] systemd[581]: Reloading... machine # [ 115.849256] systemd[581]: Reloading finished in 193 ms. machine # [ 115.864209] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 115.874274] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 115.877204] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 115.881083] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 115.931338] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 115.935426] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 115.937087] systemd[1]: Stopping Reactivate sysinit units... machine # [ 115.937953] systemd[1]: Reached target Reactivate sysinit units. machine # [ 115.939982] systemd[1]: test.service: Deactivated successfully. machine # [ 115.943005] systemd[1]: Stopped test.service. machine # [ 115.943657] systemd[1]: Stopping test.service... machine # [ 115.946100] systemd[1]: Starting test.service... machine # [ 115.980631] systemd[1]: Finished test.service. machine # [ 115.985412] systemd[1]: Reached target Remote File Systems. machine # [ 115.987874] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 115.990286] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 115.992465] systemd[1]: Reached target Local File Systems. machine # [ 115.999208] systemd[1]: Starting linger-users.service... machine # [ 116.010655] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 116.015437] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 116.017499] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 116.018869] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 116.022447] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 116.023995] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 116.028506] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 116.085561] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 116.088791] systemd[1]: Finished linger-users.service. machine # [ 116.369710] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 116.372992] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 116.629960] nixos[5177]: finished switching to system configuration /nix/store/rjfi4rgw28mamxf3wjgs4n5dpfhjyy0r-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&1, in 3.27 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 117.240268] nixos[5319]: switching to system configuration /nix/store/8w1qkrbnln7x21ymczhvirs6win356ij-nixos-system-machine-test machine # [ 117.242878] systemd[1]: Stopped target Local File Systems. machine # [ 117.245165] systemd[1]: Stopped target Remote File Systems. machine # [ 117.704868] systemd[1]: Reload requested from client PID 5319 ('.switch-to-conf') (unit backdoor.service)... machine # [ 117.706896] systemd[1]: Reloading... machine # [ 118.304656] systemd[1]: Reloading finished in 595 ms. machine # [ 118.461351] systemd[581]: Reexecution requested from client PID 5388 ('switch-to-confi')... machine # [ 118.466585] systemd[581]: Reexecuting. machine # [ 118.517995] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 118.715043] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 118.721617] systemd[581]: Reload requested from client PID 5388 ('switch-to-confi')... machine # [ 118.724165] systemd[581]: Reloading... machine # [ 118.917284] systemd[581]: Reloading finished in 191 ms. machine # [ 118.932186] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 118.943008] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 118.945616] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 118.948898] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 119.002243] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 119.007311] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 119.012635] systemd[1]: Stopping Reactivate sysinit units... machine # [ 119.013881] systemd[1]: Reached target Reactivate sysinit units. machine # [ 119.014760] systemd[1]: test.service: Deactivated successfully. machine # [ 119.015587] systemd[1]: Stopped test.service. machine # [ 119.016401] systemd[1]: Stopping test.service... machine # [ 119.018624] systemd[1]: Starting test.service... machine # [ 119.056319] systemd[1]: Finished test.service. machine # [ 119.058503] systemd[1]: Reached target Remote File Systems. machine # [ 119.064893] systemd[1]: Starting linger-users.service... machine # [ 119.073312] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 119.074668] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 119.081598] systemd[1]: Reached target Local File Systems. machine # [ 119.083309] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 119.089274] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 119.090172] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 119.091373] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 119.095089] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 119.097686] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 119.099419] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 119.159320] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 119.162775] systemd[1]: Finished linger-users.service. machine # [ 119.437031] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 119.440316] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 119.699214] nixos[5319]: finished switching to system configuration /nix/store/8w1qkrbnln7x21ymczhvirs6win356ij-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 3.07 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&1 machine # [ 120.329161] nixos[5461]: switching to system configuration /nix/store/4pikxa98hhvazfb0x8cj3rj2z4216szj-nixos-system-machine-test machine # [ 120.332694] systemd[1]: Stopped target Local File Systems. machine # [ 120.334126] systemd[1]: Stopped target Remote File Systems. machine # [ 120.852282] systemd[1]: Reload requested from client PID 5461 ('.switch-to-conf') (unit backdoor.service)... machine # [ 120.854894] systemd[1]: Reloading... machine # [ 121.525208] systemd[1]: Reloading finished in 667 ms. machine # [ 121.682581] systemd[581]: Reexecution requested from client PID 5530 ('switch-to-confi')... machine # [ 121.686248] systemd[581]: Reexecuting. machine # [ 121.741397] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 121.952954] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 121.960800] systemd[581]: Reload requested from client PID 5530 ('switch-to-confi')... machine # [ 121.963616] systemd[581]: Reloading... machine # [ 122.155981] systemd[581]: Reloading finished in 191 ms. machine # [ 122.172193] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 122.182947] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 122.184128] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 122.187219] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 122.229960] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 122.234524] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 122.238090] systemd[1]: Stopping Reactivate sysinit units... machine # [ 122.238986] systemd[1]: Reached target Reactivate sysinit units. machine # [ 122.243097] systemd[1]: Reached target Remote File Systems. machine # [ 122.249326] systemd[1]: Starting linger-users.service... machine # [ 122.257610] systemd[1]: Reached target Local File Systems. machine # [ 122.262849] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 122.269610] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 122.270541] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 122.271811] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 122.278087] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 122.284528] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 122.286698] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 122.289122] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 122.291344] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 122.351590] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 122.355559] systemd[1]: Finished linger-users.service. machine # [ 122.622733] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 122.626458] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 122.883737] nixos[5461]: finished switching to system configuration /nix/store/4pikxa98hhvazfb0x8cj3rj2z4216szj-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&1, in 3.19 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&1 machine # [ 123.496543] nixos[5601]: switching to system configuration /nix/store/c19lqp0s3gvv2ksg5y3g4w1khm6wicfb-nixos-system-machine-test machine # [ 123.500469] systemd[1]: Stopped target Remote File Systems. machine # [ 123.501634] systemd[1]: Stopped target Local File Systems. machine # [ 124.001930] systemd[1]: Reload requested from client PID 5601 ('.switch-to-conf') (unit backdoor.service)... machine # [ 124.003868] systemd[1]: Reloading... machine # [ 124.649842] systemd[1]: Reloading finished in 642 ms. machine # [ 124.795692] systemd[581]: Reexecution requested from client PID 5670 ('switch-to-confi')... machine # [ 124.799620] systemd[581]: Reexecuting. machine # [ 124.861561] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 125.043053] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 125.049711] systemd[581]: Reload requested from client PID 5670 ('switch-to-confi')... machine # [ 125.052369] systemd[581]: Reloading... machine # [ 125.234029] systemd[581]: Reloading finished in 180 ms. machine # [ 125.248178] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 125.258378] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 125.261002] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 125.264100] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 125.309048] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 125.313394] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 125.315368] systemd[1]: Stopping Reactivate sysinit units... machine # [ 125.316957] systemd[1]: Reached target Reactivate sysinit units. machine # [ 125.320220] systemd[1]: test.service: Deactivated successfully. machine # [ 125.321121] systemd[1]: Stopped test.service. machine # [ 125.321775] systemd[1]: Stopping test.service... machine # [ 125.324387] systemd[1]: Starting test.service... machine # [ 125.359342] systemd[1]: Finished test.service. machine # [ 125.367785] systemd[1]: Reached target Remote File Systems. machine # [ 125.370418] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 125.373302] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 125.374610] systemd[1]: Reached target Local File Systems. machine # [ 125.377369] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 125.384655] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 125.387005] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 125.388967] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 125.391410] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 125.393335] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 125.398708] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 125.714585] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 125.718869] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 125.721591] systemd[1]: Starting linger-users.service... machine # [ 125.783309] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 125.786218] systemd[1]: Finished linger-users.service. machine # [ 126.045183] nixos[5601]: finished switching to system configuration /nix/store/c19lqp0s3gvv2ksg5y3g4w1khm6wicfb-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&1, in 3.16 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&1 machine # [ 126.701497] nixos[5743]: switching to system configuration /nix/store/1xy4rzdi8rmmk10s23cylq1lrl47k85j-nixos-system-machine-test machine # [ 126.704439] systemd[1]: Stopped target Local File Systems. machine # [ 126.706980] systemd[1]: Stopped target Remote File Systems. machine # [ 127.235401] systemd[1]: Reload requested from client PID 5743 ('.switch-to-conf') (unit backdoor.service)... machine # [ 127.237700] systemd[1]: Reloading... machine # [ 127.925209] systemd[1]: Reloading finished in 685 ms. machine # [ 128.068668] systemd[581]: Reexecution requested from client PID 5812 ('switch-to-confi')... machine # [ 128.073367] systemd[581]: Reexecuting. machine # [ 128.117726] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 128.309253] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 128.316002] systemd[581]: Reload requested from client PID 5812 ('switch-to-confi')... machine # [ 128.318591] systemd[581]: Reloading... machine # [ 128.508449] systemd[581]: Reloading finished in 188 ms. machine # [ 128.524164] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 128.534420] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 128.535477] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 128.538590] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 128.587544] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 128.591616] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 128.593873] systemd[1]: Stopping Reactivate sysinit units... machine # [ 128.594688] systemd[1]: Reached target Reactivate sysinit units. machine # [ 128.598382] systemd[1]: test.service: Deactivated successfully. machine # [ 128.599295] systemd[1]: Stopped test.service. machine # [ 128.599929] systemd[1]: Stopping test.service... machine # [ 128.602409] systemd[1]: Starting test.service... machine # [ 128.638908] systemd[1]: Finished test.service. machine # [ 128.643212] systemd[1]: Reached target Local File Systems. machine # [ 128.645095] systemd[1]: Reached target Remote File Systems. machine # [ 128.648974] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 128.655537] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 128.658654] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 128.661234] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 128.663432] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 128.666163] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 128.675481] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 128.676908] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 128.678193] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 128.994188] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 128.998988] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 129.001461] systemd[1]: Starting linger-users.service... machine # [ 129.064988] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 129.067471] systemd[1]: Finished linger-users.service. machine # [ 129.326058] nixos[5743]: finished switching to system configuration /nix/store/1xy4rzdi8rmmk10s23cylq1lrl47k85j-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&1, in 3.28 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&1 machine # [ 129.932987] nixos[5885]: switching to system configuration /nix/store/irk09arrbwf6w42569g461hq6bjccw1i-nixos-system-machine-test machine # [ 129.935689] systemd[1]: Stopped target Remote File Systems. machine # [ 129.937860] systemd[1]: Stopped target Local File Systems. machine # [ 130.439189] systemd[1]: Reload requested from client PID 5885 ('.switch-to-conf') (unit backdoor.service)... machine # [ 130.441303] systemd[1]: Reloading... machine # [ 131.097983] systemd[1]: Reloading finished in 654 ms. machine # [ 131.244426] systemd[581]: Reexecution requested from client PID 5954 ('switch-to-confi')... machine # [ 131.247478] systemd[581]: Reexecuting. machine # [ 131.309923] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 131.501255] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 131.507523] systemd[581]: Reload requested from client PID 5954 ('switch-to-confi')... machine # [ 131.510249] systemd[581]: Reloading... machine # [ 131.698402] systemd[581]: Reloading finished in 187 ms. machine # [ 131.712158] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 131.722506] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 131.723546] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 131.726537] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 131.772317] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 131.776716] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 131.778748] systemd[1]: Stopping Reactivate sysinit units... machine # [ 131.782823] systemd[1]: Reached target Reactivate sysinit units. machine # [ 131.783727] systemd[1]: test.service: Deactivated successfully. machine # [ 131.784733] systemd[1]: Stopped test.service. machine # [ 131.785732] systemd[1]: Stopping test.service... machine # [ 131.787613] systemd[1]: Starting test.service... machine # [ 131.823002] systemd[1]: Finished test.service. machine # [ 131.826160] systemd[1]: Reached target Remote File Systems. machine # [ 131.828956] systemd[1]: Reached target Local File Systems. machine # [ 131.834375] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 131.838132] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 131.844078] systemd[1]: Starting linger-users.service... machine # [ 131.851047] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 131.859001] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 131.859895] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 131.862610] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 131.868224] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 131.869883] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 131.874909] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 131.934582] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 131.938537] systemd[1]: Finished linger-users.service. machine # [ 132.210329] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 132.213534] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 132.471547] nixos[5885]: finished switching to system configuration /nix/store/irk09arrbwf6w42569g461hq6bjccw1i-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&1, in 3.15 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 133.041551] nixos[6027]: switching to system configuration /nix/store/8w1qkrbnln7x21ymczhvirs6win356ij-nixos-system-machine-test machine # [ 133.044297] systemd[1]: Stopped target Remote File Systems. machine # [ 133.046740] systemd[1]: Stopped target Local File Systems. machine # [ 133.495181] systemd[1]: Reload requested from client PID 6027 ('.switch-to-conf') (unit backdoor.service)... machine # [ 133.497431] systemd[1]: Reloading... machine # [ 134.083274] systemd[1]: Reloading finished in 583 ms. machine # [ 134.221350] systemd[581]: Reexecution requested from client PID 6096 ('switch-to-confi')... machine # [ 134.226270] systemd[581]: Reexecuting. machine # [ 134.269615] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 134.451958] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 134.459022] systemd[581]: Reload requested from client PID 6096 ('switch-to-confi')... machine # [ 134.461679] systemd[581]: Reloading... machine # [ 134.643751] systemd[581]: Reloading finished in 181 ms. machine # [ 134.657981] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 134.666167] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 134.667127] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 134.669911] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 134.716424] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 134.720562] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 134.721514] systemd[1]: Stopping Reactivate sysinit units... machine # [ 134.723068] systemd[1]: Reached target Reactivate sysinit units. machine # [ 134.727072] systemd[1]: test.service: Deactivated successfully. machine # [ 134.728312] systemd[1]: Stopped test.service. machine # [ 134.730113] systemd[1]: Stopping test.service... machine # [ 134.731107] systemd[1]: Starting test.service... machine # [ 134.766061] systemd[1]: Finished test.service. machine # [ 134.768608] systemd[1]: Reached target Local File Systems. machine # [ 134.770690] systemd[1]: Reached target Remote File Systems. machine # [ 134.776281] systemd[1]: Starting linger-users.service... machine # [ 134.785288] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 134.786670] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 134.793262] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 134.801715] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 134.802601] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 134.803784] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 134.806767] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 134.809151] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 134.810871] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 134.868974] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 134.870734] systemd[1]: Finished linger-users.service. machine # [ 135.140340] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 135.143358] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 135.401814] nixos[6027]: finished switching to system configuration /nix/store/8w1qkrbnln7x21ymczhvirs6win356ij-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 2.93 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&1 machine # [ 136.033455] nixos[6169]: switching to system configuration /nix/store/6yzjjnn9sij0pl2nhnhqlpbch23x8ivh-nixos-system-machine-test machine # [ 136.035947] systemd[1]: Stopped target Remote File Systems. machine # [ 136.038312] systemd[1]: Stopped target Local File Systems. machine # [ 136.569495] systemd[1]: Reload requested from client PID 6169 ('.switch-to-conf') (unit backdoor.service)... machine # [ 136.571504] systemd[1]: Reloading... machine # [ 137.219126] systemd[1]: Reloading finished in 645 ms. machine # [ 137.364434] systemd[581]: Reexecution requested from client PID 6238 ('switch-to-confi')... machine # [ 137.365994] systemd[581]: Reexecuting. machine # [ 137.426112] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 137.621652] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 137.628607] systemd[581]: Reload requested from client PID 6238 ('switch-to-confi')... machine # [ 137.631430] systemd[581]: Reloading... machine # [ 137.830467] systemd[581]: Reloading finished in 197 ms. machine # [ 137.844209] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 137.856087] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 137.857234] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 137.860431] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 137.916170] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 137.921509] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 137.926812] systemd[1]: Stopping Reactivate sysinit units... machine # [ 137.928382] systemd[1]: Reached target Reactivate sysinit units. machine # [ 137.931195] systemd[1]: Reloading test.service... machine # [ 137.970898] systemd[1]: Reloaded test.service. machine # [ 137.975791] systemd[1]: Reached target Remote File Systems. machine # [ 137.979657] systemd[1]: Reached target Local File Systems. machine # [ 137.982981] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 137.990844] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 137.991813] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 137.993523] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 137.998291] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 138.005943] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 138.007218] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 138.013592] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 138.015045] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 138.338002] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 138.342712] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 138.345693] systemd[1]: Starting linger-users.service... machine # [ 138.409060] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 138.412178] systemd[1]: Finished linger-users.service. machine # [ 138.669268] nixos[6169]: finished switching to system configuration /nix/store/6yzjjnn9sij0pl2nhnhqlpbch23x8ivh-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 3.27 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&1 machine # [ 139.302307] nixos[6311]: switching to system configuration /nix/store/ic2qxchr51l4jk5fnlyazazh036k7p7h-nixos-system-machine-test machine # [ 139.306061] systemd[1]: Stopped target Local File Systems. machine # [ 139.307572] systemd[1]: Stopped target Remote File Systems. machine # [ 139.855492] systemd[1]: Reload requested from client PID 6311 ('.switch-to-conf') (unit backdoor.service)... machine # [ 139.857774] systemd[1]: Reloading... machine # [ 140.535309] systemd[1]: Reloading finished in 675 ms. machine # [ 140.686913] systemd[581]: Reexecution requested from client PID 6380 ('switch-to-confi')... machine # [ 140.689077] systemd[581]: Reexecuting. machine # [ 140.754397] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 140.951125] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 140.958161] systemd[581]: Reload requested from client PID 6380 ('switch-to-confi')... machine # [ 140.960508] systemd[581]: Reloading... machine # [ 141.154697] systemd[581]: Reloading finished in 193 ms. machine # [ 141.168176] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 141.178309] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 141.180836] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 141.183907] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 141.231900] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 141.236497] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 141.240518] systemd[1]: Stopping Reactivate sysinit units... machine # [ 141.241443] systemd[1]: Reached target Reactivate sysinit units. machine # [ 141.244831] systemd[1]: Reloading test.service... machine # [ 141.284632] systemd[1]: Reloaded test.service. machine # [ 141.287237] systemd[1]: Reached target Remote File Systems. machine # [ 141.291049] systemd[1]: Reached target Local File Systems. machine # [ 141.297608] systemd[1]: Starting linger-users.service... machine # [ 141.308657] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 141.314119] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 141.320053] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 141.321706] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 141.328959] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 141.330721] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 141.332818] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 141.335159] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 141.337405] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 141.400316] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 141.401370] systemd[1]: Finished linger-users.service. machine # [ 141.693260] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 141.696625] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 141.956561] nixos[6311]: finished switching to system configuration /nix/store/ic2qxchr51l4jk5fnlyazazh036k7p7h-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&1, in 3.28 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&1 machine # [ 142.602813] nixos[6453]: switching to system configuration /nix/store/1ycalbazc4valagma4v1j01p36rg1ays-nixos-system-machine-test machine # [ 142.605304] systemd[1]: Stopped target Local File Systems. machine # [ 142.607196] systemd[1]: Stopped target Remote File Systems. machine # [ 143.117753] systemd[1]: Reload requested from client PID 6453 ('.switch-to-conf') (unit backdoor.service)... machine # [ 143.119693] systemd[1]: Reloading... machine # [ 143.793799] systemd[1]: Reloading finished in 670 ms. machine # [ 143.944435] systemd[581]: Reexecution requested from client PID 6522 ('switch-to-confi')... machine # [ 143.947774] systemd[581]: Reexecuting. machine # [ 144.006334] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 144.197450] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 144.204164] systemd[581]: Reload requested from client PID 6522 ('switch-to-confi')... machine # [ 144.206603] systemd[581]: Reloading... machine # [ 144.397653] systemd[581]: Reloading finished in 189 ms. machine # [ 144.412203] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 144.422463] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 144.425274] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 144.428435] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 144.480486] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 144.486996] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 144.490044] systemd[1]: Stopping Reactivate sysinit units... machine # [ 144.491003] systemd[1]: Reached target Reactivate sysinit units. machine # [ 144.494625] systemd[1]: test.service: Deactivated successfully. machine # [ 144.495628] systemd[1]: Stopped test.service. machine # [ 144.496614] systemd[1]: Stopping test.service... machine # [ 144.499138] systemd[1]: Starting test.service... machine # [ 144.536565] systemd[1]: Finished test.service. machine # [ 144.543219] systemd[1]: Reached target Local File Systems. machine # [ 144.547114] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 144.549153] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 144.553400] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 144.555019] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 144.555817] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 144.565846] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 144.573790] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 144.575412] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 144.576777] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 144.577973] systemd[1]: Reached target Remote File Systems. machine # [ 144.899229] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 144.901987] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 144.907165] systemd[1]: Starting linger-users.service... machine # [ 144.972935] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 144.975336] systemd[1]: Finished linger-users.service. machine # [ 145.236263] nixos[6453]: finished switching to system configuration /nix/store/1ycalbazc4valagma4v1j01p36rg1ays-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&1, in 3.28 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&1 machine # [ 145.836439] nixos[6595]: switching to system configuration /nix/store/c19lqp0s3gvv2ksg5y3g4w1khm6wicfb-nixos-system-machine-test machine # [ 145.839235] systemd[1]: Stopped target Local File Systems. machine # [ 145.841452] systemd[1]: Stopped target Remote File Systems. machine # [ 146.312900] systemd[1]: Reload requested from client PID 6595 ('.switch-to-conf') (unit backdoor.service)... machine # [ 146.314728] systemd[1]: Reloading... machine # [ 146.931532] systemd[1]: Reloading finished in 613 ms. machine # [ 147.067158] systemd[581]: Reexecution requested from client PID 6664 ('switch-to-confi')... machine # [ 147.069620] systemd[581]: Reexecuting. machine # [ 147.135293] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 147.327038] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 147.333825] systemd[581]: Reload requested from client PID 6664 ('switch-to-confi')... machine # [ 147.336351] systemd[581]: Reloading... machine # [ 147.524203] systemd[581]: Reloading finished in 185 ms. machine # [ 147.540173] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 147.550789] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 147.551803] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 147.554764] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 147.600715] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 147.604885] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 147.607893] systemd[1]: Stopping Reactivate sysinit units... machine # [ 147.609372] systemd[1]: Reached target Reactivate sysinit units. machine # [ 147.610666] systemd[1]: Reached target Local File Systems. machine # [ 147.613838] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 147.620884] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 147.624206] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 147.627450] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 147.635824] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 147.639235] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 147.642126] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 147.643318] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 147.644909] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 147.646331] systemd[1]: Reached target Remote File Systems. machine # [ 147.932255] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 147.936620] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 147.939287] systemd[1]: Starting linger-users.service... machine # [ 147.998724] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 148.001368] systemd[1]: Finished linger-users.service. machine # [ 148.259443] nixos[6595]: finished switching to system configuration /nix/store/c19lqp0s3gvv2ksg5y3g4w1khm6wicfb-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&1, in 3.02 seconds) (finished: subtest: unit file parser, in 41.16 seconds) subtest: restart and reload by activation script machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1 machine # [ 148.823127] nixos[6735]: switching to system configuration /nix/store/12zh8y7plvai6dd6gnzisvgwx1593956-nixos-system-machine-test machine # [ 148.825932] systemd[1]: Stopped target Remote File Systems. machine # [ 148.828518] systemd[1]: Stopped target Local File Systems. machine # [ 149.276571] systemd[1]: Reload requested from client PID 6735 ('.switch-to-conf') (unit backdoor.service)... machine # [ 149.278502] systemd[1]: Reloading... machine # [ 149.885285] systemd[1]: Reloading finished in 604 ms. machine # [ 150.035035] systemd[581]: Reexecution requested from client PID 6804 ('switch-to-confi')... machine # [ 150.037087] systemd[581]: Reexecuting. machine # [ 150.089981] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 150.280191] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 150.288241] systemd[581]: Reload requested from client PID 6804 ('switch-to-confi')... machine # [ 150.290488] systemd[581]: Reloading... machine # [ 150.483182] systemd[581]: Reloading finished in 191 ms. machine # [ 150.497673] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 150.507077] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 150.508211] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 150.511437] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 150.558986] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 150.563690] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 150.566253] systemd[1]: Stopping Reactivate sysinit units... machine # [ 150.567149] systemd[1]: Reached target Reactivate sysinit units. machine # [ 150.570516] systemd[1]: Reached target Remote File Systems. machine # [ 150.576384] systemd[1]: Starting linger-users.service... machine # [ 150.587293] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 150.591852] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 150.593384] systemd[1]: Reached target Local File Systems. machine # [ 150.594178] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 150.601848] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 150.607683] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 150.609394] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 150.616262] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 150.617901] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 150.619096] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 150.674685] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 150.676388] systemd[1]: Finished linger-users.service. machine # [ 150.946917] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 150.950553] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 151.207783] nixos[6735]: finished switching to system configuration /nix/store/12zh8y7plvai6dd6gnzisvgwx1593956-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 2.95 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 151.866412] nixos[6875]: switching to system configuration /nix/store/c2hv4d4c4s3qhdpgbpazm22whvpd46rj-nixos-system-machine-test machine # [ 151.870512] systemd[1]: Stopped target Local File Systems. machine # [ 151.872329] systemd[1]: Stopped target Remote File Systems. machine # [ 151.873204] systemd[1]: test.service: Deactivated successfully. machine # [ 151.874043] systemd[1]: Stopped test.service. machine # [ 152.417365] systemd[1]: Reload requested from client PID 6875 ('.switch-to-conf') (unit backdoor.service)... machine # [ 152.419448] systemd[1]: Reloading... machine # [ 153.114174] systemd[1]: Reloading finished in 690 ms. machine # [ 153.264446] systemd[581]: Reexecution requested from client PID 6946 ('switch-to-confi')... machine # [ 153.265877] systemd[581]: Reexecuting. machine # [ 153.329729] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 153.526883] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 153.533383] systemd[581]: Reload requested from client PID 6946 ('switch-to-confi')... machine # [ 153.535885] systemd[581]: Reloading... machine # [ 153.727753] systemd[581]: Reloading finished in 190 ms. machine # [ 153.740158] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 153.750068] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 153.752556] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 153.755448] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 153.802446] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 153.806651] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 153.808609] systemd[1]: Stopping Reactivate sysinit units... machine # [ 153.809710] systemd[1]: Reached target Reactivate sysinit units. machine # [ 153.817298] systemd[1]: Starting no-restart-service.service... machine # [ 153.834066] systemd[1]: Created slice Slice /system/templated-simple-reload-service. machine # [ 153.839587] systemd[1]: Starting templated-simple-reload-service@instance.service... machine # [ 153.856087] systemd[1]: Created slice Slice /system/templated-simple-service. machine # [ 153.861279] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 153.868613] systemd[1]: Reached target Local File Systems. machine # [ 153.870711] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 153.884433] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 153.885330] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 153.886609] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 153.900849] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 153.903275] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 153.907032] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 153.919181] systemd[1]: Created slice Slice /system/templated-simple-restart-service. machine # [ 153.940462] systemd[1]: Created slice Slice /system/templated-no-restart-service. machine # [ 153.951253] systemd[1]: Reached target Remote File Systems. machine # [ 153.964611] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 153.966112] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 153.967501] systemd[1]: Finished no-restart-service.service. machine # [ 153.982393] systemd[1]: Created slice Slice /system/templated-reload-triggers. machine # [ 153.986712] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart. machine # [ 153.995608] systemd[1]: Finished templated-simple-reload-service@instance.service. machine # [ 154.013288] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart-by-as. machine # [ 154.017345] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 154.325992] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 154.330832] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 154.334946] systemd[1]: Starting linger-users.service... machine # [ 154.339150] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 154.343534] systemd[1]: Starting reload-triggers-and-restart.service... machine # [ 154.353797] systemd[1]: Starting reload-triggers.service... machine # [ 154.358605] systemd[1]: Starting simple-reload-service.service... machine # [ 154.366771] systemd[1]: Starting simple-restart-service.service... machine # [ 154.380356] systemd[1]: Starting simple-service.service... machine # [ 154.390125] systemd[1]: Starting templated-no-restart-service@instance.service... machine # [ 154.405735] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 154.424668] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service... machine # [ 154.426049] systemd[1]: Starting templated-reload-triggers@instance.service... machine # [ 154.463206] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 154.509902] systemd[1]: Finished reload-triggers-and-restart.service. machine # [ 154.517315] systemd[1]: Finished templated-no-restart-service@instance.service. machine # [ 154.537317] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 154.581259] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 154.582386] systemd[1]: Finished simple-reload-service.service. machine # [ 154.583208] systemd[1]: Finished simple-restart-service.service. machine # [ 154.595257] systemd[1]: Finished simple-service.service. machine # [ 154.598938] systemd[1]: Finished reload-triggers.service. machine # [ 154.601382] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service. machine # [ 154.610816] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 154.614423] systemd[1]: Finished templated-reload-triggers@instance.service. machine # [ 154.633051] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 154.635331] systemd[1]: Finished linger-users.service. machine # [ 154.895619] nixos[6875]: finished switching to system configuration /nix/store/c2hv4d4c4s3qhdpgbpazm22whvpd46rj-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 3.69 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 155.608859] nixos[7045]: switching to system configuration /nix/store/c2hv4d4c4s3qhdpgbpazm22whvpd46rj-nixos-system-machine-test machine # [ 155.611992] systemd[1]: Stopped target Local File Systems. machine # [ 155.614876] systemd[1]: Stopped target Remote File Systems. machine # [ 156.129544] systemd[1]: Reload requested from client PID 7045 ('.switch-to-conf') (unit backdoor.service)... machine # [ 156.131477] systemd[1]: Reloading... machine # [ 156.786239] systemd[1]: Reloading finished in 652 ms. machine # [ 156.936681] systemd[581]: Reexecution requested from client PID 7116 ('switch-to-confi')... machine # [ 156.940959] systemd[581]: Reexecuting. machine # [ 156.994011] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 157.187898] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 157.195871] systemd[581]: Reload requested from client PID 7116 ('switch-to-confi')... machine # [ 157.199306] systemd[581]: Reloading... machine # [ 157.390737] systemd[581]: Reloading finished in 192 ms. machine # [ 157.404185] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 157.413837] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 157.416149] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 157.419392] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 157.465183] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 157.469652] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 157.470564] systemd[1]: Stopping Reactivate sysinit units... machine # [ 157.471348] systemd[1]: Reached target Reactivate sysinit units. machine # [ 157.478089] systemd[1]: Reloading reload-triggers-and-restart.service... machine # [ 157.486291] systemd[1]: Reloading reload-triggers.service... machine # [ 157.492480] systemd[1]: Reloading simple-reload-service.service... machine # [ 157.506307] systemd[1]: Reloading templated-reload-triggers-and-restart@instance.service... machine # [ 157.512968] systemd[1]: Reloading templated-reload-triggers@instance.service... machine # [ 157.520561] systemd[1]: Reloading templated-simple-reload-service@instance.service... machine # [ 157.572290] systemd[1]: Reloaded reload-triggers-and-restart.service. machine # [ 157.582211] systemd[1]: Reloaded reload-triggers.service. machine # [ 157.587210] systemd[1]: Reloaded templated-reload-triggers-and-restart@instance.service. machine # [ 157.591893] systemd[1]: Reloaded simple-reload-service.service. machine # [ 157.600824] systemd[1]: Reloaded templated-reload-triggers@instance.service. machine # [ 157.603750] systemd[1]: Reloaded templated-simple-reload-service@instance.service. machine # [ 157.607146] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully. machine # [ 157.610588] systemd[1]: Stopped reload-triggers-and-restart-by-as.service. machine # [ 157.611775] systemd[1]: Stopping reload-triggers-and-restart-by-as.service... machine # [ 157.614585] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 157.618486] systemd[1]: simple-restart-service.service: Deactivated successfully. machine # [ 157.619796] systemd[1]: Stopped simple-restart-service.service. machine # [ 157.621284] systemd[1]: Stopping simple-restart-service.service... machine # [ 157.628720] systemd[1]: Starting simple-restart-service.service... machine # [ 157.629657] systemd[1]: simple-service.service: Deactivated successfully. machine # [ 157.630580] systemd[1]: Stopped simple-service.service. machine # [ 157.631295] systemd[1]: Stopping simple-service.service... machine # [ 157.645074] systemd[1]: Starting simple-service.service... machine # [ 157.646330] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully. machine # [ 157.650095] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 157.651685] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 157.658499] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 157.664701] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully. machine # [ 157.676354] systemd[1]: Stopped templated-simple-restart-service@instance.service. machine # [ 157.677552] systemd[1]: Stopping templated-simple-restart-service@instance.service... machine # [ 157.678683] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 157.679706] systemd[1]: templated-simple-service@instance.service: Deactivated successfully. machine # [ 157.685986] systemd[1]: Stopped templated-simple-service@instance.service. machine # [ 157.687518] systemd[1]: Stopping templated-simple-service@instance.service... machine # [ 157.698962] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 157.735256] systemd[1]: Finished simple-service.service. machine # [ 157.740789] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 157.743640] systemd[1]: Finished simple-restart-service.service. machine # [ 157.752613] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 157.755581] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 157.762764] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 157.769388] systemd[1]: Reached target Local File Systems. machine # [ 157.771991] systemd[1]: Reached target Remote File Systems. machine # [ 157.778091] systemd[1]: Starting linger-users.service... machine # [ 157.782576] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 157.790897] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 157.791848] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 157.801337] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 157.803568] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 157.807406] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 157.810562] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 157.812969] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 157.815418] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 157.874789] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 157.877323] systemd[1]: Finished linger-users.service. machine # [ 158.142849] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 158.146199] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 158.403242] nixos[7045]: finished switching to system configuration /nix/store/c2hv4d4c4s3qhdpgbpazm22whvpd46rj-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 3.51 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&1 machine # [ 159.192179] nixos[7211]: switching to system configuration /nix/store/fcvqsmcbfrq8nqh280idms8f6pp293nn-nixos-system-machine-test machine # [ 159.195532] systemd[1]: Stopped target Local File Systems. machine # [ 159.196928] systemd[1]: Stopped target Remote File Systems. machine # [ 159.770332] systemd[1]: Reload requested from client PID 7211 ('.switch-to-conf') (unit backdoor.service)... machine # [ 159.772350] systemd[1]: Reloading... machine # [ 160.502949] systemd[1]: Reloading finished in 727 ms. machine # [ 160.659893] systemd[581]: Reexecution requested from client PID 7282 ('switch-to-confi')... machine # [ 160.662091] systemd[581]: Reexecuting. machine # [ 160.730084] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 160.928485] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 160.935636] systemd[581]: Reload requested from client PID 7282 ('switch-to-confi')... machine # [ 160.938995] systemd[581]: Reloading... machine # [ 161.111964] systemd[581]: Reloading finished in 173 ms. machine # [ 161.124152] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 161.133962] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 161.136112] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 161.139137] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 161.184620] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 161.188607] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 161.193445] systemd[1]: Stopping Reactivate sysinit units... machine # [ 161.194343] systemd[1]: Reached target Reactivate sysinit units. machine # [ 161.197038] systemd[1]: Reloading reload-triggers.service... machine # [ 161.204284] systemd[1]: Reloading simple-reload-service.service... machine # [ 161.207387] systemd[1]: Reloading templated-reload-triggers@instance.service... machine # [ 161.220607] systemd[1]: Reloading templated-simple-reload-service@instance.service... machine # [ 161.269166] systemd[1]: Reloaded reload-triggers.service. machine # [ 161.271874] systemd[1]: Reloaded simple-reload-service.service. machine # [ 161.276661] systemd[1]: Reloaded templated-reload-triggers@instance.service. machine # [ 161.282916] systemd[1]: Reloaded templated-simple-reload-service@instance.service. machine # [ 161.286368] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully. machine # [ 161.289876] systemd[1]: Stopped reload-triggers-and-restart-by-as.service. machine # [ 161.291033] systemd[1]: Stopping reload-triggers-and-restart-by-as.service... machine # [ 161.293911] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 161.297697] systemd[1]: reload-triggers-and-restart.service: Deactivated successfully. machine # [ 161.299233] systemd[1]: Stopped reload-triggers-and-restart.service. machine # [ 161.304662] systemd[1]: Stopping reload-triggers-and-restart.service... machine # [ 161.305857] systemd[1]: Starting reload-triggers-and-restart.service... machine # [ 161.315011] systemd[1]: simple-restart-service.service: Deactivated successfully. machine # [ 161.317096] systemd[1]: Stopped simple-restart-service.service. machine # [ 161.326007] systemd[1]: Stopping simple-restart-service.service... machine # [ 161.334180] systemd[1]: Starting simple-restart-service.service... machine # [ 161.337799] systemd[1]: simple-service.service: Deactivated successfully. machine # [ 161.339240] systemd[1]: Stopped simple-service.service. machine # [ 161.351099] systemd[1]: Stopping simple-service.service... machine # [ 161.362798] systemd[1]: Starting simple-service.service... machine # [ 161.370108] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully. machine # [ 161.371543] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 161.393813] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 161.401803] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 161.412460] systemd[1]: templated-reload-triggers-and-restart@instance.service: Deactivated successfully. machine # [ 161.426005] systemd[1]: Stopped templated-reload-triggers-and-restart@instance.service. machine # [ 161.445452] systemd[1]: Stopping templated-reload-triggers-and-restart@instance.service... machine # [ 161.446799] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service... machine # [ 161.447882] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully. machine # [ 161.450607] systemd[1]: Stopped templated-simple-restart-service@instance.service. machine # [ 161.452629] systemd[1]: Stopping templated-simple-restart-service@instance.service... machine # [ 161.456813] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 161.458261] systemd[1]: templated-simple-service@instance.service: Deactivated successfully. machine # [ 161.460512] systemd[1]: Stopped templated-simple-service@instance.service. machine # [ 161.461775] systemd[1]: Stopping templated-simple-service@instance.service... machine # [ 161.462967] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 161.464637] systemd[1]: Finished reload-triggers-and-restart.service. machine # [ 161.466360] systemd[1]: Finished simple-restart-service.service. machine # [ 161.467773] systemd[1]: Finished simple-service.service. machine # [ 161.469549] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 161.477963] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service. machine # [ 161.480515] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 161.484890] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 161.489210] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 161.493432] systemd[1]: Reached target Local File Systems. machine # [ 161.497048] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 161.501131] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 161.502016] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 161.503461] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 161.516487] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 161.518108] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 161.519314] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 161.522298] systemd[1]: Reached target Remote File Systems. machine # [ 161.523937] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 161.525332] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 161.838904] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 161.843554] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 161.846216] systemd[1]: Starting linger-users.service... machine # [ 161.907432] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 161.910434] systemd[1]: Finished linger-users.service. machine # [ 162.167275] nixos[7211]: finished switching to system configuration /nix/store/fcvqsmcbfrq8nqh280idms8f6pp293nn-nixos-system-machine-test machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&1, in 3.76 seconds) machine: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration dry-activate 2>&1 machine: (finished: must succeed: /nix/store/kz11kvzsjdhwhh3qp3ack06r7ibgf35m-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration dry-activate 2>&1, in 0.90 seconds) (finished: subtest: restart and reload by activation script, in 14.81 seconds) (finished: run the VM test script, in 163.83 seconds) test script finished in 163.88s cleanup kill QemuMachine (pid 45) machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14) (finished: cleanup, in 0.21 seconds) additionally exposed symbols: machine, other, vlan1, 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