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.01 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 # Disk image does not exist, creating the virtualisation disk image... machine: QEMU running (pid 45) machine # Formatting '/build/vm-state-machine/tmp.qxg8ldsMSs', 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: cde7f0cc-4b78-46bc-b375-eaef85d57601 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 # Starting virtiofs daemons... machine # [2026-09-22T02:58:43Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-22T02:58:43Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-22T02:58:43Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-22T02:58:43Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-22T02:58:43Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-22T02:58:43Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-22T02:58:43Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-22T02:58:43Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-22T02:58:43Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-22T02:58:43Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-22T02:58:43Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-22T02:58:43Z INFO virtiofsd] Client connected, servicing requests machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 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 s186712 r8192 d116392 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/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/init regInfo=/nix/.ro-store/zf0fbr8l912346haldma0wba9iqqkwfn-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/zf0fbr8l912346haldma0wba9iqqkwfn-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 74950 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 @44cf0000 (indirect, esz 8, psz 64K, shr 1) machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 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.000037] arm-pv: using stolen time PV machine # [ 0.000675] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000887] Console: colour dummy device 80x25 machine # [ 0.000895] printk: legacy console [tty0] enabled machine # [ 0.001096] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.001102] pid_max: default: 32768 minimum: 301 machine # [ 0.001196] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.001362] landlock: Up and running. machine # [ 0.001365] Yama: becoming mindful. machine # [ 0.001972] LSM support for eBPF active machine # [ 0.002115] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.002135] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.004107] rcu: Hierarchical SRCU implementation. machine # [ 0.004112] rcu: Max phase no-delay instances is 1000. machine # [ 0.005422] fsl-mc MSI: its@8080000 domain created machine # [ 0.005520] EFI services will not be available. machine # [ 0.005655] smp: Bringing up secondary CPUs ... machine # [ 0.005665] smp: Brought up 1 node, 1 CPU machine # [ 0.005668] SMP: Total of 1 processors activated. machine # [ 0.005671] CPU: All CPU(s) started at EL1 machine # [ 0.005686] CPU features: detected: Branch Target Identification machine # [ 0.005690] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.005693] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.005696] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.005700] CPU features: detected: Common not Private translations machine # [ 0.005703] CPU features: detected: CRC32 instructions machine # [ 0.005706] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.005710] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.005713] CPU features: detected: Data independent timing control (DIT) machine # [ 0.005716] CPU features: detected: E0PD machine # [ 0.005718] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.005721] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.005725] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.005728] CPU features: detected: Fine Grained Traps machine # [ 0.005731] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.005737] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.005740] CPU features: detected: LSE atomic instructions machine # [ 0.005742] CPU features: detected: Privileged Access Never machine # [ 0.005745] CPU features: detected: PMUv3 machine # [ 0.005748] CPU features: detected: RAS Extension Support machine # [ 0.005751] CPU features: detected: RASv1p1 Extension Support machine # [ 0.005753] CPU features: detected: Random Number Generator machine # [ 0.005756] CPU features: detected: Speculation barrier (SB) machine # [ 0.005759] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.005761] CPU features: detected: TLB range maintenance instructions machine # [ 0.005766] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.005807] alternatives: applying system-wide alternatives machine # [ 0.008953] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.009143] Memory: 893952K/1048576K available (24448K kernel code, 7094K rwdata, 26372K rodata, 4736K init, 1107K bss, 113300K reserved, 32768K cma-reserved) machine # [ 0.009751] devtmpfs: initialized machine # [ 0.011548] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.011577] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.011781] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.011785] 0 pages in range for non-PLT usage machine # [ 0.011787] 508272 pages in range for PLT usage machine # [ 0.011899] pinctrl core: initialized pinctrl subsystem machine # [ 0.012773] DMI not present or invalid. machine # [ 0.016404] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.018753] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.018901] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.019291] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.019315] audit: initializing netlink subsys (disabled) machine # [ 0.019876] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.019878] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.019882] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.019885] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.019888] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.019922] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 machine # [ 0.019931] cpuidle: using governor ladder machine # [ 0.019937] cpuidle: using governor menu machine # [ 0.020148] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.020171] ASID allocator initialised with 65536 entries machine # [ 0.021487] Serial: AMBA PL011 UART driver machine # [ 0.027322] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 machine # [ 0.027464] printk: console [ttyAMA0] enabled machine # [ 0.166594] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.166624] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.166630] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.166634] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.166639] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.166644] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.166648] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.166653] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.175015] fbcon: Taking over console machine # [ 0.175039] ACPI: Interpreter disabled. machine # [ 0.182602] iommu: Default domain type: Translated machine # [ 0.182619] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.183038] SCSI subsystem initialized machine # [ 0.185222] usbcore: registered new interface driver usbfs machine # [ 0.185269] usbcore: registered new interface driver hub machine # [ 0.185294] usbcore: registered new device driver usb machine # [ 0.185625] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.185633] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.185643] PTP clock support registered machine # [ 0.185701] EDAC MC: Ver: 3.0.0 machine # [ 0.190664] scmi_core: SCMI protocol bus registered machine # [ 0.191745] FPGA manager framework machine # [ 0.192800] vgaarb: loaded machine # [ 0.193522] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.197467] VFS: Disk quotas dquot_6.6.0 machine # [ 0.197511] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.201750] netfs: FS-Cache loaded machine # [ 0.201924] pnp: PnP ACPI: disabled machine # [ 0.206286] NET: Registered PF_INET protocol family machine # [ 0.206469] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.237826] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.237882] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.237912] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.237963] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.238041] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.238164] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.238231] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.238260] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.238342] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.238365] NET: Registered PF_XDP protocol family machine # [ 0.238387] PCI: CLS 0 bytes, default 64 machine # [ 0.238636] Trying to unpack rootfs image as initramfs... machine # [ 0.261606] kvm [1]: HYP mode not available machine # [ 0.370201] Initialise system trusted keyrings machine # [ 0.371001] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.372261] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.373083] 9p: Installing v9fs 9p2000 file system support machine # [ 0.393949] Key type asymmetric registered machine # [ 0.393986] Asymmetric key parser 'x509' registered machine # [ 0.394075] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.401626] io scheduler mq-deadline registered machine # [ 0.401652] io scheduler kyber registered machine # [ 0.413727] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.414405] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.414787] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.414806] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.414819] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.414828] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.414851] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.414879] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.414964] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.414974] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.414980] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.414985] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.414991] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.415072] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.415547] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.415737] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.415755] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.415784] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.415801] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.416262] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.416449] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.416464] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.416494] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.416968] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.417159] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.417177] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.417208] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.417695] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.417884] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.417900] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.417929] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.417945] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.418410] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.418597] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.418628] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.419089] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.419281] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.419310] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.419711] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.419892] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.420141] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.420326] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.420356] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.420827] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.421014] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.421043] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.421489] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.469782] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.469830] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.470387] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.470685] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.470703] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.470732] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.471198] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.471390] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.471406] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.471436] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.472037] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.472048] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.472054] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.472101] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.472148] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.472196] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.472245] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.472293] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.472343] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.472392] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.472440] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.472488] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.472563] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.472609] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.472630] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.472655] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.472677] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.472699] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.472748] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.472771] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.472797] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.472822] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.472845] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.472867] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.472889] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.472911] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.472933] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.472955] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned machine # [ 0.472979] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned machine # [ 0.473001] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.473023] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.473051] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.473061] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.473065] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.473915] pci 0000:00:07.0: enabling device (0000 -> 0002) machine # [ 0.540505] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.543914] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.554204] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.556254] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.558802] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) machine # [ 0.561264] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) machine # [ 0.575105] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.577060] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.580595] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.590974] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.594740] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.608180] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.610988] msm_serial: driver initialized machine # [ 0.611138] SuperH (H)SCI(F) driver initialized machine # [ 0.611215] STM32 USART driver initialized machine # [ 0.648716] loop: module loaded machine # [ 0.648977] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 0.651092] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.666378] megasas: 07.734.00.00-rc1 machine # [ 0.667260] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.669418] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.669506] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.671276] Using buffer write method machine # [ 0.671372] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.673303] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.673348] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.682834] Using buffer write method machine # [ 0.682916] Concatenating MTD devices: machine # [ 0.682920] (0): "0.flash" machine # [ 0.682925] (1): "0.flash" machine # [ 0.682928] into device "0.flash" machine # [ 0.976351] Freeing initrd memory: 26376K machine # [ 0.982849] tun: Universal TUN/TAP device driver, 1.6 machine # [ 0.986834] thunder_xcv, ver 1.0 machine # [ 0.986874] thunder_bgx, ver 1.0 machine # [ 0.986896] nicpf, ver 1.0 machine # [ 0.987482] e1000: Intel(R) PRO/1000 Network Driver machine # [ 0.987489] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 0.987515] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 0.987523] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 0.987550] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 0.987559] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 0.987605] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 0.987614] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 0.987755] sky2: driver version 1.30 machine # [ 0.989415] usbcore: registered new interface driver usb-storage machine # [ 0.989499] usbcore: registered new interface driver usbserial_generic machine # [ 0.989513] usbserial: USB Serial support registered for generic machine # [ 0.999654] ehci-pci 0000:00:07.0: EHCI Host Controller machine # [ 0.999688] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 machine # [ 0.999947] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 machine # [ 1.003057] hv_vmbus: registering driver hyperv_keyboard machine # [ 1.004636] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 1.004666] rtc-pl031 9010000.pl031: setting system clock to 2026-09-22T02:58:45 UTC (1790045925) machine # [ 1.005025] i2c_dev: i2c /dev entries driver machine # [ 1.010652] sdhci: Secure Digital Host Controller Interface driver machine # [ 1.010668] sdhci: Copyright(c) Pierre Ossman machine # [ 1.010960] Synopsys Designware Multimedia Card Interface Driver machine # [ 1.011334] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 1.012941] hid: raw HID events driver (C) Jiri Kosina machine # [ 1.013190] usbcore: registered new interface driver usbhid machine # [ 1.013196] usbhid: USB HID core driver machine # [ 1.018068] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 machine # [ 1.019128] hub 1-0:1.0: USB hub found machine # [ 1.019624] hub 1-0:1.0: 6 ports detected machine # [ 1.021391] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 1.024165] drop_monitor: Initializing network drop monitor service machine # [ 1.024363] NET: Registered PF_INET6 protocol family machine # [ 1.026381] Segment Routing with IPv6 machine # [ 1.026398] In-situ OAM (IOAM) with IPv6 machine # [ 1.026430] NET: Registered PF_PACKET protocol family machine # [ 1.028052] 9pnet: Installing 9P2000 support machine # [ 1.028108] Key type dns_resolver registered machine # [ 1.035345] registered taskstats version 1 machine # [ 1.035513] Loading compiled-in X.509 certificates machine # [ 1.044938] Demotion targets for Node 0: null machine # [ 1.045072] Key type .fscrypt registered machine # [ 1.045084] Key type fscrypt-provisioning registered machine # [ 1.045192] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.045215] ima: Allocated hash algorithm: sha1 machine # [ 1.045248] ima: No architecture policies found machine # [ 1.049443] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 1.068277] clk: Disabling unused clocks machine # [ 1.068318] PM: genpd: Disabling unused power domains machine # [ 1.073037] Freeing unused kernel memory: 4736K machine # [ 1.073259] Run /init as init process machine # [ 1.092202] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.265701] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.417648] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 machine # [ 1.427317] systemd[1]: systemd 262-e362a4e 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.440545] systemd[1]: Detected virtualization qemu. machine # [ 1.442773] systemd[1]: Detected architecture arm64. machine # [ 1.444696] systemd[1]: Running in initrd. machine # [ 1.447720] systemd[1]: Initializing machine ID from random generator. machine # [ 1.451039] systemd[1]: Hostname set to . machine # [ 1.517839] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0 machine # [ 1.594844] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.637768] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.657010] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.663787] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.664863] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.666009] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.666968] systemd[1]: Reached target Path Units. machine # [ 1.667634] systemd[1]: Reached target Slice Units. machine # [ 1.668304] systemd[1]: Reached target Swaps. machine # [ 1.668955] systemd[1]: Reached target Timer Units. machine # [ 1.669805] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.670753] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.671789] systemd[1]: Listening on Journal Sockets. machine # [ 1.672595] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.673353] systemd[1]: Reached target Socket Units. machine # [ 1.675649] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.675747] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.685743] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.693873] systemd[1]: Starting Journal Service... machine # [ 1.710606] systemd[1]: Starting Load Kernel Modules... machine # [ 1.712239] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.748301] systemd-journald[71]: Collecting audit messages is disabled. machine # [ 1.748519] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.752108] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.754197] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.769749] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.783414] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.804992] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 machine # [ 1.805146] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 machine # [ 1.809633] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.827329] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 machine # [ 1.827574] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.827586] [drm] features: -context_init machine # [ 1.828345] [drm] number of scanouts: 1 machine # [ 1.828363] [drm] number of cap sets: 0 machine # [ 1.832609] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.839599] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.853979] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic machine # [ 1.854004] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 machine # [ 1.881822] Console: switching to colour frame buffer device 160x50 machine # [ 1.889107] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.902249] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.902720] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.902759] systemd[1]: Reached target Local File Systems. machine # [ 1.910364] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.938331] systemd[1]: Finished Load Kernel Modules. machine # [ 1.942415] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.942502] systemd-modules-load[72]: Inserted module 'dm_mod' machine # [ 1.943851] systemd-modules-load[72]: Module 'virtio_balloon' is built in machine # [ 1.969910] systemd[1]: Started Journal Service. machine # [ 1.956643] systemd-modules-load[72]: Module 'virtio_console' is built in machine # [ 1.957881] systemd-modules-load[72]: Inserted module 'virtio_gpu' machine # [ 1.958959] systemd-modules-load[72]: Module 'virtio_rng' is built in machine # [ 1.974055] systemd[1]: Starting Create System Files and Directories... machine # [ 2.021348] systemd[1]: Finished Apply Kernel Variables. machine # [ 2.025708] systemd-udevd[78]: Using default interface naming scheme 'v261'. machine # [ 2.041243] systemd[1]: Finished Create System Files and Directories. machine # [ 2.065508] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 2.159617] systemd[1]: Starting Virtual Console Setup... machine # [ 2.245220] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.252682] systemd[1]: Finished Virtual Console Setup. machine # [ 2.892123] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.893137] systemd[1]: Reached target System Initialization. machine # [ 2.893962] systemd[1]: Reached target Basic System. machine # [ 3.080134] (udev-worker)[90]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 3.084621] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line. machine # [ 3.151743] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 3.156231] systemd[1]: Reached target Initrd Root Device. machine # [ 3.159422] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 3.217019] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 3.224545] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 3.228953] systemd[1]: Mounting /sysroot... machine # [ 3.291172] EXT4-fs (vda): mounted filesystem cde7f0cc-4b78-46bc-b375-eaef85d57601 r/w with ordered data mode. Quota mode: none. machine # [ 3.275328] systemd[1]: Mounted /sysroot. machine # [ 3.279297] systemd[1]: Reached target Initrd Root File System. machine # [ 3.283171] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 3.312477] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 3.320968] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 3.324151] systemd[1]: Reloading... machine # [ 3.534484] systemd[1]: Reloading finished in 210 ms. machine # [ 3.571822] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace... machine # [ 3.576268] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace... machine # [ 3.584477] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.585601] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.587138] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.732786] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.741002] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.756968] systemd[1]: Mounting /sysroot/run... machine # [ 3.766353] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.796297] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.872418] fuse: init (API version 7.45) machine # [ 3.883898] virtiofs virtio6: discovered new tag: nix-store machine # [ 3.884762] virtiofs virtio6: virtio_fs_setup_dax: No cache capability machine # [ 3.899376] virtiofs virtio7: discovered new tag: shared machine # [ 3.900198] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 3.907240] virtiofs virtio8: discovered new tag: xchg machine # [ 3.908047] virtiofs virtio8: virtio_fs_setup_dax: No cache capability machine # [ 3.929181] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.931116] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.935221] systemd[1]: Mounted /sysroot/run. machine # [ 3.936677] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.938721] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.948887] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.981583] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.984228] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.986920] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.040942] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.043144] systemd[1]: Reached target Initrd File Systems. machine # [ 4.044635] systemd[1]: Starting Find NixOS closure... machine # [ 4.064358] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.113041] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.127417] systemd[1]: Finished Find NixOS closure. machine # [ 4.132279] systemd[1]: Reached target Initrd Default Target. machine # [ 4.143010] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.178626] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.184113] systemd[1]: Stopped target Basic System. machine # [ 4.186498] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.187414] systemd[1]: Stopped target Path Units. machine # [ 4.189403] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.192404] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.196843] systemd[1]: Stopped target Slice Units. machine # [ 4.198548] systemd[1]: Stopped target Socket Units. machine # [ 4.201916] systemd[1]: Stopped target System Initialization. machine # [ 4.205654] systemd[1]: Stopped target Swaps. machine # [ 4.206844] systemd[1]: Stopped target Timer Units. machine # [ 4.211955] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.214256] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.216374] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.225062] systemd[1]: Stopped Find NixOS closure. machine # [ 4.227811] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.232771] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.233854] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.234633] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.235661] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.248410] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.252474] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.256322] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 4.257442] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.260310] systemd[1]: Stopped target Local File Systems. machine # [ 4.261152] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.264432] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 4.265529] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.266947] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 4.270877] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.272120] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.272872] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.273801] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.274702] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.275721] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.281852] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.283266] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 4.288575] systemd[1]: systemd-udevd.service: Consumed 1.084s CPU time over 2.390s wall clock time, 16M memory peak. machine # [ 4.290031] systemd[1]: Starting Cleanup udev Database... machine # [ 4.290825] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 4.293787] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 4.294824] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 4.296114] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 4.297179] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 4.298153] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 4.328674] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 4.330382] systemd[1]: Finished Cleanup udev Database. machine # [ 4.332938] systemd[1]: Reached target Switch Root. machine # [ 4.335850] systemd[1]: Starting NixOS Activation... machine # [ 4.443443] initrd-nixos-activation-start[166]: booting system configuration /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test machine # [ 4.488180] initrd-nixos-activation-start[166]: running activation script... machine # [ 4.779539] initrd-nixos-activation-start[189]: setting up /etc... machine # [ 4.924570] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 4.927887] systemd[1]: Finished NixOS Activation. machine # [ 4.929838] systemd[1]: Starting Switch Root... machine # [ 4.954620] systemd[1]: Switching root. machine # [ 5.147383] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). machine # [ 5.648546] systemd[1]: systemd 262-e362a4e 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.662360] systemd[1]: Detected virtualization qemu. machine # [ 5.666027] systemd[1]: Detected architecture arm64. machine # [ 5.670435] systemd[1]: Detected first boot. machine # [ 5.676648] systemd[1]: Initializing machine ID from random generator. machine # [ 5.820176] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 6.010179] systemd[1]: Applying preset policy. machine # [ 6.261127] systemd[1]: Populated /etc with preset unit settings. machine # [ 6.506677] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 6.508512] systemd[1]: Stopped initrd-switch-root.service. machine # [ 6.512605] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 6.516387] systemd[1]: Created slice Slice /system/getty. machine # [ 6.519776] systemd[1]: Created slice User and Session Slice. machine # [ 6.521185] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 6.523670] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 6.525110] systemd[1]: Expecting device /dev/hvc0... machine # [ 6.526685] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 6.528167] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 6.529985] systemd[1]: Stopped target initrd-fs.target. machine # [ 6.531454] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 6.532975] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 6.534655] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 6.536295] systemd[1]: Reached target Path Units. machine # [ 6.537795] systemd[1]: Reached target Remote File Systems. machine # [ 6.539370] systemd[1]: Reached target Slice Units. machine # [ 6.540846] systemd[1]: Reached target Swaps. machine # [ 6.543697] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 6.546996] systemd[1]: Listening on Process Core Dump Socket. machine # [ 6.549324] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 6.551557] systemd[1]: Listening on Factory Reset Management. machine # [ 6.552843] systemd[1]: Listening on Hostname Service Socket. machine # [ 6.557387] systemd[1]: Starting Journal Log Access Socket... machine # [ 6.559657] systemd[1]: Listening on Journal Audit Socket. machine # [ 6.563633] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 6.565340] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 6.567054] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.570739] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 6.576785] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 6.578390] systemd[1]: Listening on udev Varlink Socket. machine # [ 6.582292] systemd[1]: Mounting Huge Pages File System... machine # [ 6.591099] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 6.606030] systemd[1]: Mounting Kernel Debug File System... machine # [ 6.615109] systemd[1]: Mounting Kernel Trace File System... machine # [ 6.640417] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 6.651641] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 6.669929] systemd[1]: Mounting Kernel Configuration File System... machine # [ 6.673741] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 6.686193] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 6.689299] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 6.712658] systemd[1]: Mounting FUSE Control File System... machine # [ 6.718031] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 6.737410] systemd[1]: Starting Journal Service... machine # [ 6.754919] systemd[1]: Starting Load Kernel Modules... machine # [ 6.777930] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 6.799515] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 6.801246] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.805359] systemd-journald[261]: Collecting audit messages is enabled. machine # [ 6.791971] systemd[1]: Queued start job for default target Multi-User System. machine # [ 6.797740] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 6.823676] systemd[1]: Starting Coldplug All udev Devices... machine # [ 6.832727] systemd[1]: Started Journal Service. machine # [ 6.826942] systemd[1]: Listening on Journal Log Access Socket. machine # [ 6.834348] systemd[1]: Mounted Huge Pages File System. machine # [ 6.835343] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 6.841060] systemd[1]: Mounted Kernel Debug File System. machine # [ 6.841924] systemd[1]: Mounted Kernel Trace File System. machine # [ 6.848460] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 6.852006] systemd[1]: Mounted Kernel Configuration File System. machine # [ 6.866648] systemd-modules-load[262]: Module 'atkbd' is built in machine # [ 6.877186] systemd-modules-load[262]: Module 'loop' is built in machine # [ 6.897470] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 6.902328] systemd[1]: Finished Load Kernel Modules. machine # [ 6.918143] systemd[1]: Starting Firewall... machine # [ 6.933829] systemd[1]: Starting Apply Kernel Variables... machine # [ 6.941163] systemd[1]: Mounted FUSE Control File System. machine # [ 6.958147] systemd-oomd[264]: No swap; memory pressure usage will be degraded machine # [ 6.985646] EXT4-fs (vda): re-mounted cde7f0cc-4b78-46bc-b375-eaef85d57601. machine # [ 6.983423] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 6.993329] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 6.998360] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 7.010190] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 7.011310] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 7.028522] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 7.029515] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.147178] systemd-journald[261]: Received client request to flush runtime journal. machine # [ 7.193629] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 7.194629] systemd[1]: Reached target First Boot Complete. machine # [ 7.195471] systemd[1]: Finished Apply Kernel Variables. machine # [ 7.201910] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 7.237327] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 7.250295] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 7.530734] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 7.531904] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 7.542587] systemd[1]: Mounting /run/wrappers... machine # [ 7.549314] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 7.656891] systemd[1]: Mounted /run/wrappers. machine # [ 7.657762] systemd[1]: Reached target Local File Systems. machine # [ 7.665909] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 7.670802] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 7.671876] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.683963] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 7.690306] systemd[1]: Starting Create System Files and Directories... machine # [ 7.918630] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 7.933365] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 8.014669] systemd[1]: Finished Create System Files and Directories. machine # [ 8.021810] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 8.030620] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 8.042021] systemd-udevd[301]: Using default interface naming scheme 'v261'. machine # [ 8.188284] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 8.226855] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 8.241659] systemd[1]: Starting Update is Completed... machine # [ 8.242640] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 8.389525] systemd[1]: Finished Update is Completed. machine # [ 8.578008] systemd[1]: Finished Coldplug All udev Devices. machine # [ 8.839248] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 8.844426] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 8.850022] systemd[1]: Reached target System Initialization. machine # [ 8.851082] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 8.854733] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 8.855906] systemd[1]: Reached target Timer Units. machine # [ 8.863378] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 8.866342] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 8.867662] systemd[1]: Reached target Socket Units. machine # [ 8.874667] systemd[1]: Reached target Basic System. machine # [ 8.875516] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 8.886364] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 8.896999] systemd[1]: Starting Post-Boot Actions... machine # [ 8.915824] systemd[1]: Started Reset console on configuration changes. machine # [ 8.933461] systemd[1]: Starting resolvconf update... machine # [ 8.992290] systemd[1]: Starting D-Bus System Message Bus... machine # [ 9.039950] nsncd[416]: Sep 22 02:58:53.556 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 9.059234] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 9.072726] systemd[1]: Finished Post-Boot Actions. machine # [ 9.117269] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 9.118410] systemd[1]: Reached target User and Group Name Lookups. machine # [ 9.149937] systemd[1]: Starting User Login Management... machine # [ 9.228922] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 9.245341] dbus-broker-launch[423]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 9.257100] dbus-broker-launch[423]: NSS returned no entry for 'systemd-timesync' machine # [ 9.258327] dbus-broker-launch[423]: Invalid user-name in /nix/store/kpsc35llka2514wxv7gim1g51s6hmkb2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 9.312522] systemd[1]: Started D-Bus System Message Bus. machine # [ 9.394451] dbus-broker-launch[423]: Ready machine # [ 9.405086] systemd-logind[442]: New seat seat0. machine # [ 9.410165] systemd[1]: Started User Login Management. machine # [ 9.418872] systemd[1]: Starting linger-users.service... machine # [ 9.522346] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 9.523564] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 9.534973] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 9.540779] systemd[1]: Stopping User and Group Name Lookups... machine # [ 9.543942] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 9.548461] systemd[1]: nscd.service: Deactivated successfully. machine # [ 9.549431] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 9.581713] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.678799] systemd[1]: Created slice Slice /user/1001. machine # [ 9.679849] systemd[1]: Starting User Runtime Directory /run/user/1001... machine # [ 9.735096] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 9.761944] systemd[1]: Finished linger-users.service. machine # [ 9.843062] nsncd[506]: Sep 22 02:58:54.352 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 9.883580] systemd[1]: Finished User Runtime Directory /run/user/1001. machine # [ 9.904803] systemd[1]: Starting User Manager for UID 1001... machine # [ 9.905822] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 9.954795] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 9.980738] systemd[1]: Reached target User and Group Name Lookups. machine # [ 9.999993] systemd[1]: Finished resolvconf update. machine # [ 10.015697] systemd[1]: Starting DHCP Client... machine # [ 10.055698] (systemd)[527]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 10.101988] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 machine # [ 10.107234] systemd-logind[442]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'. machine # [ 10.191350] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 10.513578] systemd[527]: Queued start job for default target Main User Target. machine # [ 10.547797] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 10.617485] systemd[527]: Created slice User Application Slice. machine # [ 10.666122] systemd[527]: Started Daily Cleanup of User's Temporary Directories. machine # [ 10.695544] systemd[527]: Reached target Paths. machine # [ 10.714203] systemd[527]: Reached target Timers. machine # [ 10.741054] systemd[527]: Starting D-Bus User Message Bus Socket... machine # [ 10.742199] systemd[527]: Starting Create User Files and Directories... machine # [ 10.743172] systemd[527]: Finished Create User Files and Directories. machine # [ 10.808538] systemd[527]: Listening on D-Bus User Message Bus Socket. machine # [ 10.809803] systemd[527]: Reached target Sockets. machine # [ 10.810561] systemd[527]: Reached target Basic System. machine # [ 10.811378] systemd[1]: Started User Manager for UID 1001. machine # [ 10.867641] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 10.891473] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 10.906697] systemd[527]: Reached target Main User Target. machine # [ 10.922966] systemd[527]: Startup finished in 574ms. machine # [ 10.938847] systemd[1]: Starting Virtual Console Setup... machine # [ 10.952548] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 10.987983] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 11.012259] systemd[1]: Stopped Virtual Console Setup. machine # [ 11.024915] systemd[1]: Starting Virtual Console Setup... machine # [ 11.028283] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 11.136535] dhcpcd[560]: dhcpcd-10.3.2 starting machine # [ 11.217693] dhcpcd[586]: dev: loaded udev machine # [ 11.292862] 8021q: 802.1Q VLAN Support v1.8 machine # [ 11.404217] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 11.421932] systemd[1]: Started backdoor.service. machine # [ 11.469446] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 11.507490] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 11.508012] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 11.511834] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 11.512175] cfg80211: failed to load regulatory.db machine # [ 11.518419] (udev-worker)[587]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 11.565260] (udev-worker)[587]: Network interface NamePolicy= disabled on kernel command line. machine # [ 11.796622] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 11.826697] systemd[1]: Finished Firewall. machine # [ 11.843397] systemd[1]: Reached target Preparation for Network. machine # [ 11.868899] systemd[1]: Starting Address configuration of eth1... machine # [ 11.886850] systemd[1]: Starting Extra networking commands.... machine # [ 11.982571] systemd-logind[442]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # connecting to host... machine # [ 12.116581] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 12.124133] mousedev: PS/2 mouse device common for all mice machine # [ 12.116532] dhcpcd[586]: eth0: waiting for carrier machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine # [ 12.137897] dhcpcd[586]: eth0: waiting for carrier machine: (connecting took 12.52 seconds) machine # [ 12.138723] dhcpcd[586]: eth0: carrier acquired machine: (finished: waiting for the VM to finish booting, in 13.03 seconds) machine # [ 12.263449] dhcpcd[586]: DUID 00:01:00:01:32:44:ab:70:52:54:00:12:34:56 machine # [ 12.287020] dhcpcd[586]: eth0: IAID 00:12:34:56 machine # [ 12.287831] dhcpcd[586]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 12.319336] systemd-logind[442]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 12.358945] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 12.356604] systemd-vconsole-setup[574]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 12.358861] systemd[1]: Finished Virtual Console Setup. machine # [ 12.490400] network-addresses-eth1-start[618]: adding address 192.168.1.1/24... done machine # [ 12.605969] network-addresses-eth1-start[618]: adding address 2001:db8:1::1/64... done machine # [ 12.707950] dhcpcd[586]: eth0: soliciting a DHCP lease machine # [ 12.726047] dhcpcd[586]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 12.727135] dhcpcd[586]: eth0: probing address 10.0.2.15/24 machine # [ 12.763165] systemd[1]: Finished Address configuration of eth1. machine # [ 13.457756] systemd[1]: Finished Extra networking commands.. machine # [ 13.458752] systemd[1]: Reached target Network. machine # [ 13.489964] systemd[1]: Starting Permit User Sessions... machine # [ 13.755298] systemd[1]: Finished Permit User Sessions. machine # [ 13.767057] systemd[1]: Started Getty on tty1. machine # [ 13.767910] systemd[1]: Reached target Login Prompts. machine # [ 13.864179] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 13.865433] systemd[1]: Stopped Virtual Console Setup. machine # [ 13.866203] systemd[1]: Stopping Virtual Console Setup... machine # [ 13.918383] systemd[1]: Starting Virtual Console Setup... machine # [ 13.938635] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 13.962211] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 13.974466] systemd[1]: Stopped Virtual Console Setup. machine # [ 13.975434] systemd[1]: Starting Virtual Console Setup... machine # [ 13.987243] systemd-logind[442]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 14.163010] systemd-vconsole-setup[703]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 14.171548] systemd[1]: Finished Virtual Console Setup. machine # [ 14.194477] dhcpcd[586]: eth0: soliciting an IPv6 router machine # [ 14.196237] dhcpcd[586]: eth0: Router Advertisement from fe80::2 machine # [ 14.197190] dhcpcd[586]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 14.198131] dhcpcd[586]: eth0: adding route to fec0::/64 machine # [ 14.198889] dhcpcd[586]: eth0: adding default route via fe80::2 machine # [ 17.166422] dhcpcd[586]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 17.171867] dhcpcd[586]: eth0: adding route to 10.0.2.0/24 machine # [ 17.177750] dhcpcd[586]: eth0: adding default route via 10.0.2.2 machine # [ 17.362176] systemd[1]: Started DHCP Client. machine # [ 17.365547] systemd[1]: Reached target Multi-User System. machine # [ 17.370989] systemd[1]: Startup finished in 1.056s (kernel) + 4.145s (initrd) + 12.164s (userspace) = 17.366s. machine: (finished: waiting for unit multi-user.target, in 18.34 seconds) machine: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/bin/switch-to-configuration test machine # Checking switch inhibitors... done machine # [ 17.728164] nixos[801]: switching to system configuration /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test machine # [ 17.731325] systemd[1]: Stopped target Remote File Systems. machine # activating the configuration... machine # [ 17.734533] systemd[1]: Stopped target Local File Systems. machine # setting up /etc... machine # [ 18.119904] systemd[1]: Reload requested from client PID 801 ('.switch-to-conf') (unit backdoor.service)... machine # [ 18.122335] systemd[1]: Reloading... machine # [ 18.599795] systemd[1]: Reloading finished in 472 ms. machine # reloading user units for usertest... machine # [ 18.662142] systemd[527]: Created slice User Core Session Slice. machine # [ 18.667943] systemd[527]: Starting D-Bus User Message Bus... machine # [ 18.729175] dbus-broker-launch[871]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/kpsc35llka2514wxv7gim1g51s6hmkb2-system-path/share/dbus-1/services/org.freedesktop.import1.service' machine # [ 18.732403] dbus-broker-launch[871]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/kpsc35llka2514wxv7gim1g51s6hmkb2-system-path/share/dbus-1/services/org.freedesktop.machine1.service' machine # [ 18.735614] dbus-broker-launch[871]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/kpsc35llka2514wxv7gim1g51s6hmkb2-system-path/share/dbus-1/services/org.freedesktop.portable1.service' machine # [ 18.738954] dbus-broker-launch[871]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/kpsc35llka2514wxv7gim1g51s6hmkb2-system-path/share/dbus-1/services/org.freedesktop.systemd1.service' machine # [ 18.744205] dbus-broker-launch[871]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored machine # [ 18.746030] dbus-broker-launch[871]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored machine # [ 18.747815] systemd[527]: Started D-Bus User Message Bus. machine # [ 18.754758] dbus-broker-launch[871]: Ready machine # [ 18.811073] systemd[527]: Reexecution requested from client PID 869 ('switch-to-confi')... machine # [ 18.812946] systemd[527]: Reexecuting. machine # [ 18.873092] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 19.009843] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 19.018712] systemd[527]: Reload requested from client PID 869 ('switch-to-confi')... machine # [ 19.021237] systemd[527]: Reloading... machine # [ 19.170144] systemd[527]: Reloading finished in 147 ms. machine # [ 19.188231] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 19.201882] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 19.204147] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 19.207888] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 19.251416] systemd[527]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 19.268386] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... machine # [ 19.387406] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. machine # [ 19.390322] systemd[1]: Reached target Reactivate sysinit units. machine # [ 19.399028] systemd[1]: Reached target Local File Systems. machine # [ 19.405344] systemd[1]: Reached target Remote File Systems. machine # [ 19.411780] systemd[1]: Starting linger-users.service... machine # [ 19.428438] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 19.440917] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 19.442073] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 19.443471] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 19.457895] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 19.459758] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 19.461989] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 19.463361] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 19.469183] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 19.549923] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 19.553665] systemd[1]: Finished linger-users.service. machine # [ 19.786439] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 19.790878] systemd[1]: Finished Create SUID/SGID Wrappers. machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service machine # [ 20.054077] nixos[801]: finished switching to system configuration /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test machine: (finished: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/bin/switch-to-configuration test, in 2.61 seconds) machine: must succeed: rm /etc/NIXOS machine: (finished: must succeed: rm /etc/NIXOS, in 0.04 seconds) machine: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/yjnagk81wxiy7hj08z5qv6svp27g8kmk-nixos-system-other-test/bin/switch-to-configuration test machine # this should succeed (config: /nix/store/yjnagk81wxiy7hj08z5qv6svp27g8kmk-nixos-system-other-test, action: test) machine # Checking switch inhibitors... done machine # [ 20.381349] nixos[949]: switching to system configuration /nix/store/yjnagk81wxiy7hj08z5qv6svp27g8kmk-nixos-system-other-test machine # [ 20.384046] systemd[1]: Stopped target Local File Systems. machine # activating the configuration... machine # [ 20.387078] systemd[1]: Stopped target Remote File Systems. machine # removing user ‘usertest’ machine # setting up /etc... machine # [ 20.692767] systemd[1]: Reload requested from client PID 949 ('.switch-to-conf') (unit backdoor.service)... machine # [ 20.694729] systemd[1]: Reloading... machine # [ 21.044948] systemd[1]: Reloading finished in 348 ms. machine # reloading user units for usertest... machine # [ 21.139758] systemd[527]: Reexecution requested from client PID 1020 ('switch-to-confi')... machine # [ 21.142900] systemd[527]: Reexecuting. machine # [ 21.200436] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 21.328834] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 21.335380] systemd[527]: Reload requested from client PID 1020 ('switch-to-confi')... machine # [ 21.339398] systemd[527]: Reloading... machine # [ 21.455853] systemd[527]: Reloading finished in 117 ms. machine # [ 21.474167] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 21.496097] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 21.497875] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 21.501745] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 21.554354] systemd[527]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 21.560858] systemd[1]: Stopped target Reactivate sysinit units. machine # reloading the following units: dbus-broker.service machine # [ 21.566414] systemd[1]: Stopping Reactivate sysinit units... machine # [ 21.567408] systemd[1]: Reached target Reactivate sysinit units. machine # [ 21.574428] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 21.575903] dbus-broker-launch[423]: Caught SIGHUP, trigger reload. machine # [ 21.582709] dbus-broker-launch[423]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 21.585180] dbus-broker-launch[423]: NSS returned no entry for 'systemd-timesync' machine # [ 21.586267] dbus-broker-launch[423]: Invalid user-name in /nix/store/vd92pv1qjgrwvlwmklz7wvklvghppc5n-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 21.614250] systemd[1]: Reloaded D-Bus System Message Bus. machine # restarting the following units: network-addresses-eth1.service machine # [ 21.621633] systemd[1]: Stopping Address configuration of eth1... machine # [ 21.658582] network-addresses-eth1-pre-stop[1033]: deleting address 192.168.1.1/24... done machine # [ 21.665977] network-addresses-eth1-pre-stop[1033]: deleting address 2001:db8:1::1/64... done machine # [ 21.673629] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 21.676143] systemd[1]: Stopped Address configuration of eth1. machine # [ 21.680731] systemd[1]: Starting Address configuration of eth1... machine # [ 21.724798] network-addresses-eth1-start[1038]: adding address 192.168.1.2/24... done machine # [ 21.732591] network-addresses-eth1-start[1038]: adding address 2001:db8:1::2/64... done machine # [ 21.742584] systemd[1]: Finished Address configuration of eth1. machine # [ 21.749117] systemd[1]: Reached target Remote File Systems. machine # [ 21.757566] systemd[1]: Reached target Local File Systems. machine # [ 21.762692] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 21.767667] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 21.773108] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 21.774584] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 21.785293] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 21.787009] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 21.788530] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 21.789786] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 21.791125] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 21.997426] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 21.999386] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 22.005428] systemd[1]: Starting linger-users.service... machine # [ 22.037436] linger-users-start[1110]: id: ‘usertest’: no such user machine # [ 22.039663] linger-users-start[1108]: Removing linger for missing user usertest machine # [ 22.047629] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 22.049776] systemd[1]: Finished linger-users.service. machine # [ 22.310865] nixos[949]: finished switching to system configuration /nix/store/yjnagk81wxiy7hj08z5qv6svp27g8kmk-nixos-system-other-test machine: (finished: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/yjnagk81wxiy7hj08z5qv6svp27g8kmk-nixos-system-other-test/bin/switch-to-configuration test, in 2.23 seconds) subtest: services machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 22.627549] nixos[1116]: switching to system configuration /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test machine # [ 22.630845] systemd[1]: Stopped target Local File Systems. machine # [ 22.633630] systemd[1]: Stopped target Remote File Systems. machine # [ 22.930332] systemd[1]: Reload requested from client PID 1116 ('.switch-to-conf') (unit backdoor.service)... machine # [ 22.932611] systemd[1]: Reloading... machine # [ 23.477600] systemd[1]: Reloading finished in 543 ms. machine # [ 23.581387] systemd[527]: Reexecution requested from client PID 1184 ('switch-to-confi')... machine # [ 23.585657] systemd[527]: Reexecuting. machine # [ 23.636051] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.755069] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.763114] systemd[527]: Reload requested from client PID 1184 ('switch-to-confi')... machine # [ 23.766411] systemd[527]: Reloading... machine # [ 23.885919] systemd[527]: Reloading finished in 120 ms. machine # [ 23.900204] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.911836] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 23.914434] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 23.916716] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 23.950017] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 23.954248] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 23.958419] systemd[1]: Stopping Reactivate sysinit units... machine # [ 23.959652] systemd[1]: Reached target Reactivate sysinit units. machine # [ 23.961312] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 23.963839] dbus-broker-launch[423]: Caught SIGHUP, trigger reload. machine # [ 23.967192] dbus-broker-launch[423]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 23.969657] dbus-broker-launch[423]: NSS returned no entry for 'systemd-timesync' machine # [ 23.970748] dbus-broker-launch[423]: Invalid user-name in /nix/store/kpsc35llka2514wxv7gim1g51s6hmkb2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 23.984468] systemd[1]: Reloaded D-Bus System Message Bus. machine # [ 23.990750] systemd[1]: Stopping Address configuration of eth1... machine # [ 24.024437] network-addresses-eth1-pre-stop[1197]: deleting address 192.168.1.2/24... done machine # [ 24.031769] network-addresses-eth1-pre-stop[1197]: deleting address 2001:db8:1::2/64... done machine # [ 24.039385] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 24.042054] systemd[1]: Stopped Address configuration of eth1. machine # [ 24.046786] systemd[1]: Starting Address configuration of eth1... machine # [ 24.100629] network-addresses-eth1-start[1202]: adding address 192.168.1.1/24... done machine # [ 24.109874] network-addresses-eth1-start[1202]: adding address 2001:db8:1::1/64... done machine # [ 24.120840] systemd[1]: Finished Address configuration of eth1. machine # [ 24.123908] systemd[1]: Reached target Remote File Systems. machine # [ 24.126836] systemd[1]: Reached target Local File Systems. machine # [ 24.129879] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 24.137554] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 24.138629] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 24.140000] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 24.151933] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 24.157629] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.159157] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.163366] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.165829] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 24.348991] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 24.353006] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 24.357301] systemd[1]: Starting linger-users.service... machine # [ 24.398094] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 24.400158] systemd[1]: Finished linger-users.service. machine # [ 24.662380] nixos[1116]: finished switching to system configuration /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.34 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 24.979541] nixos[1271]: switching to system configuration /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test machine # [ 24.982726] systemd[1]: Stopped target Local File Systems. machine # [ 24.985248] systemd[1]: Stopped target Remote File Systems. machine # [ 25.287048] systemd[1]: Reload requested from client PID 1271 ('.switch-to-conf') (unit backdoor.service)... machine # [ 25.289502] systemd[1]: Reloading... machine # [ 25.633447] systemd[1]: Reloading finished in 342 ms. machine # [ 25.726682] systemd[527]: Reexecution requested from client PID 1341 ('switch-to-confi')... machine # [ 25.731294] systemd[527]: Reexecuting. machine # [ 25.783834] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 25.900700] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 25.907612] systemd[527]: Reload requested from client PID 1341 ('switch-to-confi')... machine # [ 25.911138] systemd[527]: Reloading... machine # [ 26.022621] systemd[527]: Reloading finished in 112 ms. machine # [ 26.041697] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.050332] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 26.052849] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 26.055118] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 26.090150] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 26.094258] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 26.096430] systemd[1]: Stopping Reactivate sysinit units... machine # [ 26.097334] systemd[1]: Reached target Reactivate sysinit units. machine # [ 26.100431] systemd[1]: Reached target Remote File Systems. machine # [ 26.104445] systemd[1]: Starting linger-users.service... machine # [ 26.116772] systemd[1]: Reached target Local File Systems. machine # [ 26.118939] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.120486] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 26.121789] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 26.136535] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 26.141065] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 26.152318] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 26.154590] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 26.159137] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.161151] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.203806] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 26.208661] systemd[1]: Finished linger-users.service. machine # [ 26.362901] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 26.365974] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 26.625618] nixos[1271]: finished switching to system configuration /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.96 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1 machine # [ 26.924517] nixos[1412]: switching to system configuration /nix/store/wamnkqnjjrfs98fs5b697n0za8k6gqa6-nixos-system-machine-test machine # [ 26.927053] systemd[1]: Stopped target Local File Systems. machine # [ 26.929379] systemd[1]: Stopped target Remote File Systems. machine # [ 27.223971] systemd[1]: Reload requested from client PID 1412 ('.switch-to-conf') (unit backdoor.service)... machine # [ 27.226255] systemd[1]: Reloading... machine # [ 27.571245] systemd[1]: Reloading finished in 342 ms. machine # [ 27.657650] systemd[527]: Reexecution requested from client PID 1481 ('switch-to-confi')... machine # [ 27.659084] systemd[527]: Reexecuting. machine # [ 27.723924] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.864226] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.878268] systemd[527]: Reload requested from client PID 1481 ('switch-to-confi')... machine # [ 27.880898] systemd[527]: Reloading... machine # [ 28.003790] systemd[527]: Reloading finished in 121 ms. machine # [ 28.020193] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.029590] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 28.032157] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 28.034651] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 28.070566] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 28.074511] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 28.076633] systemd[1]: Stopping Reactivate sysinit units... machine # [ 28.077578] systemd[1]: Reached target Reactivate sysinit units. machine # [ 28.082524] systemd[1]: Reached target Local File Systems. machine # [ 28.088274] systemd[1]: Reached target Remote File Systems. machine # [ 28.089259] systemd[1]: Starting linger-users.service... machine # [ 28.100619] systemd[1]: Starting test.service... machine # [ 28.102603] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.111286] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 28.117992] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 28.119607] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 28.132430] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 28.133870] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 28.151278] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 28.164416] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.169166] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.183430] systemd[1]: Finished test.service. machine # [ 28.236287] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 28.237363] systemd[1]: Finished linger-users.service. machine # [ 28.473783] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 28.476929] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 28.737751] nixos[1412]: finished switching to system configuration /nix/store/wamnkqnjjrfs98fs5b697n0za8k6gqa6-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 2.11 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1 machine # [ 29.017388] nixos[1555]: switching to system configuration /nix/store/wamnkqnjjrfs98fs5b697n0za8k6gqa6-nixos-system-machine-test machine # [ 29.019931] systemd[1]: Stopped target Local File Systems. machine # [ 29.022425] systemd[1]: Stopped target Remote File Systems. machine # [ 29.301031] systemd[1]: Reload requested from client PID 1555 ('.switch-to-conf') (unit backdoor.service)... machine # [ 29.302925] systemd[1]: Reloading... machine # [ 29.629681] systemd[1]: Reloading finished in 325 ms. machine # [ 29.715652] systemd[527]: Reexecution requested from client PID 1624 ('switch-to-confi')... machine # [ 29.719613] systemd[527]: Reexecuting. machine # [ 29.779873] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.897763] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.904693] systemd[527]: Reload requested from client PID 1624 ('switch-to-confi')... machine # [ 29.907200] systemd[527]: Reloading... machine # [ 30.017771] systemd[527]: Reloading finished in 109 ms. machine # [ 30.032188] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.041270] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 30.043703] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 30.045874] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 30.080883] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 30.084773] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 30.086713] systemd[1]: Stopping Reactivate sysinit units... machine # [ 30.087620] systemd[1]: Reached target Reactivate sysinit units. machine # [ 30.092624] systemd[1]: Reached target Remote File Systems. machine # [ 30.100523] systemd[1]: Starting linger-users.service... machine # [ 30.101424] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.102719] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 30.110560] systemd[1]: Reached target Local File Systems. machine # [ 30.113085] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 30.122448] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 30.123479] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 30.132212] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 30.134783] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 30.145168] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.146730] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.189171] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 30.191781] systemd[1]: Finished linger-users.service. machine # [ 30.360895] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 30.365038] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 30.622453] nixos[1555]: finished switching to system configuration /nix/store/wamnkqnjjrfs98fs5b697n0za8k6gqa6-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 1.89 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&1 machine # [ 30.937871] nixos[1695]: switching to system configuration /nix/store/cf3dlyy9k54p0dfs9xcgcz1wb3yqvbnh-nixos-system-machine-test machine # [ 30.942162] systemd[1]: Stopped target Remote File Systems. machine # [ 30.944750] systemd[1]: Stopped target Local File Systems. machine # [ 31.247683] systemd[1]: Reload requested from client PID 1695 ('.switch-to-conf') (unit backdoor.service)... machine # [ 31.250037] systemd[1]: Reloading... machine # [ 31.700144] systemd[1]: Reloading finished in 448 ms. machine # [ 31.826298] systemd[527]: Reexecution requested from client PID 1764 ('switch-to-confi')... machine # [ 31.827765] systemd[527]: Reexecuting. machine # [ 31.887635] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.006301] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.013585] systemd[527]: Reload requested from client PID 1764 ('switch-to-confi')... machine # [ 32.016336] systemd[527]: Reloading... machine # [ 32.129506] systemd[527]: Reloading finished in 112 ms. machine # [ 32.144211] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.156106] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 32.157127] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 32.159748] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 32.189680] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 32.193425] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 32.195471] systemd[1]: Stopping Reactivate sysinit units... machine # [ 32.198336] systemd[1]: Reached target Reactivate sysinit units. machine # [ 32.199371] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.202628] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 32.206234] systemd[1]: Reached target Local File Systems. machine # [ 32.209199] systemd[1]: Reached target Remote File Systems. machine # [ 32.214863] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 32.224109] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 32.225222] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 32.226439] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 32.231751] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 32.233870] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.235243] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.401795] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 32.403907] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 32.409060] systemd[1]: Starting linger-users.service... machine # [ 32.451594] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 32.454473] systemd[1]: Finished linger-users.service. machine # [ 32.716207] nixos[1695]: finished switching to system configuration /nix/store/cf3dlyy9k54p0dfs9xcgcz1wb3yqvbnh-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&1, in 2.09 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 33.017041] nixos[1836]: switching to system configuration /nix/store/ijl55ziz03scd38hmlj5rhw0mnk7rbax-nixos-system-machine-test machine # [ 33.020584] systemd[1]: Stopped target Remote File Systems. machine # [ 33.021878] systemd[1]: test.service: Deactivated successfully. machine # [ 33.024750] systemd[1]: Stopped Test unit. machine # [ 33.025826] systemd[1]: Stopped target Local File Systems. machine # [ 33.335213] systemd[1]: Reload requested from client PID 1836 ('.switch-to-conf') (unit backdoor.service)... machine # [ 33.337308] systemd[1]: Reloading... machine # [ 33.711207] systemd[1]: Reloading finished in 372 ms. machine # [ 33.800726] systemd[527]: Reexecution requested from client PID 1905 ('switch-to-confi')... machine # [ 33.804390] systemd[527]: Reexecuting. machine # [ 33.863561] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.005324] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.012207] systemd[527]: Reload requested from client PID 1905 ('switch-to-confi')... machine # [ 34.016074] systemd[527]: Reloading... machine # [ 34.132943] systemd[527]: Reloading finished in 117 ms. machine # [ 34.150038] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.159867] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 34.162348] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 34.164292] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 34.198494] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 34.202938] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 34.205862] systemd[1]: Stopping Reactivate sysinit units... machine # [ 34.206801] systemd[1]: Reached target Reactivate sysinit units. machine # [ 34.214102] systemd[1]: Starting test.service... machine # [ 34.219439] systemd[1]: Reached target Remote File Systems. machine # [ 34.228379] systemd[1]: Starting linger-users.service... machine # [ 34.233873] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.235372] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 34.249810] systemd[1]: Reached target Local File Systems. machine # [ 34.250785] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 34.262053] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 34.263093] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 34.271484] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 34.281045] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 34.282845] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.295909] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.300963] systemd[1]: Finished test.service. machine # [ 34.330502] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 34.334617] systemd[1]: Finished linger-users.service. machine # [ 34.543410] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 34.546582] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 34.810741] nixos[1836]: finished switching to system configuration /nix/store/ijl55ziz03scd38hmlj5rhw0mnk7rbax-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 2.10 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 35.135916] nixos[1978]: switching to system configuration /nix/store/haj41h0p4x0qrkkaycgivzipfxs0a5m0-nixos-system-machine-test machine # [ 35.138639] systemd[1]: Stopped target Local File Systems. machine # [ 35.141137] systemd[1]: Stopped target Remote File Systems. machine # [ 35.445407] systemd[1]: Reload requested from client PID 1978 ('.switch-to-conf') (unit backdoor.service)... machine # [ 35.447407] systemd[1]: Reloading... machine # [ 35.827538] systemd[1]: Reloading finished in 378 ms. machine # [ 35.922175] systemd[527]: Reexecution requested from client PID 2048 ('switch-to-confi')... machine # [ 35.926427] systemd[527]: Reexecuting. machine # [ 35.979827] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.097499] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.104286] systemd[527]: Reload requested from client PID 2048 ('switch-to-confi')... machine # [ 36.106764] systemd[527]: Reloading... machine # [ 36.220420] systemd[527]: Reloading finished in 112 ms. machine # [ 36.236212] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.248086] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 36.249109] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 36.251932] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 36.286572] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 36.290707] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 36.292881] systemd[1]: Stopping Reactivate sysinit units... machine # [ 36.293757] systemd[1]: Reached target Reactivate sysinit units. machine # [ 36.296447] systemd[1]: test.service: Deactivated successfully. machine # [ 36.297355] systemd[1]: Stopped test.service. machine # [ 36.297997] systemd[1]: Stopping test.service... machine # [ 36.301083] systemd[1]: Starting test.service... machine # [ 36.325757] systemd[1]: Finished test.service. machine # [ 36.332440] systemd[1]: Reached target Remote File Systems. machine # [ 36.337017] systemd[1]: Starting linger-users.service... machine # [ 36.344858] systemd[1]: Reached target Local File Systems. machine # [ 36.345752] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 36.356237] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 36.357200] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 36.358432] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 36.373513] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 36.375255] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.383743] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.390051] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.391443] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 36.426286] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 36.429321] systemd[1]: Finished linger-users.service. machine # [ 36.568602] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 36.571949] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 36.828796] nixos[1978]: finished switching to system configuration /nix/store/haj41h0p4x0qrkkaycgivzipfxs0a5m0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 2.01 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&1 machine # [ 37.123103] nixos[2121]: switching to system configuration /nix/store/lam8hlwxccngczn1rn2n5jyg9h7jmszr-nixos-system-machine-test machine # [ 37.125757] systemd[1]: Stopped target Remote File Systems. machine # [ 37.128266] systemd[1]: Stopped target Local File Systems. machine # [ 37.423712] systemd[1]: Reload requested from client PID 2121 ('.switch-to-conf') (unit backdoor.service)... machine # [ 37.426009] systemd[1]: Reloading... machine # [ 37.763770] systemd[1]: Reloading finished in 335 ms. machine # [ 37.861311] systemd[527]: Reexecution requested from client PID 2190 ('switch-to-confi')... machine # [ 37.865900] systemd[527]: Reexecuting. machine # [ 37.915383] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.029964] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.036674] systemd[527]: Reload requested from client PID 2190 ('switch-to-confi')... machine # [ 38.040120] systemd[527]: Reloading... machine # [ 38.190837] systemd[527]: Reloading finished in 151 ms. machine # [ 38.216597] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.229973] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 38.233019] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 38.235217] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 38.270232] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 38.274420] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 38.279020] systemd[1]: Stopping Reactivate sysinit units... machine # [ 38.279950] systemd[1]: Reached target Reactivate sysinit units. machine # [ 38.282113] systemd[1]: Reloading test.service... machine # [ 38.310626] systemd[1]: Reloaded test.service. machine # [ 38.316510] systemd[1]: Reached target Local File Systems. machine # [ 38.322485] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 38.326490] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 38.327473] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 38.336582] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 38.338899] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 38.347610] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.349137] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.350348] systemd[1]: Reached target Remote File Systems. machine # [ 38.351177] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.353042] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 38.524163] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 38.528851] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 38.530927] systemd[1]: Starting linger-users.service... machine # [ 38.574438] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 38.577268] systemd[1]: Finished linger-users.service. machine # [ 38.835786] nixos[2121]: finished switching to system configuration /nix/store/lam8hlwxccngczn1rn2n5jyg9h7jmszr-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&1, in 2.01 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1 machine # [ 39.148534] nixos[2264]: switching to system configuration /nix/store/5v7sf5lcjls65m8azcd473j1vn2igdnx-nixos-system-machine-test machine # [ 39.151611] systemd[1]: Stopped target Local File Systems. machine # [ 39.153861] systemd[1]: Stopped target Remote File Systems. machine # [ 39.450102] systemd[1]: Reload requested from client PID 2264 ('.switch-to-conf') (unit backdoor.service)... machine # [ 39.452147] systemd[1]: Reloading... machine # [ 39.800776] systemd[1]: Reloading finished in 347 ms. machine # [ 39.897004] systemd[527]: Reexecution requested from client PID 2333 ('switch-to-confi')... machine # [ 39.900639] systemd[527]: Reexecuting. machine # [ 39.951382] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.082986] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.090786] systemd[527]: Reload requested from client PID 2333 ('switch-to-confi')... machine # [ 40.094151] systemd[527]: Reloading... machine # [ 40.205498] systemd[527]: Reloading finished in 112 ms. machine # [ 40.220320] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.234758] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 40.239062] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 40.242851] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 40.278065] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 40.284872] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 40.285944] systemd[1]: Stopping Reactivate sysinit units... machine # [ 40.286802] systemd[1]: Reached target Reactivate sysinit units. machine # [ 40.293584] systemd[1]: Reached target Local File Systems. machine # [ 40.296698] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 40.301678] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 40.302706] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 40.303986] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 40.313366] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 40.315108] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 40.323206] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 40.324669] systemd[1]: Reached target Remote File Systems. machine # [ 40.325509] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 40.326891] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 40.505133] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 40.508382] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 40.513002] systemd[1]: Starting linger-users.service... machine # [ 40.555890] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 40.559099] systemd[1]: Finished linger-users.service. machine # [ 40.819476] nixos[2264]: finished switching to system configuration /nix/store/5v7sf5lcjls65m8azcd473j1vn2igdnx-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 1.98 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&1 machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&1, in 0.35 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 41.400566] nixos[2414]: switching to system configuration /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test machine # [ 41.403586] systemd[1]: test.service: Deactivated successfully. machine # [ 41.407874] systemd[1]: Stopped test.service. machine # [ 41.409445] systemd[1]: Stopped target Local File Systems. machine # [ 41.410288] systemd[1]: Stopped target Remote File Systems. machine # [ 41.755493] systemd[1]: Reload requested from client PID 2414 ('.switch-to-conf') (unit backdoor.service)... machine # [ 41.757672] systemd[1]: Reloading... machine # [ 42.089356] systemd[1]: Reloading finished in 330 ms. machine # [ 42.178504] systemd[527]: Reexecution requested from client PID 2484 ('switch-to-confi')... machine # [ 42.179923] systemd[527]: Reexecuting. machine # [ 42.231479] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.353309] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.359787] systemd[527]: Reload requested from client PID 2484 ('switch-to-confi')... machine # [ 42.362319] systemd[527]: Reloading... machine # [ 42.476381] systemd[527]: Reloading finished in 113 ms. machine # [ 42.492188] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.501556] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 42.504042] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 42.506208] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 42.558611] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 42.566536] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 42.572422] systemd[1]: Stopping Reactivate sysinit units... machine # [ 42.573877] systemd[1]: Reached target Reactivate sysinit units. machine # [ 42.575320] systemd[1]: Reached target Remote File Systems. machine # [ 42.583713] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.585324] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 42.587678] systemd[1]: Starting linger-users.service... machine # [ 42.602382] systemd[1]: Reached target Local File Systems. machine # [ 42.603362] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 42.614223] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 42.615162] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 42.618177] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 42.620595] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 42.622169] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.623443] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.681707] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 42.687436] systemd[1]: Finished linger-users.service. machine # [ 42.839483] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 42.841890] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 43.101429] nixos[2414]: finished switching to system configuration /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.94 seconds) machine: must succeed: /nix/store/g4gwwljhlwpqwl3xv48qmcbz5dm6j6r0-separate-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 43.389033] nixos[2555]: switching to system configuration /nix/store/n1dzxqbxl28xyfvyr2wv6b6ryn584vpb-nixos-system-machine-test machine # [ 43.391636] systemd[1]: Stopped target Local File Systems. machine # [ 43.394090] systemd[1]: Stopped target Remote File Systems. machine # [ 43.689727] systemd[1]: Reload requested from client PID 2555 ('.switch-to-conf') (unit backdoor.service)... machine # [ 43.691637] systemd[1]: Reloading... machine # [ 44.018157] systemd[1]: Reloading finished in 324 ms. machine # [ 44.111931] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 44.115370] systemd[527]: Reexecution requested from client PID 2624 ('switch-to-confi')... machine # [ 44.117891] systemd[527]: Reexecuting. machine # [ 44.176295] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.297932] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.304880] systemd[527]: Reload requested from client PID 2624 ('switch-to-confi')... machine # [ 44.306270] systemd[527]: Reloading... machine # [ 44.418612] systemd[527]: Reloading finished in 110 ms. machine # [ 44.440239] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.457029] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 44.458136] systemd[1]: Stopping Reactivate sysinit units... machine # [ 44.461857] systemd[1]: Reached target Reactivate sysinit units. machine # [ 44.462871] systemd[1]: Reached target Remote File Systems. machine # [ 44.465660] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 44.470396] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 44.471846] systemd[1]: Reached target Local File Systems. machine # [ 44.480185] systemd[1]: Starting linger-users.service... machine # [ 44.495833] systemd[1]: Starting test.service... machine # [ 44.497695] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 44.508158] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 44.509230] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 44.510558] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 44.522334] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 44.531479] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 44.543159] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 44.564218] systemd[1]: Finished test.service. machine # [ 44.591651] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 44.596275] systemd[1]: Finished linger-users.service. machine # [ 44.733135] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 44.736531] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 44.995387] nixos[2555]: finished switching to system configuration /nix/store/n1dzxqbxl28xyfvyr2wv6b6ryn584vpb-nixos-system-machine-test machine: (finished: must succeed: /nix/store/g4gwwljhlwpqwl3xv48qmcbz5dm6j6r0-separate-activation-script/bin/switch-to-configuration test 2>&1, in 1.89 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/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&1 machine # [ 45.340823] nixos[2704]: switching to system configuration /nix/store/dkb1kddvdw0slkgawicdri6yyjbclmr2-nixos-system-machine-test machine # [ 45.343800] systemd[1]: test.service: Deactivated successfully. machine # [ 45.346037] systemd[1]: Stopped test.service. machine # [ 45.346705] systemd[1]: Stopped target Local File Systems. machine # [ 45.347921] systemd[1]: Stopped target Remote File Systems. machine # [ 45.637704] systemd[1]: Reload requested from client PID 2704 ('.switch-to-conf') (unit backdoor.service)... machine # [ 45.639645] systemd[1]: Reloading... machine # [ 45.975165] systemd[1]: Reloading finished in 333 ms. machine # [ 46.061167] systemd[527]: Reexecution requested from client PID 2773 ('switch-to-confi')... machine # [ 46.065486] systemd[527]: Reexecuting. machine # [ 46.119671] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.244831] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.252129] systemd[527]: Reload requested from client PID 2773 ('switch-to-confi')... machine # [ 46.253494] systemd[527]: Reloading... machine # [ 46.374955] systemd[527]: Reloading finished in 119 ms. machine # [ 46.392172] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.404285] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 46.440892] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 46.444508] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 46.446876] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 46.448781] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 46.456534] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 46.457475] systemd[1]: Stopping Reactivate sysinit units... machine # [ 46.458258] systemd[1]: Reached target Reactivate sysinit units. machine # [ 46.474119] systemd[1]: Started test.service. machine # [ 46.480326] systemd[1]: Reached target Remote File Systems. machine # [ 46.486606] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.493235] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 46.494678] systemd[1]: Reached target Local File Systems. machine # [ 46.499779] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 46.505803] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 46.506682] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 46.524425] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 46.527145] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 46.542700] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.547029] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.551842] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 46.561828] systemd[1]: test.service: Deactivated successfully. machine # [ 46.749950] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 46.752209] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 46.758012] systemd[1]: Starting linger-users.service... machine # [ 46.802960] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 46.806039] systemd[1]: Finished linger-users.service. machine # [ 47.062648] nixos[2704]: finished switching to system configuration /nix/store/dkb1kddvdw0slkgawicdri6yyjbclmr2-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&1, in 1.98 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&1 machine # [ 47.347711] nixos[2849]: switching to system configuration /nix/store/3bl21xac2l7wxzfs06p2f9rvj0qd6y7w-nixos-system-machine-test machine # [ 47.350524] systemd[1]: Stopped target Remote File Systems. machine # [ 47.352769] systemd[1]: Stopped target Local File Systems. machine # [ 47.636668] systemd[1]: Reload requested from client PID 2849 ('.switch-to-conf') (unit backdoor.service)... machine # [ 47.638608] systemd[1]: Reloading... machine # [ 48.045892] systemd[1]: Reloading finished in 404 ms. machine # [ 48.136091] systemd[527]: Reexecution requested from client PID 2918 ('switch-to-confi')... machine # [ 48.141217] systemd[527]: Reexecuting. machine # [ 48.187326] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.301263] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.308130] systemd[527]: Reload requested from client PID 2918 ('switch-to-confi')... machine # [ 48.310640] systemd[527]: Reloading... machine # [ 48.422445] systemd[527]: Reloading finished in 110 ms. machine # [ 48.440176] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.450176] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 48.452796] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 48.454961] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 48.489950] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 48.494158] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 48.496355] systemd[1]: Stopping Reactivate sysinit units... machine # [ 48.497196] systemd[1]: Reached target Reactivate sysinit units. machine # [ 48.499421] systemd[1]: Reached target Local File Systems. machine # [ 48.504003] systemd[1]: Reached target Remote File Systems. machine # [ 48.507109] systemd[1]: Starting escaped\x2ddash.service... machine # [ 48.511198] systemd[1]: Starting linger-users.service... machine # [ 48.523255] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 48.533865] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 48.534765] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 48.548587] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 48.550936] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 48.564126] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.565493] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.566751] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.579021] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 48.583601] systemd[1]: Finished escaped\x2ddash.service. machine # [ 48.614135] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 48.618375] systemd[1]: Finished linger-users.service. machine # [ 48.755163] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 48.758426] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 49.016620] nixos[2849]: finished switching to system configuration /nix/store/3bl21xac2l7wxzfs06p2f9rvj0qd6y7w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&1, in 1.95 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&1 machine # [ 49.309611] nixos[2991]: switching to system configuration /nix/store/l343976di0mbky3iy3ab6c5lq8g7ljli-nixos-system-machine-test machine # [ 49.312083] systemd[1]: Stopped target Remote File Systems. machine # [ 49.315560] systemd[1]: Stopped target Local File Systems. machine # [ 49.316884] systemd[1]: escaped\x2ddash.service: Deactivated successfully. machine # [ 49.317819] systemd[1]: Stopped escaped\x2ddash.service. machine # [ 49.592778] systemd[1]: Reload requested from client PID 2991 ('.switch-to-conf') (unit backdoor.service)... machine # [ 49.594620] systemd[1]: Reloading... machine # [ 49.920299] systemd[1]: Reloading finished in 324 ms. machine # [ 50.003960] systemd[527]: Reexecution requested from client PID 3059 ('switch-to-confi')... machine # [ 50.008562] systemd[527]: Reexecuting. machine # [ 50.067763] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.184810] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.191357] systemd[527]: Reload requested from client PID 3059 ('switch-to-confi')... machine # [ 50.194635] systemd[527]: Reloading... machine # [ 50.301639] systemd[527]: Reloading finished in 108 ms. machine # [ 50.320187] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.329628] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 50.331991] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 50.334159] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 50.364772] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 50.368814] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 50.370742] systemd[1]: Stopping Reactivate sysinit units... machine # [ 50.371623] systemd[1]: Reached target Reactivate sysinit units. machine # [ 50.376962] systemd[1]: Reached target Local File Systems. machine # [ 50.377762] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 50.381078] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 50.381927] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 50.383142] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 50.400402] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 50.402024] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.403258] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.404614] systemd[1]: Reached target Remote File Systems. machine # [ 50.405376] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.406589] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 50.560416] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 50.563217] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 50.568296] systemd[1]: Starting escaped\x2ddash.service... machine # [ 50.572450] systemd[1]: Starting linger-users.service... machine # [ 50.611456] systemd[1]: Finished escaped\x2ddash.service. machine # [ 50.629711] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 50.632331] systemd[1]: Finished linger-users.service. machine # [ 50.890651] nixos[2991]: finished switching to system configuration /nix/store/l343976di0mbky3iy3ab6c5lq8g7ljli-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&1, in 1.88 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&1 machine # [ 51.219741] nixos[3133]: switching to system configuration /nix/store/21l0j63qdkzv26jr7j9d1n24x1g3xk4s-nixos-system-machine-test machine # [ 51.222711] systemd[1]: Stopped target Remote File Systems. machine # [ 51.226570] systemd[1]: escaped\x2ddash.service: Deactivated successfully. machine # [ 51.228316] systemd[1]: Stopped escaped\x2ddash.service. machine # [ 51.229189] systemd[1]: Stopped target Local File Systems. machine # [ 51.517006] systemd[1]: Reload requested from client PID 3133 ('.switch-to-conf') (unit backdoor.service)... machine # [ 51.519090] systemd[1]: Reloading... machine # [ 51.849593] systemd[1]: Reloading finished in 327 ms. machine # [ 51.934821] systemd[527]: Reexecution requested from client PID 3202 ('switch-to-confi')... machine # [ 51.938786] systemd[527]: Reexecuting. machine # [ 51.999399] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.115280] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.122186] systemd[527]: Reload requested from client PID 3202 ('switch-to-confi')... machine # [ 52.125651] systemd[527]: Reloading... machine # [ 52.232504] systemd[527]: Reloading finished in 107 ms. machine # [ 52.249602] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.258498] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 52.260870] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 52.262995] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 52.295581] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 52.299370] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 52.301054] systemd[1]: Stopping Reactivate sysinit units... machine # [ 52.301898] systemd[1]: Reached target Reactivate sysinit units. machine # [ 52.303993] systemd[1]: Reached target Remote File Systems. machine # [ 52.307297] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 52.309299] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 52.311272] systemd[1]: Reached target Local File Systems. machine # [ 52.315835] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 52.319486] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 52.323107] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 52.328701] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 52.330981] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 52.337443] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 52.338778] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 52.497487] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 52.499991] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 52.504335] systemd[1]: Starting -.service... machine # [ 52.507845] systemd[1]: Starting linger-users.service... machine # [ 52.546483] systemd[1]: Finished -.service. machine # [ 52.564972] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 52.567547] systemd[1]: Finished linger-users.service. machine # [ 52.827067] nixos[3133]: finished switching to system configuration /nix/store/21l0j63qdkzv26jr7j9d1n24x1g3xk4s-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&1, in 1.93 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&1 machine # [ 53.121680] nixos[3275]: switching to system configuration /nix/store/j5qjij09mwzmwdw59wz2hxik5bma4a3p-nixos-system-machine-test machine # [ 53.124463] systemd[1]: Stopped target Local File Systems. machine # [ 53.126769] systemd[1]: Stopped target Remote File Systems. machine # [ 53.408750] systemd[1]: Reload requested from client PID 3275 ('.switch-to-conf') (unit backdoor.service)... machine # [ 53.410609] systemd[1]: Reloading... machine # [ 53.737690] systemd[1]: Reloading finished in 325 ms. machine # [ 53.840199] systemd[527]: Reexecution requested from client PID 3344 ('switch-to-confi')... machine # [ 53.844670] systemd[527]: Reexecuting. machine # [ 53.887574] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.001074] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.007998] systemd[527]: Reload requested from client PID 3344 ('switch-to-confi')... machine # [ 54.009431] systemd[527]: Reloading... machine # [ 54.120441] systemd[527]: Reloading finished in 109 ms. machine # [ 54.140184] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.151196] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 54.152294] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 54.154551] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 54.188742] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 54.193023] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 54.197339] systemd[1]: Stopping Reactivate sysinit units... machine # [ 54.198277] systemd[1]: Reached target Reactivate sysinit units. machine # [ 54.200346] systemd[1]: Reloading -.service... machine # [ 54.225467] systemd[1]: Reloaded -.service. machine # [ 54.232842] systemd[1]: Reached target Local File Systems. machine # [ 54.236815] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.244868] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 54.246259] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 54.247757] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 54.256575] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 54.257889] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 54.260235] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 54.261833] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.263119] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.264403] systemd[1]: Reached target Remote File Systems. machine # [ 54.424835] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 54.429640] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 54.431737] systemd[1]: Starting linger-users.service... machine # [ 54.473113] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 54.475552] systemd[1]: Finished linger-users.service. machine # [ 54.735563] nixos[3275]: finished switching to system configuration /nix/store/j5qjij09mwzmwdw59wz2hxik5bma4a3p-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&1, in 1.91 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&1 machine # [ 55.017366] nixos[3417]: switching to system configuration /nix/store/ric6cl470b68bnxy2bg9qs952p2rlz8c-nixos-system-machine-test machine # [ 55.019885] systemd[1]: Stopped target Remote File Systems. machine # [ 55.023165] systemd[1]: Stopped target Local File Systems. machine # [ 55.024127] systemd[1]: -.service: Deactivated successfully. machine # [ 55.025184] systemd[1]: Stopped -.service. machine # [ 55.299993] systemd[1]: Reload requested from client PID 3417 ('.switch-to-conf') (unit backdoor.service)... machine # [ 55.302011] systemd[1]: Reloading... machine # [ 55.642140] systemd[1]: Reloading finished in 338 ms. machine # [ 55.731027] systemd[527]: Reexecution requested from client PID 3486 ('switch-to-confi')... machine # [ 55.735274] systemd[527]: Reexecuting. machine # [ 55.787620] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.903645] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.910356] systemd[527]: Reload requested from client PID 3486 ('switch-to-confi')... machine # [ 55.914037] systemd[527]: Reloading... machine # [ 56.023620] systemd[527]: Reloading finished in 110 ms. machine # [ 56.040172] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.050321] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 56.051396] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 56.053795] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 56.084364] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 56.088582] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 56.089558] systemd[1]: Stopping Reactivate sysinit units... machine # [ 56.090352] systemd[1]: Reached target Reactivate sysinit units. machine # [ 56.092521] systemd[1]: Reached target Local File Systems. machine # [ 56.095178] systemd[1]: Reached target Remote File Systems. machine # [ 56.104252] systemd[1]: Starting linger-users.service... machine # [ 56.108115] systemd[1]: Starting required-service.service... machine # [ 56.124952] systemd[1]: Starting test-service.service... machine # [ 56.125858] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 56.127372] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 56.143994] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 56.145371] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 56.147573] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 56.164453] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.165833] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.181723] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.191833] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 56.201049] systemd[1]: Finished required-service.service. machine # [ 56.202086] systemd[1]: Finished test-service.service. machine # [ 56.233115] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 56.236486] systemd[1]: Finished linger-users.service. machine # [ 56.369034] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 56.371428] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 56.631976] nixos[3417]: finished switching to system configuration /nix/store/ric6cl470b68bnxy2bg9qs952p2rlz8c-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&1, in 1.90 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&1 machine # [ 56.918531] nixos[3561]: switching to system configuration /nix/store/cv3mrzx5dxls140gn49gyz05fcxgdcbh-nixos-system-machine-test machine # [ 56.921149] systemd[1]: required-service.service: Deactivated successfully. machine # [ 56.924312] systemd[1]: Stopped required-service.service. machine # [ 56.925145] systemd[1]: test-service.service: Deactivated successfully. machine # [ 56.926033] systemd[1]: Stopped test-service.service. machine # [ 56.926749] systemd[1]: Stopped target Local File Systems. machine # [ 56.928114] systemd[1]: Stopped target Remote File Systems. machine # [ 57.209532] systemd[1]: Reload requested from client PID 3561 ('.switch-to-conf') (unit backdoor.service)... machine # [ 57.211410] systemd[1]: Reloading... machine # [ 57.533914] systemd[1]: Reloading finished in 320 ms. machine # [ 57.616406] systemd[527]: Reexecution requested from client PID 3630 ('switch-to-confi')... machine # [ 57.619929] systemd[527]: Reexecuting. machine # [ 57.679385] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.794442] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.800802] systemd[527]: Reload requested from client PID 3630 ('switch-to-confi')... machine # [ 57.804076] systemd[527]: Reloading... machine # [ 57.911247] systemd[527]: Reloading finished in 108 ms. machine # [ 57.932168] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.941081] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 57.943357] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 57.945452] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 57.975997] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 57.979329] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 57.980932] systemd[1]: Stopping Reactivate sysinit units... machine # [ 57.981724] systemd[1]: Reached target Reactivate sysinit units. machine # [ 57.984748] systemd[1]: Reached target Remote File Systems. machine # [ 57.992229] systemd[1]: Starting required-service.service... machine # [ 57.995186] systemd[1]: Starting test-service.service... machine # [ 58.005182] systemd[1]: Reached target Local File Systems. machine # [ 58.012312] systemd[1]: Starting linger-users.service... machine # [ 58.020349] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.021646] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 58.022902] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 58.037445] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 58.038324] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 58.049247] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 58.060677] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 58.072454] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.079582] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.084502] systemd[1]: Finished required-service.service. machine # [ 58.085307] systemd[1]: Finished test-service.service. machine # [ 58.118524] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 58.122419] systemd[1]: Finished linger-users.service. machine # [ 58.257380] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 58.260446] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 58.518644] nixos[3561]: finished switching to system configuration /nix/store/cv3mrzx5dxls140gn49gyz05fcxgdcbh-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&1, in 1.89 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&1 machine # [ 58.811419] nixos[3705]: switching to system configuration /nix/store/f085d2klz1cydafy975as9xmlz8kjaym-nixos-system-machine-test machine # [ 58.814327] systemd[1]: Stopped target Remote File Systems. machine # [ 58.818348] systemd[1]: required-service.service: Deactivated successfully. machine # [ 58.819423] systemd[1]: Stopped required-service.service. machine # [ 58.820867] systemd[1]: test-service.service: Deactivated successfully. machine # [ 58.821794] systemd[1]: Stopped test-service.service. machine # [ 58.822495] systemd[1]: Stopped target Local File Systems. machine # [ 59.147481] systemd[1]: Reload requested from client PID 3705 ('.switch-to-conf') (unit backdoor.service)... machine # [ 59.149809] systemd[1]: Reloading... machine # [ 59.483156] systemd[1]: Reloading finished in 332 ms. machine # [ 59.575275] systemd[527]: Reexecution requested from client PID 3774 ('switch-to-confi')... machine # [ 59.577857] systemd[527]: Reexecuting. machine # [ 59.640315] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.758492] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.765214] systemd[527]: Reload requested from client PID 3774 ('switch-to-confi')... machine # [ 59.767729] systemd[527]: Reloading... machine # [ 59.883168] systemd[527]: Reloading finished in 114 ms. machine # [ 59.900181] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.909143] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 59.911635] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 59.913889] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 59.949259] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 59.953694] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 59.957150] systemd[1]: Stopping Reactivate sysinit units... machine # [ 59.958078] systemd[1]: Reached target Reactivate sysinit units. machine # [ 59.959561] systemd[1]: Reached target Remote File Systems. machine # [ 59.964268] systemd[1]: Reached target Local File Systems. machine # [ 59.965164] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 59.970057] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 59.971005] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 59.980626] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 59.982863] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 59.991432] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.992877] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.994079] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.995373] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 60.153826] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 60.158460] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 60.161821] systemd[1]: Starting linger-users.service... machine # [ 60.165997] systemd[1]: Starting required-service.service... machine # [ 60.173197] systemd[1]: Starting test-service.service... machine # [ 60.219880] systemd[1]: Finished required-service.service. machine # [ 60.223448] systemd[1]: Finished test-service.service. machine # [ 60.241100] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 60.242958] systemd[1]: Finished linger-users.service. machine # [ 60.502087] nixos[3705]: finished switching to system configuration /nix/store/f085d2klz1cydafy975as9xmlz8kjaym-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&1, in 1.98 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&1 machine # [ 60.786856] nixos[3849]: switching to system configuration /nix/store/swlqqd0i4bb1iasknxfsbqlwgxxkjr9l-nixos-system-machine-test machine # [ 60.789382] systemd[1]: test-service.service: Deactivated successfully. machine # [ 60.792360] systemd[1]: Stopped test-service.service. machine # [ 60.793140] systemd[1]: required-service.service: Deactivated successfully. machine # [ 60.794055] systemd[1]: Stopped required-service.service. machine # [ 60.795392] systemd[1]: Stopped target Local File Systems. machine # [ 60.797434] systemd[1]: Stopped target Remote File Systems. machine # [ 61.071429] systemd[1]: Reload requested from client PID 3849 ('.switch-to-conf') (unit backdoor.service)... machine # [ 61.073502] systemd[1]: Reloading... machine # [ 61.409096] systemd[1]: Reloading finished in 334 ms. machine # [ 61.495183] systemd[527]: Reexecution requested from client PID 3918 ('switch-to-confi')... machine # [ 61.499464] systemd[527]: Reexecuting. machine # [ 61.564382] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 61.690981] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 61.698705] systemd[527]: Reload requested from client PID 3918 ('switch-to-confi')... machine # [ 61.702915] systemd[527]: Reloading... machine # [ 61.823553] systemd[527]: Reloading finished in 121 ms. machine # [ 61.841778] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 61.849864] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 61.852173] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 61.854623] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 61.888689] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 61.892750] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 61.895561] systemd[1]: Stopping Reactivate sysinit units... machine # [ 61.898080] systemd[1]: Reached target Reactivate sysinit units. machine # [ 61.905010] systemd[1]: Created slice Slice /system/instantiated. machine # [ 61.907960] systemd[1]: Reached target Remote File Systems. machine # [ 61.911643] systemd[1]: Starting instantiated@one.service... machine # [ 61.916644] systemd[1]: Starting instantiated@two.service... machine # [ 61.928269] systemd[1]: Starting linger-users.service... machine # [ 61.934441] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 61.935811] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 61.946819] systemd[1]: Reached target Local File Systems. machine # [ 61.947639] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 61.955920] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 61.960725] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 61.970007] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 61.981304] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 61.983093] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.000859] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.005970] systemd[1]: Finished instantiated@one.service. machine # [ 62.006857] systemd[1]: Finished instantiated@two.service. machine # [ 62.037697] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 62.041734] systemd[1]: Finished linger-users.service. machine # [ 62.172361] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 62.175357] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 62.433586] nixos[3849]: finished switching to system configuration /nix/store/swlqqd0i4bb1iasknxfsbqlwgxxkjr9l-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&1, in 1.93 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&1 machine # [ 62.732817] nixos[3993]: switching to system configuration /nix/store/753pi2abh34gzql03mfpk097icfp7xvn-nixos-system-machine-test machine # [ 62.735752] systemd[1]: instantiated@one.service: Deactivated successfully. machine # [ 62.738267] systemd[1]: Stopped instantiated@one.service. machine # [ 62.739090] systemd[1]: instantiated@two.service: Deactivated successfully. machine # [ 62.741766] systemd[1]: Stopped instantiated@two.service. machine # [ 62.743201] systemd[1]: Stopped target Local File Systems. machine # [ 62.743991] systemd[1]: Stopped target Remote File Systems. machine # [ 63.019743] systemd[1]: Reload requested from client PID 3993 ('.switch-to-conf') (unit backdoor.service)... machine # [ 63.021878] systemd[1]: Reloading... machine # [ 63.348910] systemd[1]: Reloading finished in 325 ms. machine # [ 63.438617] systemd[527]: Reexecution requested from client PID 4062 ('switch-to-confi')... machine # [ 63.441573] systemd[527]: Reexecuting. machine # [ 63.503464] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 63.626135] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 63.632640] systemd[527]: Reload requested from client PID 4062 ('switch-to-confi')... machine # [ 63.636163] systemd[527]: Reloading... machine # [ 63.746954] systemd[527]: Reloading finished in 111 ms. machine # [ 63.764184] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 63.773705] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 63.775984] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 63.778123] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 63.812009] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 63.815475] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 63.817521] systemd[1]: Stopping Reactivate sysinit units... machine # [ 63.818337] systemd[1]: Reached target Reactivate sysinit units. machine # [ 63.822320] systemd[1]: Reached target Local File Systems. machine # [ 63.823158] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 63.832396] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 63.833258] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 63.834421] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 63.846912] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 63.849684] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 63.850965] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 63.852799] systemd[1]: Reached target Remote File Systems. machine # [ 63.853616] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 63.855396] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 64.011769] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 64.016656] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 64.019827] systemd[1]: Starting instantiated@one.service... machine # [ 64.023562] systemd[1]: Starting instantiated@two.service... machine # [ 64.028382] systemd[1]: Starting linger-users.service... machine # [ 64.076329] systemd[1]: Finished instantiated@one.service. machine # [ 64.077923] systemd[1]: Finished instantiated@two.service. machine # [ 64.099442] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 64.102503] systemd[1]: Finished linger-users.service. machine # [ 64.359085] nixos[3993]: finished switching to system configuration /nix/store/753pi2abh34gzql03mfpk097icfp7xvn-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&1, in 1.93 seconds) (finished: subtest: services, in 42.04 seconds) subtest: failing units machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 64.628328] nixos[4137]: switching to system configuration /nix/store/ijl55ziz03scd38hmlj5rhw0mnk7rbax-nixos-system-machine-test machine # [ 64.631170] systemd[1]: Stopped target Local File Systems. machine # [ 64.635623] systemd[1]: instantiated@one.service: Deactivated successfully. machine # [ 64.637862] systemd[1]: Stopped instantiated@one.service. machine # [ 64.638674] systemd[1]: instantiated@two.service: Deactivated successfully. machine # [ 64.639593] systemd[1]: Stopped instantiated@two.service. machine # [ 64.640616] systemd[1]: Stopped target Remote File Systems. machine # [ 64.893722] systemd[1]: Reload requested from client PID 4137 ('.switch-to-conf') (unit backdoor.service)... machine # [ 64.895591] systemd[1]: Reloading... machine # [ 65.206560] systemd[1]: Reloading finished in 309 ms. machine # [ 65.295203] systemd[527]: Reexecution requested from client PID 4206 ('switch-to-confi')... machine # [ 65.297920] systemd[527]: Reexecuting. machine # [ 65.355415] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.470676] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.477047] systemd[527]: Reload requested from client PID 4206 ('switch-to-confi')... machine # [ 65.480795] systemd[527]: Reloading... machine # [ 65.589128] systemd[527]: Reloading finished in 109 ms. machine # [ 65.604178] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.612695] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 65.614968] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 65.617014] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 65.647377] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 65.651619] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 65.652949] systemd[1]: Stopping Reactivate sysinit units... machine # [ 65.653747] systemd[1]: Reached target Reactivate sysinit units. machine # [ 65.656634] systemd[1]: Reached target Local File Systems. machine # [ 65.659250] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 65.668732] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 65.669593] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 65.670806] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 65.683049] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 65.684690] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 65.685901] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 65.687052] systemd[1]: Reached target Remote File Systems. machine # [ 65.687801] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 65.689408] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 65.843555] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 65.848349] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 65.851408] systemd[1]: Starting linger-users.service... machine # [ 65.853979] systemd[1]: Starting test.service... machine # [ 65.890190] systemd[1]: Finished test.service. machine # [ 65.907054] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 65.909469] systemd[1]: Finished linger-users.service. machine # [ 66.169309] nixos[4137]: finished switching to system configuration /nix/store/ijl55ziz03scd38hmlj5rhw0mnk7rbax-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 1.81 seconds) machine: must fail: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 66.455105] nixos[4279]: switching to system configuration /nix/store/m0s53gwrilyzcx14h11llnp4fnic6spb-nixos-system-machine-test machine # [ 66.457588] systemd[1]: test.service: Deactivated successfully. machine # [ 66.460936] systemd[1]: Stopped test.service. machine # [ 66.462129] systemd[1]: Stopped target Local File Systems. machine # [ 66.462918] systemd[1]: Stopped target Remote File Systems. machine # [ 66.733838] systemd[1]: Reload requested from client PID 4279 ('.switch-to-conf') (unit backdoor.service)... machine # [ 66.735343] systemd[1]: Reloading... machine # [ 67.057266] systemd[1]: Reloading finished in 319 ms. machine # [ 67.142170] systemd[527]: Reexecution requested from client PID 4348 ('switch-to-confi')... machine # [ 67.143528] systemd[527]: Reexecuting. machine # [ 67.203663] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 67.319221] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 67.325544] systemd[527]: Reload requested from client PID 4348 ('switch-to-confi')... machine # [ 67.327816] systemd[527]: Reloading... machine # [ 67.435837] systemd[527]: Reloading finished in 107 ms. machine # [ 67.452147] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 67.461380] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 67.463651] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 67.465702] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 67.495891] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 67.499865] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 67.502773] systemd[1]: Stopping Reactivate sysinit units... machine # [ 67.503623] systemd[1]: Reached target Reactivate sysinit units. machine # [ 67.508139] systemd[1]: Reached target Remote File Systems. machine # [ 67.511538] systemd[1]: Starting linger-users.service... machine # [ 67.514794] systemd[1]: Starting test.service... machine # [ 67.527320] systemd[1]: Reached target Local File Systems. machine # [ 67.530839] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 67.536807] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 67.537677] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 67.538896] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 67.555810] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 67.563910] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.571720] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.577805] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.579215] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 67.590344] systemd[1]: test.service: Main process exited, code=exited, status=1/FAILURE machine # [ 67.591697] systemd[1]: test.service: Failed with result 'exit-code'. machine # [ 67.596333] systemd[1]: Failed to start test.service. machine # [ 67.623031] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 67.624692] systemd[1]: Finished linger-users.service. machine # [ 67.755375] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 67.758237] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 68.049682] nixos[4279]: switching to system configuration /nix/store/m0s53gwrilyzcx14h11llnp4fnic6spb-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&1, in 1.88 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1 machine # [ 68.317345] nixos[4422]: switching to system configuration /nix/store/rpc128j2k0vykfwq1q19hbzhvi9467d0-nixos-system-machine-test machine # [ 68.319796] systemd[1]: Stopped target Remote File Systems. machine # [ 68.322592] systemd[1]: Stopped target Local File Systems. machine # [ 68.598643] systemd[1]: Reload requested from client PID 4422 ('.switch-to-conf') (unit backdoor.service)... machine # [ 68.600654] systemd[1]: Reloading... machine # [ 68.921745] systemd[1]: Reloading finished in 319 ms. machine # [ 69.005136] systemd[527]: Reexecution requested from client PID 4491 ('switch-to-confi')... machine # [ 69.008576] systemd[527]: Reexecuting. machine # [ 69.067354] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.182917] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.189535] systemd[527]: Reload requested from client PID 4491 ('switch-to-confi')... machine # [ 69.193222] systemd[527]: Reloading... machine # [ 69.299909] systemd[527]: Reloading finished in 107 ms. machine # [ 69.316158] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.324779] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 69.327072] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 69.329159] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 69.360488] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 69.363862] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 69.366777] systemd[1]: Stopping Reactivate sysinit units... machine # [ 69.367618] systemd[1]: Reached target Reactivate sysinit units. machine # [ 69.369377] systemd[1]: Reached target Remote File Systems. machine # [ 69.376216] systemd[1]: Started autorestart.service. machine # [ 69.380270] systemd[1]: Starting linger-users.service... machine # [ 69.394672] systemd[1]: Reached target Local File Systems. machine # [ 69.395565] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 69.407257] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 69.408244] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 69.409477] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 69.411657] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 69.424744] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 69.426075] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 69.437148] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 69.438680] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 69.450574] systemd[1]: autorestart.service: Deactivated successfully. machine # [ 69.479983] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 69.483630] systemd[1]: Finished linger-users.service. machine # [ 69.611688] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 69.615345] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 69.874024] nixos[4422]: finished switching to system configuration /nix/store/rpc128j2k0vykfwq1q19hbzhvi9467d0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 1.82 seconds) machine # [ 69.943917] systemd[1]: Stopped autorestart.service. machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1 machine # [ 70.170056] nixos[4569]: switching to system configuration /nix/store/rpc128j2k0vykfwq1q19hbzhvi9467d0-nixos-system-machine-test machine # [ 70.172440] systemd[1]: Stopped target Remote File Systems. machine # [ 70.174577] systemd[1]: Stopped target Local File Systems. machine # [ 70.426040] systemd[1]: Reload requested from client PID 4569 ('.switch-to-conf') (unit backdoor.service)... machine # [ 70.427980] systemd[1]: Reloading... machine # [ 70.734935] systemd[1]: Reloading finished in 305 ms. machine # [ 70.856637] systemd[527]: Reexecution requested from client PID 4638 ('switch-to-confi')... machine # [ 70.858880] systemd[527]: Reexecuting. machine # [ 70.919648] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.039732] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.046978] systemd[527]: Reload requested from client PID 4638 ('switch-to-confi')... machine # [ 71.050765] systemd[527]: Reloading... machine # [ 71.165471] systemd[527]: Reloading finished in 115 ms. machine # [ 71.180164] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.191412] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 71.193699] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 71.195788] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 71.227405] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 71.231509] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 71.233809] systemd[1]: Stopping Reactivate sysinit units... machine # [ 71.234684] systemd[1]: Reached target Reactivate sysinit units. machine # [ 71.238331] systemd[1]: Reached target Local File Systems. machine # [ 71.239201] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 71.248294] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 71.249172] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 71.250383] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 71.264224] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 71.265899] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.267613] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.269744] systemd[1]: Reached target Remote File Systems. machine # [ 71.270998] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.274925] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 71.421690] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 71.423970] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 71.430047] systemd[1]: Started autorestart.service. machine # [ 71.432502] systemd[1]: Starting linger-users.service... machine # [ 71.469282] systemd[1]: autorestart.service: Deactivated successfully. machine # [ 71.486602] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 71.489598] systemd[1]: Finished linger-users.service. machine # [ 71.748599] nixos[4569]: finished switching to system configuration /nix/store/rpc128j2k0vykfwq1q19hbzhvi9467d0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 1.80 seconds) machine # [ 71.818317] systemd[1]: Stopped autorestart.service. machine: must fail: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 72.063734] nixos[4716]: switching to system configuration /nix/store/x9jmr14h2lll0mab9xxpxwlhjsnzplrm-nixos-system-machine-test machine # [ 72.066277] systemd[1]: Stopped target Local File Systems. machine # [ 72.068624] systemd[1]: Stopped target Remote File Systems. machine # [ 72.338631] systemd[1]: Reload requested from client PID 4716 ('.switch-to-conf') (unit backdoor.service)... machine # [ 72.340631] systemd[1]: Reloading... machine # [ 72.662360] systemd[1]: Reloading finished in 320 ms. machine # [ 72.752526] systemd[527]: Reexecution requested from client PID 4785 ('switch-to-confi')... machine # [ 72.756103] systemd[527]: Reexecuting. machine # [ 72.815526] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.935874] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.942551] systemd[527]: Reload requested from client PID 4785 ('switch-to-confi')... machine # [ 72.946220] systemd[527]: Reloading... machine # [ 73.053628] systemd[527]: Reloading finished in 108 ms. machine # [ 73.068166] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.078498] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 73.081027] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 73.083152] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 73.115884] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 73.119553] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 73.122135] systemd[1]: Stopping Reactivate sysinit units... machine # [ 73.122986] systemd[1]: Reached target Reactivate sysinit units. machine # [ 73.125851] systemd[1]: Reached target Remote File Systems. machine # [ 73.130693] systemd[1]: Reached target Local File Systems. machine # [ 73.134367] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 73.141099] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 73.141986] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 73.143178] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 73.153211] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 73.154858] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.156220] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.157372] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.158598] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 73.312129] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 73.313846] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 73.320152] systemd[1]: Started autorestart.service. machine # [ 73.323138] systemd[1]: Starting linger-users.service... machine # [ 73.360685] systemd[1]: autorestart.service: Main process exited, code=exited, status=1/FAILURE machine # [ 73.365314] systemd[1]: autorestart.service: Failed with result 'exit-code'. machine # [ 73.381830] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 73.384156] systemd[1]: Finished linger-users.service. machine # [ 73.676304] nixos[4716]: switching to system configuration /nix/store/x9jmr14h2lll0mab9xxpxwlhjsnzplrm-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&1, in 1.86 seconds) (finished: subtest: failing units, in 9.32 seconds) subtest: unit file parser machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 73.932103] nixos[4859]: switching to system configuration /nix/store/haj41h0p4x0qrkkaycgivzipfxs0a5m0-nixos-system-machine-test machine # [ 73.934675] systemd[1]: Stopped autorestart.service. machine # [ 73.938144] systemd[1]: Stopped target Local File Systems. machine # [ 73.939026] systemd[1]: Stopped target Remote File Systems. machine # [ 74.198343] systemd[1]: Reload requested from client PID 4859 ('.switch-to-conf') (unit backdoor.service)... machine # [ 74.200311] systemd[1]: Reloading... machine # [ 74.503429] systemd[1]: Reloading finished in 301 ms. machine # [ 74.589629] systemd[527]: Reexecution requested from client PID 4928 ('switch-to-confi')... machine # [ 74.591071] systemd[527]: Reexecuting. machine # [ 74.651269] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 74.765534] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 74.771653] systemd[527]: Reload requested from client PID 4928 ('switch-to-confi')... machine # [ 74.773980] systemd[527]: Reloading... machine # [ 74.880656] systemd[527]: Reloading finished in 105 ms. machine # [ 74.897543] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 74.905506] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 74.907765] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 74.909783] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 74.938752] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 74.942089] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 74.944459] systemd[1]: Stopping Reactivate sysinit units... machine # [ 74.945278] systemd[1]: Reached target Reactivate sysinit units. machine # [ 74.949904] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 74.952733] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 74.956241] systemd[1]: Reached target Remote File Systems. machine # [ 74.960084] systemd[1]: Starting linger-users.service... machine # [ 74.964396] systemd[1]: Starting test.service... machine # [ 74.976576] systemd[1]: Reached target Local File Systems. machine # [ 74.977420] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 74.978835] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 74.979616] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 74.993347] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 74.995540] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 75.009950] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 75.015930] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 75.027814] systemd[1]: Finished test.service. machine # [ 75.054449] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 75.058807] systemd[1]: Finished linger-users.service. machine # [ 75.189312] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 75.192766] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 75.451074] nixos[4859]: finished switching to system configuration /nix/store/haj41h0p4x0qrkkaycgivzipfxs0a5m0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 1.77 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&1 machine # [ 75.737501] nixos[5001]: switching to system configuration /nix/store/k1m9sj9ashaxhi95zbk8y1q4wrv5dbyj-nixos-system-machine-test machine # [ 75.740313] systemd[1]: Stopped target Remote File Systems. machine # [ 75.742786] systemd[1]: Stopped target Local File Systems. machine # [ 76.014580] systemd[1]: Reload requested from client PID 5001 ('.switch-to-conf') (unit backdoor.service)... machine # [ 76.016571] systemd[1]: Reloading... machine # [ 76.336273] systemd[1]: Reloading finished in 318 ms. machine # [ 76.428158] systemd[527]: Reexecution requested from client PID 5070 ('switch-to-confi')... machine # [ 76.432201] systemd[527]: Reexecuting. machine # [ 76.487316] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.606561] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.612830] systemd[527]: Reload requested from client PID 5070 ('switch-to-confi')... machine # [ 76.616062] systemd[527]: Reloading... machine # [ 76.724451] systemd[527]: Reloading finished in 109 ms. machine # [ 76.740153] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.749082] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 76.751355] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 76.753373] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 76.782811] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 76.786404] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 76.790779] systemd[1]: Stopping Reactivate sysinit units... machine # [ 76.791635] systemd[1]: Reached target Reactivate sysinit units. machine # [ 76.792757] systemd[1]: test.service: Deactivated successfully. machine # [ 76.793571] systemd[1]: Stopped test.service. machine # [ 76.794184] systemd[1]: Stopping test.service... machine # [ 76.795758] systemd[1]: Starting test.service... machine # [ 76.819936] systemd[1]: Finished test.service. machine # [ 76.823536] systemd[1]: Reached target Local File Systems. machine # [ 76.825817] systemd[1]: Reached target Remote File Systems. machine # [ 76.831141] systemd[1]: Starting linger-users.service... machine # [ 76.838491] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 76.849087] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 76.850011] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 76.851222] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 76.857778] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 76.859398] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 76.866502] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 76.867819] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 76.876587] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 76.909125] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 76.912270] systemd[1]: Finished linger-users.service. machine # [ 77.042440] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 77.045727] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 77.302341] nixos[5001]: finished switching to system configuration /nix/store/k1m9sj9ashaxhi95zbk8y1q4wrv5dbyj-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&1, in 1.85 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&1 machine # [ 77.587492] nixos[5143]: switching to system configuration /nix/store/6idgs8a887n19n7nah2ymsf9dqj0swb8-nixos-system-machine-test machine # [ 77.589909] systemd[1]: Stopped target Local File Systems. machine # [ 77.592057] systemd[1]: Stopped target Remote File Systems. machine # [ 77.862483] systemd[1]: Reload requested from client PID 5143 ('.switch-to-conf') (unit backdoor.service)... machine # [ 77.864467] systemd[1]: Reloading... machine # [ 78.184994] systemd[1]: Reloading finished in 319 ms. machine # [ 78.273173] systemd[527]: Reexecution requested from client PID 5212 ('switch-to-confi')... machine # [ 78.277090] systemd[527]: Reexecuting. machine # [ 78.322867] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.438861] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.445328] systemd[527]: Reload requested from client PID 5212 ('switch-to-confi')... machine # [ 78.447615] systemd[527]: Reloading... machine # [ 78.555758] systemd[527]: Reloading finished in 107 ms. machine # [ 78.572174] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.581928] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 78.584394] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 78.586640] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 78.619405] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 78.622769] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 78.624651] systemd[1]: Stopping Reactivate sysinit units... machine # [ 78.625503] systemd[1]: Reached target Reactivate sysinit units. machine # [ 78.628518] systemd[1]: test.service: Deactivated successfully. machine # [ 78.629383] systemd[1]: Stopped test.service. machine # [ 78.630031] systemd[1]: Stopping test.service... machine # [ 78.632954] systemd[1]: Starting test.service... machine # [ 78.656449] systemd[1]: Finished test.service. machine # [ 78.659412] systemd[1]: Reached target Local File Systems. machine # [ 78.664540] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 78.668691] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 78.670018] systemd[1]: Reached target Remote File Systems. machine # [ 78.674359] systemd[1]: Starting linger-users.service... machine # [ 78.680122] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 78.688314] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 78.689216] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 78.690424] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 78.697666] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 78.699298] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 78.700597] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 78.753214] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 78.756682] systemd[1]: Finished linger-users.service. machine # [ 78.885210] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 78.888634] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 79.146388] nixos[5143]: finished switching to system configuration /nix/store/6idgs8a887n19n7nah2ymsf9dqj0swb8-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&1, in 1.84 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 79.426659] nixos[5285]: switching to system configuration /nix/store/haj41h0p4x0qrkkaycgivzipfxs0a5m0-nixos-system-machine-test machine # [ 79.429084] systemd[1]: Stopped target Local File Systems. machine # [ 79.431608] systemd[1]: Stopped target Remote File Systems. machine # [ 79.679794] systemd[1]: Reload requested from client PID 5285 ('.switch-to-conf') (unit backdoor.service)... machine # [ 79.681838] systemd[1]: Reloading... machine # [ 79.990480] systemd[1]: Reloading finished in 306 ms. machine # [ 80.073515] systemd[527]: Reexecution requested from client PID 5353 ('switch-to-confi')... machine # [ 80.077558] systemd[527]: Reexecuting. machine # [ 80.135220] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.249214] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.255370] systemd[527]: Reload requested from client PID 5353 ('switch-to-confi')... machine # [ 80.258733] systemd[527]: Reloading... machine # [ 80.364880] systemd[527]: Reloading finished in 107 ms. machine # [ 80.380157] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.390330] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 80.391395] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 80.393738] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 80.423156] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 80.426814] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 80.431168] systemd[1]: Stopping Reactivate sysinit units... machine # [ 80.432331] systemd[1]: Reached target Reactivate sysinit units. machine # [ 80.433194] systemd[1]: test.service: Deactivated successfully. machine # [ 80.434008] systemd[1]: Stopped test.service. machine # [ 80.434644] systemd[1]: Stopping test.service... machine # [ 80.436365] systemd[1]: Starting test.service... machine # [ 80.460505] systemd[1]: Finished test.service. machine # [ 80.466221] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 80.470179] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 80.471493] systemd[1]: Reached target Local File Systems. machine # [ 80.477210] systemd[1]: Reached target Remote File Systems. machine # [ 80.478062] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 80.479524] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 80.488637] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 80.489921] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 80.493686] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 80.495258] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 80.496599] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 80.652850] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 80.656863] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 80.658997] systemd[1]: Starting linger-users.service... machine # [ 80.699685] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 80.702681] systemd[1]: Finished linger-users.service. machine # [ 80.959372] nixos[5285]: finished switching to system configuration /nix/store/haj41h0p4x0qrkkaycgivzipfxs0a5m0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 1.81 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&1 machine # [ 81.253811] nixos[5427]: switching to system configuration /nix/store/9j2mzl2dkjplk4qygr1xwv0adg3dwira-nixos-system-machine-test machine # [ 81.257120] systemd[1]: Stopped target Local File Systems. machine # [ 81.258958] systemd[1]: Stopped target Remote File Systems. machine # [ 81.602698] systemd[1]: Reload requested from client PID 5427 ('.switch-to-conf') (unit backdoor.service)... machine # [ 81.604811] systemd[1]: Reloading... machine # [ 81.969118] systemd[1]: Reloading finished in 362 ms. machine # [ 82.072483] systemd[527]: Reexecution requested from client PID 5496 ('switch-to-confi')... machine # [ 82.076361] systemd[527]: Reexecuting. machine # [ 82.136028] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 82.270049] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 82.277024] systemd[527]: Reload requested from client PID 5496 ('switch-to-confi')... machine # [ 82.280276] systemd[527]: Reloading... machine # [ 82.399073] systemd[527]: Reloading finished in 119 ms. machine # [ 82.416168] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 82.426036] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 82.428495] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 82.430655] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 82.465648] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 82.470877] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 82.474649] systemd[1]: Stopping Reactivate sysinit units... machine # [ 82.475509] systemd[1]: Reached target Reactivate sysinit units. machine # [ 82.478080] systemd[1]: Reached target Remote File Systems. machine # [ 82.480967] systemd[1]: Reached target Local File Systems. machine # [ 82.484525] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 82.487308] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 82.490863] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 82.496987] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 82.499191] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 82.506654] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 82.507969] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 82.513141] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 82.514485] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 82.717531] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 82.722530] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 82.724699] systemd[1]: Starting linger-users.service... machine # [ 82.767553] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 82.769589] systemd[1]: Finished linger-users.service. machine # [ 83.032737] nixos[5427]: finished switching to system configuration /nix/store/9j2mzl2dkjplk4qygr1xwv0adg3dwira-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&1, in 2.07 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&1 machine # [ 83.403280] nixos[5567]: switching to system configuration /nix/store/y5ncdi7qndi3rx6bimmlnwv0czlmj3ms-nixos-system-machine-test machine # [ 83.406241] systemd[1]: Stopped target Local File Systems. machine # [ 83.409181] systemd[1]: Stopped target Remote File Systems. machine # [ 83.733112] systemd[1]: Reload requested from client PID 5567 ('.switch-to-conf') (unit backdoor.service)... machine # [ 83.734671] systemd[1]: Reloading... machine # [ 84.125822] systemd[1]: Reloading finished in 389 ms. machine # [ 84.223744] systemd[527]: Reexecution requested from client PID 5636 ('switch-to-confi')... machine # [ 84.225673] systemd[527]: Reexecuting. machine # [ 84.279434] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 84.406380] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 84.413124] systemd[527]: Reload requested from client PID 5636 ('switch-to-confi')... machine # [ 84.416098] systemd[527]: Reloading... machine # [ 84.540184] systemd[527]: Reloading finished in 124 ms. machine # [ 84.556171] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 84.567001] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 84.569463] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 84.571593] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 84.605140] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 84.608748] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 84.609683] systemd[1]: Stopping Reactivate sysinit units... machine # [ 84.611374] systemd[1]: Reached target Reactivate sysinit units. machine # [ 84.615034] systemd[1]: test.service: Deactivated successfully. machine # [ 84.615938] systemd[1]: Stopped test.service. machine # [ 84.618142] systemd[1]: Stopping test.service... machine # [ 84.618891] systemd[1]: Starting test.service... machine # [ 84.645671] systemd[1]: Finished test.service. machine # [ 84.651180] systemd[1]: Reached target Local File Systems. machine # [ 84.654547] systemd[1]: Reached target Remote File Systems. machine # [ 84.656769] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 84.662434] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 84.663738] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 84.667845] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 84.671908] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 84.678217] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 84.682564] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 84.684357] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 84.685618] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 84.864974] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 84.868849] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 84.871530] systemd[1]: Starting linger-users.service... machine # [ 84.914890] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 84.917574] systemd[1]: Finished linger-users.service. machine # [ 85.178762] nixos[5567]: finished switching to system configuration /nix/store/y5ncdi7qndi3rx6bimmlnwv0czlmj3ms-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&1, in 2.15 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&1 machine # [ 85.540504] nixos[5709]: switching to system configuration /nix/store/xb5h34hxd5ai0v558s9bxl114qk64ac0-nixos-system-machine-test machine # [ 85.543310] systemd[1]: Stopped target Remote File Systems. machine # [ 85.545781] systemd[1]: Stopped target Local File Systems. machine # [ 85.891836] systemd[1]: Reload requested from client PID 5709 ('.switch-to-conf') (unit backdoor.service)... machine # [ 85.893895] systemd[1]: Reloading... machine # [ 86.292081] systemd[1]: Reloading finished in 396 ms. machine # [ 86.400618] systemd[527]: Reexecution requested from client PID 5777 ('switch-to-confi')... machine # [ 86.402061] systemd[527]: Reexecuting. machine # [ 86.464484] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 86.611585] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 86.619643] systemd[527]: Reload requested from client PID 5777 ('switch-to-confi')... machine # [ 86.622553] systemd[527]: Reloading... machine # [ 86.756832] systemd[527]: Reloading finished in 133 ms. machine # [ 86.772161] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 86.784343] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 86.787057] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 86.789404] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 86.827800] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 86.832104] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 86.836673] systemd[1]: Stopping Reactivate sysinit units... machine # [ 86.837521] systemd[1]: Reached target Reactivate sysinit units. machine # [ 86.839070] systemd[1]: test.service: Deactivated successfully. machine # [ 86.839885] systemd[1]: Stopped test.service. machine # [ 86.842398] systemd[1]: Stopping test.service... machine # [ 86.844264] systemd[1]: Starting test.service... machine # [ 86.870773] systemd[1]: Finished test.service. machine # [ 86.875445] systemd[1]: Reached target Local File Systems. machine # [ 86.879832] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 86.888881] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 86.889780] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 86.890997] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 86.895996] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 86.898120] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 86.899493] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 86.915516] systemd[1]: Reached target Remote File Systems. machine # [ 86.920304] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 86.921772] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 87.092358] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 87.096643] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 87.100571] systemd[1]: Starting linger-users.service... machine # [ 87.146196] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 87.148874] systemd[1]: Finished linger-users.service. machine # [ 87.408910] nixos[5709]: finished switching to system configuration /nix/store/xb5h34hxd5ai0v558s9bxl114qk64ac0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&1, in 2.23 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&1 machine # [ 87.858221] nixos[5851]: switching to system configuration /nix/store/fviw3lsfygisc9x07fakhmnwanxywids-nixos-system-machine-test machine # [ 87.863732] systemd[1]: Stopped target Remote File Systems. machine # [ 87.869346] systemd[1]: Stopped target Local File Systems. machine # [ 88.227839] systemd[1]: Reload requested from client PID 5851 ('.switch-to-conf') (unit backdoor.service)... machine # [ 88.230044] systemd[1]: Reloading... machine # [ 88.567232] systemd[1]: Reloading finished in 335 ms. machine # [ 88.654135] systemd[527]: Reexecution requested from client PID 5920 ('switch-to-confi')... machine # [ 88.655512] systemd[527]: Reexecuting. machine # [ 88.715331] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 88.831560] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 88.837989] systemd[527]: Reload requested from client PID 5920 ('switch-to-confi')... machine # [ 88.841603] systemd[527]: Reloading... machine # [ 88.948234] systemd[527]: Reloading finished in 107 ms. machine # [ 88.964147] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 88.975180] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 88.977461] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 88.979523] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 89.011765] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 89.015597] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 89.017683] systemd[1]: Stopping Reactivate sysinit units... machine # [ 89.018538] systemd[1]: Reached target Reactivate sysinit units. machine # [ 89.022044] systemd[1]: test.service: Deactivated successfully. machine # [ 89.022964] systemd[1]: Stopped test.service. machine # [ 89.023614] systemd[1]: Stopping test.service... machine # [ 89.028169] systemd[1]: Starting test.service... machine # [ 89.050576] systemd[1]: Finished test.service. machine # [ 89.054744] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 89.058367] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 89.062207] systemd[1]: Reached target Remote File Systems. machine # [ 89.066349] systemd[1]: Starting linger-users.service... machine # [ 89.074983] systemd[1]: Reached target Local File Systems. machine # [ 89.077845] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 89.088167] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 89.089102] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 89.090295] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 89.096178] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 89.097793] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 89.106787] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 89.149446] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 89.152302] systemd[1]: Finished linger-users.service. machine # [ 89.284656] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 89.287728] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 89.547225] nixos[5851]: finished switching to system configuration /nix/store/fviw3lsfygisc9x07fakhmnwanxywids-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&1, in 2.13 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 89.809724] nixos[5993]: switching to system configuration /nix/store/haj41h0p4x0qrkkaycgivzipfxs0a5m0-nixos-system-machine-test machine # [ 89.812512] systemd[1]: Stopped target Remote File Systems. machine # [ 89.814121] systemd[1]: Stopped target Local File Systems. machine # [ 90.063828] systemd[1]: Reload requested from client PID 5993 ('.switch-to-conf') (unit backdoor.service)... machine # [ 90.066075] systemd[1]: Reloading... machine # [ 90.372906] systemd[1]: Reloading finished in 305 ms. machine # [ 90.456136] systemd[527]: Reexecution requested from client PID 6062 ('switch-to-confi')... machine # [ 90.460112] systemd[527]: Reexecuting. machine # [ 90.506933] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 90.618462] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 90.624681] systemd[527]: Reload requested from client PID 6062 ('switch-to-confi')... machine # [ 90.626846] systemd[527]: Reloading... machine # [ 90.734623] systemd[527]: Reloading finished in 106 ms. machine # [ 90.752153] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 90.761196] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 90.763423] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 90.765428] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 90.794673] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 90.798190] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 90.802650] systemd[1]: Stopping Reactivate sysinit units... machine # [ 90.803526] systemd[1]: Reached target Reactivate sysinit units. machine # [ 90.804632] systemd[1]: test.service: Deactivated successfully. machine # [ 90.805438] systemd[1]: Stopped test.service. machine # [ 90.806042] systemd[1]: Stopping test.service... machine # [ 90.807648] systemd[1]: Starting test.service... machine # [ 90.832087] systemd[1]: Finished test.service. machine # [ 90.835152] systemd[1]: Reached target Local File Systems. machine # [ 90.839359] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 90.848290] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 90.849131] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 90.850316] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 90.860586] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 90.862141] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 90.863366] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 90.864646] systemd[1]: Reached target Remote File Systems. machine # [ 90.865401] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 90.866648] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 91.019579] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 91.024446] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 91.026621] systemd[1]: Starting linger-users.service... machine # [ 91.063406] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 91.066019] systemd[1]: Finished linger-users.service. machine # [ 91.323951] nixos[5993]: finished switching to system configuration /nix/store/haj41h0p4x0qrkkaycgivzipfxs0a5m0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 1.78 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&1 machine # [ 91.614775] nixos[6135]: switching to system configuration /nix/store/l0z785l7qc4f004h6c0y2i0s1m0ppsng-nixos-system-machine-test machine # [ 91.617406] systemd[1]: Stopped target Remote File Systems. machine # [ 91.619668] systemd[1]: Stopped target Local File Systems. machine # [ 91.888441] systemd[1]: Reload requested from client PID 6135 ('.switch-to-conf') (unit backdoor.service)... machine # [ 91.890291] systemd[1]: Reloading... machine # [ 92.207110] systemd[1]: Reloading finished in 315 ms. machine # [ 92.296382] systemd[527]: Reexecution requested from client PID 6204 ('switch-to-confi')... machine # [ 92.299872] systemd[527]: Reexecuting. machine # [ 92.355189] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 92.468734] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 92.475055] systemd[527]: Reload requested from client PID 6204 ('switch-to-confi')... machine # [ 92.477137] systemd[527]: Reloading... machine # [ 92.584931] systemd[527]: Reloading finished in 106 ms. machine # [ 92.601527] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 92.608756] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 92.610987] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 92.612991] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 92.641869] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 92.645727] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 92.648283] systemd[1]: Stopping Reactivate sysinit units... machine # [ 92.649100] systemd[1]: Reached target Reactivate sysinit units. machine # [ 92.652413] systemd[1]: Reloading test.service... machine # [ 92.675528] systemd[1]: Reloaded test.service. machine # [ 92.680847] systemd[1]: Reached target Remote File Systems. machine # [ 92.683764] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 92.685740] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 92.691023] systemd[1]: Starting linger-users.service... machine # [ 92.696095] systemd[1]: Reached target Local File Systems. machine # [ 92.702539] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 92.707147] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 92.711393] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 92.712785] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 92.714931] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 92.721650] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 92.722943] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 92.765321] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 92.768793] systemd[1]: Finished linger-users.service. machine # [ 92.897629] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 92.900839] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 93.158746] nixos[6135]: finished switching to system configuration /nix/store/l0z785l7qc4f004h6c0y2i0s1m0ppsng-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 1.83 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&1 machine # [ 93.442754] nixos[6277]: switching to system configuration /nix/store/q34zv04917y9f9f5q3761yd2pbm92wpg-nixos-system-machine-test machine # [ 93.445185] systemd[1]: Stopped target Local File Systems. machine # [ 93.447470] systemd[1]: Stopped target Remote File Systems. machine # [ 93.713685] systemd[1]: Reload requested from client PID 6277 ('.switch-to-conf') (unit backdoor.service)... machine # [ 93.715523] systemd[1]: Reloading... machine # [ 94.031546] systemd[1]: Reloading finished in 314 ms. machine # [ 94.123206] systemd[527]: Reexecution requested from client PID 6346 ('switch-to-confi')... machine # [ 94.125118] systemd[527]: Reexecuting. machine # [ 94.170859] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 94.281282] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 94.287940] systemd[527]: Reload requested from client PID 6346 ('switch-to-confi')... machine # [ 94.291454] systemd[527]: Reloading... machine # [ 94.397523] systemd[527]: Reloading finished in 107 ms. machine # [ 94.412132] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 94.420673] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 94.422859] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 94.424828] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 94.454235] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 94.457643] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 94.461402] systemd[1]: Stopping Reactivate sysinit units... machine # [ 94.462232] systemd[1]: Reached target Reactivate sysinit units. machine # [ 94.464264] systemd[1]: Reloading test.service... machine # [ 94.488240] systemd[1]: Reloaded test.service. machine # [ 94.494357] systemd[1]: Reached target Remote File Systems. machine # [ 94.498233] systemd[1]: Starting linger-users.service... machine # [ 94.506419] systemd[1]: Reached target Local File Systems. machine # [ 94.507228] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 94.516481] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 94.517299] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 94.518521] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 94.523989] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 94.525582] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 94.526801] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 94.527979] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 94.533633] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 94.576784] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 94.579364] systemd[1]: Finished linger-users.service. machine # [ 94.710209] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 94.713937] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 94.970437] nixos[6277]: finished switching to system configuration /nix/store/q34zv04917y9f9f5q3761yd2pbm92wpg-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&1, in 1.81 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&1 machine # [ 95.259943] nixos[6419]: switching to system configuration /nix/store/5v2ak9rwlf69iv36px97ccqrmai8h376-nixos-system-machine-test machine # [ 95.262486] systemd[1]: Stopped target Local File Systems. machine # [ 95.265270] systemd[1]: Stopped target Remote File Systems. machine # [ 95.531194] systemd[1]: Reload requested from client PID 6419 ('.switch-to-conf') (unit backdoor.service)... machine # [ 95.533173] systemd[1]: Reloading... machine # [ 95.847356] systemd[1]: Reloading finished in 312 ms. machine # [ 95.938467] systemd[527]: Reexecution requested from client PID 6488 ('switch-to-confi')... machine # [ 95.939791] systemd[527]: Reexecuting. machine # [ 95.999661] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 96.115396] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 96.121432] systemd[527]: Reload requested from client PID 6488 ('switch-to-confi')... machine # [ 96.124770] systemd[527]: Reloading... machine # [ 96.228454] systemd[527]: Reloading finished in 104 ms. machine # [ 96.244136] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 96.254461] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 96.256628] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 96.258541] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 96.292519] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 96.295730] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 96.297600] systemd[1]: Stopping Reactivate sysinit units... machine # [ 96.298409] systemd[1]: Reached target Reactivate sysinit units. machine # [ 96.301762] systemd[1]: test.service: Deactivated successfully. machine # [ 96.302681] systemd[1]: Stopped test.service. machine # [ 96.303282] systemd[1]: Stopping test.service... machine # [ 96.305868] systemd[1]: Starting test.service... machine # [ 96.327881] systemd[1]: Finished test.service. machine # [ 96.330976] systemd[1]: Reached target Remote File Systems. machine # [ 96.333442] systemd[1]: Reached target Local File Systems. machine # [ 96.339372] systemd[1]: Starting linger-users.service... machine # [ 96.348976] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 96.351173] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 96.354086] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 96.361892] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 96.368200] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 96.369460] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 96.371541] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 96.374547] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 96.375958] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 96.418135] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 96.420851] systemd[1]: Finished linger-users.service. machine # [ 96.551912] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 96.555130] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 96.813453] nixos[6419]: finished switching to system configuration /nix/store/5v2ak9rwlf69iv36px97ccqrmai8h376-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&1, in 1.84 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&1 machine # [ 97.074645] nixos[6561]: switching to system configuration /nix/store/y5ncdi7qndi3rx6bimmlnwv0czlmj3ms-nixos-system-machine-test machine # [ 97.077208] systemd[1]: Stopped target Remote File Systems. machine # [ 97.079344] systemd[1]: Stopped target Local File Systems. machine # [ 97.325355] systemd[1]: Reload requested from client PID 6561 ('.switch-to-conf') (unit backdoor.service)... machine # [ 97.327197] systemd[1]: Reloading... machine # [ 97.629292] systemd[1]: Reloading finished in 300 ms. machine # [ 97.715791] systemd[527]: Reexecution requested from client PID 6630 ('switch-to-confi')... machine # [ 97.719460] systemd[527]: Reexecuting. machine # [ 97.771062] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 97.883114] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 97.889330] systemd[527]: Reload requested from client PID 6630 ('switch-to-confi')... machine # [ 97.892081] systemd[527]: Reloading... machine # [ 97.996935] systemd[527]: Reloading finished in 105 ms. machine # [ 98.012159] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 98.022673] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 98.024904] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 98.026869] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 98.058096] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 98.061138] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 98.063210] systemd[1]: Stopping Reactivate sysinit units... machine # [ 98.065179] systemd[1]: Reached target Reactivate sysinit units. machine # [ 98.066965] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 98.069474] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 98.071325] systemd[1]: Reached target Remote File Systems. machine # [ 98.077538] systemd[1]: Starting linger-users.service... machine # [ 98.084387] systemd[1]: Reached target Local File Systems. machine # [ 98.087966] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 98.093647] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 98.094452] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 98.095617] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 98.101954] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 98.103542] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 98.108811] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 98.152359] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 98.155960] systemd[1]: Finished linger-users.service. machine # [ 98.280863] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 98.283519] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 98.542864] nixos[6561]: finished switching to system configuration /nix/store/y5ncdi7qndi3rx6bimmlnwv0czlmj3ms-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&1, in 1.73 seconds) (finished: subtest: unit file parser, in 24.86 seconds) subtest: restart and reload by activation script machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1 machine # [ 98.801123] nixos[6701]: switching to system configuration /nix/store/5v7sf5lcjls65m8azcd473j1vn2igdnx-nixos-system-machine-test machine # [ 98.803643] systemd[1]: Stopped target Remote File Systems. machine # [ 98.805885] systemd[1]: Stopped target Local File Systems. machine # [ 99.049615] systemd[1]: Reload requested from client PID 6701 ('.switch-to-conf') (unit backdoor.service)... machine # [ 99.051531] systemd[1]: Reloading... machine # [ 99.354868] systemd[1]: Reloading finished in 301 ms. machine # [ 99.445086] systemd[527]: Reexecution requested from client PID 6770 ('switch-to-confi')... machine # [ 99.449343] systemd[527]: Reexecuting. machine # [ 99.507279] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 99.624091] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 99.630369] systemd[527]: Reload requested from client PID 6770 ('switch-to-confi')... machine # [ 99.632599] systemd[527]: Reloading... machine # [ 99.739217] systemd[527]: Reloading finished in 105 ms. machine # [ 99.756141] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 99.766089] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 99.768396] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 99.770392] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 99.802596] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 99.806161] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 99.807960] systemd[1]: Stopping Reactivate sysinit units... machine # [ 99.810418] systemd[1]: Reached target Reactivate sysinit units. machine # [ 99.811256] systemd[1]: Reached target Remote File Systems. machine # [ 99.815961] systemd[1]: Reached target Local File Systems. machine # [ 99.819887] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 99.826916] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 99.827734] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 99.835016] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 99.837444] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 99.839007] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 99.840259] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 99.841380] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 99.842597] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 99.995075] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 99.996900] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 100.001603] systemd[1]: Starting linger-users.service... machine # [ 100.038757] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 100.041429] systemd[1]: Finished linger-users.service. machine # [ 100.298004] nixos[6701]: finished switching to system configuration /nix/store/5v7sf5lcjls65m8azcd473j1vn2igdnx-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 1.76 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 100.587001] nixos[6841]: switching to system configuration /nix/store/4gb96awhq649id9pjviarlwd3rbyiaaz-nixos-system-machine-test machine # [ 100.589693] systemd[1]: Stopped target Remote File Systems. machine # [ 100.592969] systemd[1]: test.service: Deactivated successfully. machine # [ 100.593934] systemd[1]: Stopped test.service. machine # [ 100.594590] systemd[1]: Stopped target Local File Systems. machine # [ 100.868329] systemd[1]: Reload requested from client PID 6841 ('.switch-to-conf') (unit backdoor.service)... machine # [ 100.870175] systemd[1]: Reloading... machine # [ 101.195227] systemd[1]: Reloading finished in 323 ms. machine # [ 101.282669] systemd[527]: Reexecution requested from client PID 6912 ('switch-to-confi')... machine # [ 101.286139] systemd[527]: Reexecuting. machine # [ 101.339044] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 101.451485] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 101.457805] systemd[527]: Reload requested from client PID 6912 ('switch-to-confi')... machine # [ 101.459968] systemd[527]: Reloading... machine # [ 101.566817] systemd[527]: Reloading finished in 105 ms. machine # [ 101.584149] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 101.593857] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 101.594848] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 101.597346] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 101.626983] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 101.630932] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 101.633943] systemd[1]: Stopping Reactivate sysinit units... machine # [ 101.634837] systemd[1]: Reached target Reactivate sysinit units. machine # [ 101.639652] systemd[1]: Starting no-restart-service.service... machine # [ 101.644872] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 101.646241] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 101.662262] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart-by-as. machine # [ 101.667129] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 101.676289] systemd[1]: Reached target Remote File Systems. machine # [ 101.677121] systemd[1]: Reached target Local File Systems. machine # [ 101.688447] systemd[1]: Starting simple-reload-service.service... machine # [ 101.689380] systemd[1]: Created slice Slice /system/templated-reload-triggers. machine # [ 101.690359] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart. machine # [ 101.706839] systemd[1]: Starting linger-users.service... machine # [ 101.707702] systemd[1]: Starting reload-triggers-and-restart.service... machine # [ 101.730116] systemd[1]: Starting reload-triggers.service... machine # [ 101.730974] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service... machine # [ 101.756681] systemd[1]: Starting templated-reload-triggers@instance.service... machine # [ 101.757727] systemd[1]: Finished no-restart-service.service. machine # [ 101.758664] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 101.759711] systemd[1]: Created slice Slice /system/templated-simple-reload-service. machine # [ 101.817467] systemd[1]: Starting templated-simple-reload-service@instance.service... machine # [ 101.818729] systemd[1]: Created slice Slice /system/templated-no-restart-service. machine # [ 101.819875] systemd[1]: Starting templated-no-restart-service@instance.service... machine # [ 101.879406] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 101.907516] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 101.918203] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 101.929545] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 101.945573] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 101.947423] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 101.962450] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 101.963683] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 101.967817] systemd[1]: Created slice Slice /system/templated-simple-service. machine # [ 101.971589] systemd[1]: Created slice Slice /system/templated-simple-restart-service. machine # [ 101.974464] systemd[1]: Finished simple-reload-service.service. machine # [ 101.975322] systemd[1]: Finished reload-triggers.service. machine # [ 101.979677] systemd[1]: Finished reload-triggers-and-restart.service. machine # [ 101.983226] systemd[1]: Finished templated-reload-triggers@instance.service. machine # [ 101.987666] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service. machine # [ 101.989085] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 101.990803] systemd[1]: Finished templated-simple-reload-service@instance.service. machine # [ 101.992475] systemd[1]: Finished templated-no-restart-service@instance.service. machine # [ 101.993481] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 101.994379] systemd[1]: Finished linger-users.service. machine # [ 102.121888] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 102.124152] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 102.128215] systemd[1]: Starting simple-restart-service.service... machine # [ 102.132557] systemd[1]: Starting simple-service.service... machine # [ 102.141821] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 102.150386] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 102.195650] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 102.199204] systemd[1]: Finished simple-restart-service.service. machine # [ 102.204828] systemd[1]: Finished simple-service.service. machine # [ 102.206935] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 102.467483] nixos[6841]: finished switching to system configuration /nix/store/4gb96awhq649id9pjviarlwd3rbyiaaz-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 2.17 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 102.767522] nixos[7012]: switching to system configuration /nix/store/4gb96awhq649id9pjviarlwd3rbyiaaz-nixos-system-machine-test machine # [ 102.770177] systemd[1]: Stopped target Remote File Systems. machine # [ 102.772315] systemd[1]: Stopped target Local File Systems. machine # [ 103.034091] systemd[1]: Reload requested from client PID 7012 ('.switch-to-conf') (unit backdoor.service)... machine # [ 103.035618] systemd[1]: Reloading... machine # [ 103.360987] systemd[1]: Reloading finished in 324 ms. machine # [ 103.453497] systemd[527]: Reexecution requested from client PID 7082 ('switch-to-confi')... machine # [ 103.457536] systemd[527]: Reexecuting. machine # [ 103.515393] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 103.631973] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 103.638075] systemd[527]: Reload requested from client PID 7082 ('switch-to-confi')... machine # [ 103.640460] systemd[527]: Reloading... machine # [ 103.748687] systemd[527]: Reloading finished in 107 ms. machine # [ 103.764150] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 103.772541] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 103.774771] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 103.776844] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 103.806238] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 103.810040] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 103.813326] systemd[1]: Stopping Reactivate sysinit units... machine # [ 103.816526] systemd[1]: Reached target Reactivate sysinit units. machine # [ 103.819066] systemd[1]: Reloading reload-triggers-and-restart.service... machine # [ 103.826780] systemd[1]: Reloading reload-triggers.service... machine # [ 103.832252] systemd[1]: Reloading simple-reload-service.service... machine # [ 103.847492] systemd[1]: Reloading templated-reload-triggers-and-restart@instance.service... machine # [ 103.850362] systemd[1]: Reloading templated-reload-triggers@instance.service... machine # [ 103.860419] systemd[1]: Reloading templated-simple-reload-service@instance.service... machine # [ 103.914685] systemd[1]: Reloaded templated-reload-triggers-and-restart@instance.service. machine # [ 103.919938] systemd[1]: Reloaded simple-reload-service.service. machine # [ 103.924991] systemd[1]: Reloaded reload-triggers-and-restart.service. machine # [ 103.925954] systemd[1]: Reloaded reload-triggers.service. machine # [ 103.928546] systemd[1]: Reloaded templated-reload-triggers@instance.service. machine # [ 103.935380] systemd[1]: Reloaded templated-simple-reload-service@instance.service. machine # [ 103.938686] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully. machine # [ 103.942181] systemd[1]: Stopped reload-triggers-and-restart-by-as.service. machine # [ 103.943206] systemd[1]: Stopping reload-triggers-and-restart-by-as.service... machine # [ 103.945693] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 103.957009] systemd[1]: simple-restart-service.service: Deactivated successfully. machine # [ 103.958056] systemd[1]: Stopped simple-restart-service.service. machine # [ 103.958869] systemd[1]: Stopping simple-restart-service.service... machine # [ 103.959714] systemd[1]: Starting simple-restart-service.service... machine # [ 103.980340] systemd[1]: simple-service.service: Deactivated successfully. machine # [ 103.981273] systemd[1]: Stopped simple-service.service. machine # [ 103.981999] systemd[1]: Stopping simple-service.service... machine # [ 103.982772] systemd[1]: Starting simple-service.service... machine # [ 103.983513] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully. machine # [ 104.006987] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 104.016357] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 104.026606] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 104.037709] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully. machine # [ 104.051828] systemd[1]: Stopped templated-simple-restart-service@instance.service. machine # [ 104.061360] systemd[1]: Stopping templated-simple-restart-service@instance.service... machine # [ 104.062578] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 104.063732] systemd[1]: templated-simple-service@instance.service: Deactivated successfully. machine # [ 104.072472] systemd[1]: Stopped templated-simple-service@instance.service. machine # [ 104.074339] systemd[1]: Stopping templated-simple-service@instance.service... machine # [ 104.075307] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 104.076672] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 104.077632] systemd[1]: Finished simple-restart-service.service. machine # [ 104.078565] systemd[1]: Finished simple-service.service. machine # [ 104.079286] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 104.081525] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 104.086422] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 104.090666] systemd[1]: Reached target Remote File Systems. machine # [ 104.091496] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 104.097064] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 104.098368] systemd[1]: Reached target Local File Systems. machine # [ 104.099130] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 104.103480] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 104.107154] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 104.109078] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 104.111206] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 104.121008] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 104.122260] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 104.275440] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 104.280154] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 104.281932] systemd[1]: Starting linger-users.service... machine # [ 104.318590] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 104.321110] systemd[1]: Finished linger-users.service. machine # [ 104.579508] nixos[7012]: finished switching to system configuration /nix/store/4gb96awhq649id9pjviarlwd3rbyiaaz-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 2.11 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&1 machine # [ 104.910448] nixos[7178]: switching to system configuration /nix/store/q084q2vgjp748ms3xgh2d52crgfhnni1-nixos-system-machine-test machine # [ 104.913067] systemd[1]: Stopped target Remote File Systems. machine # [ 104.915183] systemd[1]: Stopped target Local File Systems. machine # [ 105.202515] systemd[1]: Reload requested from client PID 7178 ('.switch-to-conf') (unit backdoor.service)... machine # [ 105.204389] systemd[1]: Reloading... machine # [ 105.547797] systemd[1]: Reloading finished in 341 ms. machine # [ 105.642685] systemd[527]: Reexecution requested from client PID 7249 ('switch-to-confi')... machine # [ 105.646739] systemd[527]: Reexecuting. machine # [ 105.703361] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 105.821059] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 105.827697] systemd[527]: Reload requested from client PID 7249 ('switch-to-confi')... machine # [ 105.831111] systemd[527]: Reloading... machine # [ 105.939238] systemd[527]: Reloading finished in 109 ms. machine # [ 105.956151] systemd[527]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 105.964720] systemd[527]: Stopped Run user-specific NixOS activation. machine # [ 105.967022] systemd[527]: Stopping Run user-specific NixOS activation... machine # [ 105.969007] systemd[527]: Starting Run user-specific NixOS activation... machine # [ 105.998548] systemd[527]: Finished Run user-specific NixOS activation. machine # [ 106.002534] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 106.004555] systemd[1]: Stopping Reactivate sysinit units... machine # [ 106.005403] systemd[1]: Reached target Reactivate sysinit units. machine # [ 106.010056] systemd[1]: Reloading reload-triggers.service... machine # [ 106.020360] systemd[1]: Reloading simple-reload-service.service... machine # [ 106.024239] systemd[1]: Reloading templated-reload-triggers@instance.service... machine # [ 106.035587] systemd[1]: Reloading templated-simple-reload-service@instance.service... machine # [ 106.087752] systemd[1]: Reloaded reload-triggers.service. machine # [ 106.091190] systemd[1]: Reloaded simple-reload-service.service. machine # [ 106.092555] systemd[1]: Reloaded templated-reload-triggers@instance.service. machine # [ 106.093505] systemd[1]: Reloaded templated-simple-reload-service@instance.service. machine # [ 106.097264] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully. machine # [ 106.098421] systemd[1]: Stopped reload-triggers-and-restart-by-as.service. machine # [ 106.099345] systemd[1]: Stopping reload-triggers-and-restart-by-as.service... machine # [ 106.108404] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 106.109371] systemd[1]: reload-triggers-and-restart.service: Deactivated successfully. machine # [ 106.110416] systemd[1]: Stopped reload-triggers-and-restart.service. machine # [ 106.111250] systemd[1]: Stopping reload-triggers-and-restart.service... machine # [ 106.126570] systemd[1]: Starting reload-triggers-and-restart.service... machine # [ 106.127503] systemd[1]: simple-restart-service.service: Deactivated successfully. machine # [ 106.146590] systemd[1]: Stopped simple-restart-service.service. machine # [ 106.147547] systemd[1]: Stopping simple-restart-service.service... machine # [ 106.174519] systemd[1]: Starting simple-restart-service.service... machine # [ 106.175385] systemd[1]: simple-service.service: Deactivated successfully. machine # [ 106.183185] systemd[1]: Stopped simple-service.service. machine # [ 106.183956] systemd[1]: Stopping simple-service.service... machine # [ 106.197869] systemd[1]: Starting simple-service.service... machine # [ 106.198711] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully. machine # [ 106.224554] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 106.234251] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 106.243048] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 106.251738] systemd[1]: templated-reload-triggers-and-restart@instance.service: Deactivated successfully. machine # [ 106.255081] systemd[1]: Stopped templated-reload-triggers-and-restart@instance.service. machine # [ 106.258164] systemd[1]: Stopping templated-reload-triggers-and-restart@instance.service... machine # [ 106.259275] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service... machine # [ 106.263778] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully. machine # [ 106.270016] systemd[1]: Stopped templated-simple-restart-service@instance.service. machine # [ 106.271039] systemd[1]: Stopping templated-simple-restart-service@instance.service... machine # [ 106.272285] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 106.273291] systemd[1]: templated-simple-service@instance.service: Deactivated successfully. machine # [ 106.274400] systemd[1]: Stopped templated-simple-service@instance.service. machine # [ 106.275300] systemd[1]: Stopping templated-simple-service@instance.service... machine # [ 106.276446] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 106.277393] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 106.278303] systemd[1]: Finished reload-triggers-and-restart.service. machine # [ 106.279142] systemd[1]: Finished simple-restart-service.service. machine # [ 106.279929] systemd[1]: Finished simple-service.service. machine # [ 106.289703] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 106.290865] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service. machine # [ 106.291895] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 106.297049] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 106.298024] systemd[1]: Reached target Local File Systems. machine # [ 106.298774] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 106.304348] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 106.305193] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 106.306674] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 106.309610] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 106.311604] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 106.314769] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 106.315951] systemd[1]: Reached target Remote File Systems. machine # [ 106.319232] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 106.320726] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 106.462800] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 106.466739] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 106.468869] systemd[1]: Starting linger-users.service... machine # [ 106.506695] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 106.509510] systemd[1]: Finished linger-users.service. machine # [ 106.766450] nixos[7178]: finished switching to system configuration /nix/store/q084q2vgjp748ms3xgh2d52crgfhnni1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&1, in 2.19 seconds) machine: must succeed: /nix/store/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-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/sj3vf02v32xsp79q4mdxnz8r8zaqmqw7-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration dry-activate 2>&1, in 0.39 seconds) (finished: subtest: restart and reload by activation script, in 8.62 seconds) (finished: run the VM test script, in 108.06 seconds) test script finished in 108.10s cleanup kill QemuMachine (pid 45) machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14) machine # [2026-09-22T03:00:31Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-22T03:00:31Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-22T03:00:31Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.19 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