tribuchet: building on eliza Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script machine: waiting for unit multi-user.target machine: waiting for the VM to finish booting machine: starting vm machine # Disk image does not exist, creating the virtualisation disk image... machine: QEMU running (pid 45) machine # Formatting '/build/vm-state-machine/tmp.6ZquObYRCR', 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: caa6b769-555d-4b32-ad4a-9d1c25c4b859 machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 machine # machine # Allocating group tables: 0/8 done machine # Writing inode tables: 0/8 done machine # Creating journal (8192 blocks): done machine # Writing superblocks and filesystem accounting information: 0/8 done machine # machine # Virtualisation disk image created. machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 0.000000] Linux version 6.18.49 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Wed Sep 2 12:31:51 UTC 2026 machine # [ 0.000000] KASLR enabled machine # [ 0.000000] random: crng init done machine # [ 0.000000] Machine model: linux,dummy-virt machine # [ 0.000000] efi: UEFI not found. machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f] machine # [ 0.000000] Zone ranges: machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] DMA32 empty machine # [ 0.000000] Normal empty machine # [ 0.000000] Device empty machine # [ 0.000000] Movable zone start for each node machine # [ 0.000000] Early memory node ranges machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 machine # [ 0.000000] psci: probing for conduit method from DT. machine # [ 0.000000] psci: PSCIv1.3 detected in firmware. machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs machine # [ 0.000000] psci: Trusted OS migration not required machine # [ 0.000000] psci: SMC Calling Convention v1.1 machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u311296 machine # [ 0.000000] Detected PIPT I-cache on CPU0 machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) machine # [ 0.000000] CPU features: detected: GICv3 CPU interface machine # [ 0.000000] CPU features: detected: Spectre-v4 machine # [ 0.000000] CPU features: detected: Spectre-BHB machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 machine # [ 0.000000] alternatives: applying boot alternatives machine # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/init regInfo=/nix/store/vqa32604sbyfh1sa5ngr6wy1nic7123y-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/vqa32604sbyfh1sa5ngr6wy1nic7123y-closure-info/registration", will be passed to user space. machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB machine # [ 0.000000] software IO TLB: area num 1. machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) machine # [ 0.000000] Fallback order for Node 0: 0 machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 machine # [ 0.000000] Policy zone: DMA machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.000000] allocated 2097152 bytes of page_ext machine # [ 0.000000] ftrace: allocating 74886 entries in 294 pages machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups machine # [ 0.000000] rcu: Hierarchical RCU implementation. machine # [ 0.000000] rcu: RCU event tracing is enabled. machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. machine # [ 0.000000] Trampoline variant of Tasks RCU enabled. machine # [ 0.000000] Rude variant of Tasks RCU enabled. machine # [ 0.000000] Tracing variant of Tasks RCU enabled. machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 machine # [ 0.000000] GICv3: 256 SPIs implemented machine # [ 0.000000] GICv3: 0 Extended SPIs implemented machine # [ 0.000000] Root IRQ handler: gic_handle_irq machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) machine # [ 0.000000] GICv3: using LPI property table @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.000030] arm-pv: using stolen time PV machine # [ 0.000390] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000562] Console: colour dummy device 80x25 machine # [ 0.000570] printk: legacy console [tty0] enabled machine # [ 0.000767] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.000775] pid_max: default: 32768 minimum: 301 machine # [ 0.000854] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.001220] landlock: Up and running. machine # [ 0.001223] Yama: becoming mindful. machine # [ 0.001700] LSM support for eBPF active machine # [ 0.001824] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.001843] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.003020] cacheinfo: Unable to detect cache hierarchy for CPU 0 machine # [ 0.003719] rcu: Hierarchical SRCU implementation. machine # [ 0.003723] rcu: Max phase no-delay instances is 1000. machine # [ 0.004953] fsl-mc MSI: its@8080000 domain created machine # [ 0.005047] EFI services will not be available. machine # [ 0.005122] smp: Bringing up secondary CPUs ... machine # [ 0.005130] smp: Brought up 1 node, 1 CPU machine # [ 0.005133] SMP: Total of 1 processors activated. machine # [ 0.005136] CPU: All CPU(s) started at EL1 machine # [ 0.005151] CPU features: detected: Branch Target Identification machine # [ 0.005156] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.005159] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.005162] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.005166] CPU features: detected: Common not Private translations machine # [ 0.005169] CPU features: detected: CRC32 instructions machine # [ 0.005172] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.005176] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.005179] CPU features: detected: Data independent timing control (DIT) machine # [ 0.005182] CPU features: detected: E0PD machine # [ 0.005185] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.005188] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.005191] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.005195] CPU features: detected: Fine Grained Traps machine # [ 0.005199] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.005204] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.005208] CPU features: detected: LSE atomic instructions machine # [ 0.005211] CPU features: detected: Privileged Access Never machine # [ 0.005213] CPU features: detected: PMUv3 machine # [ 0.005216] CPU features: detected: RAS Extension Support machine # [ 0.005219] CPU features: detected: RASv1p1 Extension Support machine # [ 0.005221] CPU features: detected: Random Number Generator machine # [ 0.005224] CPU features: detected: Speculation barrier (SB) machine # [ 0.005227] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.005230] CPU features: detected: TLB range maintenance instructions machine # [ 0.005235] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.005291] alternatives: applying system-wide alternatives machine # [ 0.008283] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.008443] Memory: 894300K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 112964K reserved, 32768K cma-reserved) machine # [ 0.008798] devtmpfs: initialized machine # [ 0.010547] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.010570] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.010757] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.010762] 0 pages in range for non-PLT usage machine # [ 0.010763] 508288 pages in range for PLT usage machine # [ 0.010879] pinctrl core: initialized pinctrl subsystem machine # [ 0.011622] DMI not present or invalid. machine # [ 0.014814] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.017109] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.017275] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.017446] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.017470] audit: initializing netlink subsys (disabled) machine # [ 0.018034] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.018036] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.018040] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.018043] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.018048] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.018082] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 machine # [ 0.018090] cpuidle: using governor ladder machine # [ 0.018096] cpuidle: using governor menu machine # [ 0.018302] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.018318] ASID allocator initialised with 65536 entries machine # [ 0.019512] Serial: AMBA PL011 UART driver machine # [ 0.024746] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 machine # [ 0.024869] printk: console [ttyAMA0] enabled machine # [ 0.148967] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.148988] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.148994] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.148998] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.149002] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.149007] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.149011] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.149015] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.156579] fbcon: Taking over console machine # [ 0.156595] ACPI: Interpreter disabled. machine # [ 0.158531] iommu: Default domain type: Translated machine # [ 0.158542] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.164734] SCSI subsystem initialized machine # [ 0.165472] usbcore: registered new interface driver usbfs machine # [ 0.165503] usbcore: registered new interface driver hub machine # [ 0.165520] usbcore: registered new device driver usb machine # [ 0.165814] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.165820] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.165830] PTP clock support registered machine # [ 0.165877] EDAC MC: Ver: 3.0.0 machine # [ 0.170631] scmi_core: SCMI protocol bus registered machine # [ 0.171591] FPGA manager framework machine # [ 0.172574] vgaarb: loaded machine # [ 0.173224] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.179678] VFS: Disk quotas dquot_6.6.0 machine # [ 0.179726] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.181431] netfs: FS-Cache loaded machine # [ 0.181559] pnp: PnP ACPI: disabled machine # [ 0.185515] NET: Registered PF_INET protocol family machine # [ 0.185681] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.215869] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.215928] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.215955] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.216002] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.216076] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.216167] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.216211] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.216237] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.216318] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.216340] NET: Registered PF_XDP protocol family machine # [ 0.216364] PCI: CLS 0 bytes, default 64 machine # [ 0.216625] Trying to unpack rootfs image as initramfs... machine # [ 0.235083] kvm [1]: HYP mode not available machine # [ 0.348970] Initialise system trusted keyrings machine # [ 0.349837] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.351097] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.351897] 9p: Installing v9fs 9p2000 file system support machine # [ 0.375951] Key type asymmetric registered machine # [ 0.375981] Asymmetric key parser 'x509' registered machine # [ 0.376064] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.381325] io scheduler mq-deadline registered machine # [ 0.381347] io scheduler kyber registered machine # [ 0.393444] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.394917] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.395352] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.395371] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.395388] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.395397] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.395427] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.395457] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.395539] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.395548] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.395555] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.395560] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.395565] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.395639] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.396080] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.396263] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.396280] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.396309] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.396325] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.396782] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.396963] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.396979] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.397011] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.397504] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.397686] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.397702] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.397733] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.398185] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.398375] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.398391] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.398422] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.398874] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.399053] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.399069] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.399098] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.399572] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.399764] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.399780] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.399815] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.400262] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.400443] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.400459] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.400489] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.400505] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.400952] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.401137] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.401167] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.445798] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.446010] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.446041] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.446448] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.446633] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.446886] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.447205] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.447223] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.447254] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.447713] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.447896] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.447912] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.447940] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.448534] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.448546] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.448552] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.448600] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.448651] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.448699] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.448745] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.448791] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.448839] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.448887] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.448937] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.448982] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.449054] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.449099] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.449120] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.449142] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.449163] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.449185] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.449210] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.485347] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.485397] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.485422] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.485446] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.485471] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.485495] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.485518] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.485540] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.485563] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned machine # [ 0.485587] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.485610] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.485633] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned machine # [ 0.485656] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned machine # [ 0.485685] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned machine # [ 0.485707] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned machine # [ 0.485744] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.485754] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.485759] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.486928] pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.522605] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.524778] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.535842] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.541965] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.544043] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) machine # [ 0.554816] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) machine # [ 0.556946] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) machine # [ 0.568644] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.571883] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.581902] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.584342] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.594657] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.602642] msm_serial: driver initialized machine # [ 0.602799] SuperH (H)SCI(F) driver initialized machine # [ 0.602853] STM32 USART driver initialized machine # [ 0.634167] loop: module loaded machine # [ 0.634373] virtio_blk virtio5: 1/0/0 default/read/poll queues machine # [ 0.635165] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.646094] megasas: 07.734.00.00-rc1 machine # [ 0.646868] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.648808] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.648837] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.653839] Using buffer write method machine # [ 0.653926] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.655757] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.655782] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.673898] Using buffer write method machine # [ 0.673946] Concatenating MTD devices: machine # [ 0.673951] (0): "0.flash" machine # [ 0.673956] (1): "0.flash" machine # [ 0.673959] into device "0.flash" machine # [ 0.946729] Freeing initrd memory: 26104K machine # [ 0.952815] tun: Universal TUN/TAP device driver, 1.6 machine # [ 0.956037] thunder_xcv, ver 1.0 machine # [ 0.956075] thunder_bgx, ver 1.0 machine # [ 0.956097] nicpf, ver 1.0 machine # [ 0.956648] e1000: Intel(R) PRO/1000 Network Driver machine # [ 0.956656] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 0.956684] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 0.956693] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 0.956739] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 0.956744] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 0.956767] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 0.956772] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 0.956906] sky2: driver version 1.30 machine # [ 0.966364] usbcore: registered new interface driver usb-storage machine # [ 0.966419] usbcore: registered new interface driver usbserial_generic machine # [ 0.966432] usbserial: USB Serial support registered for generic machine # [ 0.967029] hv_vmbus: registering driver hyperv_keyboard machine # [ 0.968730] ehci-pci 0000:00:0a.0: EHCI Host Controller machine # [ 0.968760] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 machine # [ 0.969054] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 machine # [ 0.974810] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 0.974845] rtc-pl031 9010000.pl031: setting system clock to 2026-09-06T02:30:19 UTC (1788661819) machine # [ 0.975240] i2c_dev: i2c /dev entries driver machine # [ 0.978350] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 machine # [ 0.978676] hub 1-0:1.0: USB hub found machine # [ 0.978696] hub 1-0:1.0: 6 ports detected machine # [ 0.982687] sdhci: Secure Digital Host Controller Interface driver machine # [ 0.982703] sdhci: Copyright(c) Pierre Ossman machine # [ 0.982991] Synopsys Designware Multimedia Card Interface Driver machine # [ 0.983360] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 0.985062] hid: raw HID events driver (C) Jiri Kosina machine # [ 0.988690] usbcore: registered new interface driver usbhid machine # [ 0.988699] usbhid: USB HID core driver machine # [ 0.990997] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 0.992522] drop_monitor: Initializing network drop monitor service machine # [ 0.992732] NET: Registered PF_INET6 protocol family machine # [ 0.995847] Segment Routing with IPv6 machine # [ 0.995867] In-situ OAM (IOAM) with IPv6 machine # [ 0.995895] NET: Registered PF_PACKET protocol family machine # [ 0.997624] 9pnet: Installing 9P2000 support machine # [ 0.999846] Key type dns_resolver registered machine # [ 1.006856] registered taskstats version 1 machine # [ 1.007003] Loading compiled-in X.509 certificates machine # [ 1.015582] Demotion targets for Node 0: null machine # [ 1.015701] Key type .fscrypt registered machine # [ 1.015709] Key type fscrypt-provisioning registered machine # [ 1.015810] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.015829] ima: Allocated hash algorithm: sha1 machine # [ 1.015850] ima: No architecture policies found machine # [ 1.020054] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 1.038439] clk: Disabling unused clocks machine # [ 1.038471] PM: genpd: Disabling unused power domains machine # [ 1.042814] Freeing unused kernel memory: 4736K machine # [ 1.043014] Run /init as init process machine # [ 1.059543] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.225344] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.377164] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 machine # [ 1.394246] systemd[1]: systemd 262-9457f81 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 1.398075] systemd[1]: Detected virtualization qemu. machine # [ 1.398737] systemd[1]: Detected architecture arm64. machine # [ 1.399367] systemd[1]: Running in initrd. machine # [ 1.400366] systemd[1]: Initializing machine ID from random generator. machine # [ 1.401466] systemd[1]: Hostname set to . machine # [ 1.461453] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 machine # [ 1.505589] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.560357] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.571214] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.572168] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.573157] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.574058] systemd[1]: Reached target Path Units. machine # [ 1.574672] systemd[1]: Reached target Slice Units. machine # [ 1.575295] systemd[1]: Reached target Swaps. machine # [ 1.575854] systemd[1]: Reached target Timer Units. machine # [ 1.576616] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.577634] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.578561] systemd[1]: Listening on Journal Sockets. machine # [ 1.579284] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.579968] systemd[1]: Reached target Socket Units. machine # [ 1.581297] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.583187] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.592765] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 1.593918] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.609656] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.625434] systemd[1]: Starting Journal Service... machine # [ 1.627797] systemd[1]: Starting Load Kernel Modules... machine # [ 1.628602] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.642740] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.661419] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.662707] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 1.663024] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 1.663283] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.673810] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.700099] systemd-journald[73]: Collecting audit messages is disabled. machine # [ 1.706880] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.729403] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.737830] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 machine # [ 1.738092] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.738102] [drm] features: -context_init machine # [ 1.738850] [drm] number of scanouts: 1 machine # [ 1.738869] [drm] number of cap sets: 0 machine # [ 1.743351] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.753554] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.757659] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 machine # [ 1.757819] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 machine # [ 1.765092] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic machine # [ 1.765119] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 machine # [ 1.817996] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.818214] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.818242] systemd[1]: Reached target Local File Systems. machine # [ 1.825621] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.833306] Console: switching to colour frame buffer device 160x50 machine # [ 1.834005] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.857568] systemd[1]: Finished Load Kernel Modules. machine # [ 1.865655] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.896410] systemd-modules-load[74]: Inserted module 'dm_mod' machine # [ 1.910904] systemd[1]: Started Journal Service. machine # [ 1.904338] systemd-modules-load[74]: Module 'virtio_balloon' is built in machine # [ 1.905512] systemd-modules-load[74]: Module 'virtio_console' is built in machine # [ 1.906609] systemd-modules-load[74]: Inserted module 'virtio_gpu' machine # [ 1.907669] systemd-modules-load[74]: Module 'virtio_rng' is built in machine # [ 1.922557] systemd[1]: Starting Create System Files and Directories... machine # [ 1.928502] systemd-udevd[80]: Using default interface naming scheme 'v261'. machine # [ 1.941771] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.968681] systemd[1]: Finished Create System Files and Directories. machine # [ 1.979048] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 2.080152] systemd[1]: Starting Virtual Console Setup... machine # [ 2.140621] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.148585] systemd[1]: Finished Virtual Console Setup. machine # [ 2.758014] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.761167] systemd[1]: Reached target System Initialization. machine # [ 2.764318] systemd[1]: Reached target Basic System. machine # [ 2.927770] (udev-worker)[94]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.932645] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.959172] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line. machine # [ 3.016641] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 3.020939] systemd[1]: Reached target Initrd Root Device. machine # [ 3.023656] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 3.081056] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 3.091577] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 3.096193] systemd[1]: Mounting /sysroot... machine # [ 3.150619] EXT4-fs (vda): mounted filesystem caa6b769-555d-4b32-ad4a-9d1c25c4b859 r/w with ordered data mode. Quota mode: none. machine # [ 3.143374] systemd[1]: Mounted /sysroot. machine # [ 3.145987] systemd[1]: Reached target Initrd Root File System. machine # [ 3.151823] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 3.187673] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 3.196794] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 3.200155] systemd[1]: Reloading... machine # [ 3.404243] systemd[1]: Reloading finished in 208 ms. machine # [ 3.427337] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace... machine # [ 3.432268] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace... machine # [ 3.441535] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 3.444212] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.447444] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.453661] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.482630] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 3.485256] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 3.642592] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.659356] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.667263] systemd[1]: Mounting /sysroot/run... machine # [ 3.698339] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.726899] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.771898] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.774722] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.782248] systemd[1]: Mounted /sysroot/run. machine # [ 3.802055] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.807990] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.826501] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.852191] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.853637] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.405478] (udev-worker)[100]: mtd0ro: Failed to find and pin callout binary "/nix/store/x4i8f5p0japrw9j9zrdx7z9lf5387qn3-systemd-262-9457f81/lib/udev/mtd_probe": No such file or directory machine # [ 4.411465] (udev-worker)[100]: mtd0ro: /nix/store/x4i8f5p0japrw9j9zrdx7z9lf5387qn3-systemd-262-9457f81/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory machine # [ 4.436692] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.441486] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.443339] systemd[1]: Stopping Virtual Console Setup... machine # [ 4.448296] systemd[1]: Starting Virtual Console Setup... machine # [ 4.456686] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.458123] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.461817] systemd[1]: Starting Virtual Console Setup... machine # [ 4.489406] systemd-vconsole-setup[155]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 4.491951] systemd[1]: Finished Virtual Console Setup. machine # [ 4.641155] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.676700] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.680307] systemd[1]: Reached target Initrd File Systems. machine # [ 4.684328] systemd[1]: Starting Find NixOS closure... machine # [ 4.689621] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.723941] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.728740] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 4.738116] systemd[1]: Finished Find NixOS closure. machine # [ 4.740190] systemd[1]: Reached target Initrd Default Target. machine # [ 4.742404] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.763757] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.765734] systemd[1]: Stopped target Basic System. machine # [ 4.766723] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.767783] systemd[1]: Stopped target Path Units. machine # [ 4.772140] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.773280] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.775011] systemd[1]: Stopped target Slice Units. machine # [ 4.777272] systemd[1]: Stopped target Socket Units. machine # [ 4.778323] systemd[1]: Stopped target System Initialization. machine # [ 4.780148] systemd[1]: Stopped target Swaps. machine # [ 4.784157] systemd[1]: Stopped target Timer Units. machine # [ 4.784970] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.785807] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.786936] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.792739] systemd[1]: Stopped Find NixOS closure. machine # [ 4.794323] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 4.796250] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.799674] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.804283] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.806227] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.810827] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.811738] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.824118] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.825344] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 4.826489] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.827438] systemd[1]: Stopped target Local File Systems. machine # [ 4.838233] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.840433] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 4.842570] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.844594] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 4.849835] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.851775] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.856348] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.857347] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.858272] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.859175] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 4.861004] systemd[1]: systemd-udevd.service: Consumed 1.535s CPU time over 3.020s wall clock time, 22.1M memory peak. machine # [ 4.862444] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.863451] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.864462] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 4.865458] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 4.866296] systemd[1]: Starting Cleanup udev Database... machine # [ 4.867061] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 4.872276] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 4.876297] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 4.877786] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 4.880241] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 4.881275] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 4.912533] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 4.915801] systemd[1]: Finished Cleanup udev Database. machine # [ 4.920313] systemd[1]: Reached target Switch Root. machine # [ 4.921124] systemd[1]: Starting NixOS Activation... machine # [ 5.112399] initrd-nixos-activation-start[180]: booting system configuration /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test machine # [ 5.179865] initrd-nixos-activation-start[180]: running activation script... machine # [ 5.696997] initrd-nixos-activation-start[203]: setting up /etc... machine # [ 5.984307] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 5.987136] systemd[1]: Finished NixOS Activation. machine # [ 5.992313] systemd[1]: Starting Switch Root... machine # [ 6.013193] systemd[1]: Switching root. machine # [ 6.192300] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). machine # [ 6.781841] systemd[1]: systemd 262-9457f81 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 6.787052] systemd[1]: Detected virtualization qemu. machine # [ 6.789100] systemd[1]: Detected architecture arm64. machine # [ 6.792920] systemd[1]: Detected first boot. machine # [ 6.803754] systemd[1]: Initializing machine ID from random generator. machine # [ 6.957783] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 7.143785] systemd[1]: Applying preset policy. machine # [ 7.658321] systemd[1]: Populated /etc with preset unit settings. machine # [ 8.203908] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 8.205702] systemd[1]: Stopped initrd-switch-root.service. machine # [ 8.208631] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 8.211488] systemd[1]: Created slice Slice /system/getty. machine # [ 8.213369] systemd[1]: Created slice User and Session Slice. machine # [ 8.215640] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 8.218245] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 8.219645] systemd[1]: Expecting device /dev/hvc0... machine # [ 8.222451] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 8.223670] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 8.225504] systemd[1]: Stopped target initrd-fs.target. machine # [ 8.227957] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 8.229160] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 8.230958] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 8.232563] systemd[1]: Reached target Path Units. machine # [ 8.234645] systemd[1]: Reached target Remote File Systems. machine # [ 8.236658] systemd[1]: Reached target Slice Units. machine # [ 8.239060] systemd[1]: Reached target Swaps. machine # [ 8.246937] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 8.254712] systemd[1]: Listening on Process Core Dump Socket. machine # [ 8.259356] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 8.263515] systemd[1]: Listening on Factory Reset Management. machine # [ 8.265031] systemd[1]: Listening on Hostname Service Socket. machine # [ 8.280019] systemd[1]: Starting Journal Log Access Socket... machine # [ 8.284034] systemd[1]: Listening on Journal Audit Socket. machine # [ 8.291417] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 8.294784] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 8.297432] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.301129] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 8.323333] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 8.326284] systemd[1]: Listening on udev Varlink Socket. machine # [ 8.332016] systemd[1]: Mounting Huge Pages File System... machine # [ 8.338325] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 8.351162] systemd[1]: Mounting Kernel Debug File System... machine # [ 8.363115] systemd[1]: Mounting Kernel Trace File System... machine # [ 8.376774] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 8.389546] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 8.393438] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 8.422228] systemd[1]: Mounting Kernel Configuration File System... machine # [ 8.433607] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 8.436462] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 8.488327] systemd[1]: Starting Load Kernel Module fuse... machine # [ 8.493549] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 8.542843] systemd[1]: Starting Journal Service... machine # [ 8.567259] systemd[1]: Starting Load Kernel Modules... machine # [ 8.616631] fuse: init (API version 7.45) machine # [ 8.617605] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 8.636953] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 8.649815] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.657364] systemd-journald[275]: Collecting audit messages is enabled. machine # [ 8.657863] systemd[1]: Queued start job for default target Multi-User System. machine # [ 8.661308] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 8.691387] systemd[1]: Starting Coldplug All udev Devices... machine # [ 8.682866] systemd-modules-load[276]: Module 'atkbd' is built in machine # [ 8.684779] systemd-modules-load[276]: Module 'loop' is built in machine # [ 8.704283] systemd[1]: Started Journal Service. machine # [ 8.715040] systemd[1]: Listening on Journal Log Access Socket. machine # [ 8.723507] systemd[1]: Mounted Huge Pages File System. machine # [ 8.726690] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 8.734017] systemd[1]: Mounted Kernel Debug File System. machine # [ 8.741691] systemd[1]: Mounted Kernel Trace File System. machine # [ 8.742803] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 8.743746] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 8.752902] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 8.758023] systemd[1]: modprobe@fuse.service: Deactivated successfully. machine # [ 8.762864] systemd[1]: Finished Load Kernel Module fuse. machine # [ 8.768973] systemd[1]: Mounted Kernel Configuration File System. machine # [ 8.770896] systemd[1]: Finished Load Kernel Modules. machine # [ 8.789728] systemd-oomd[278]: No swap; memory pressure usage will be degraded machine # [ 8.800222] systemd[1]: Mounting FUSE Control File System... machine # [ 8.809813] systemd[1]: Starting Firewall... machine # [ 8.824765] systemd[1]: Starting Apply Kernel Variables... machine # [ 8.841367] EXT4-fs (vda): re-mounted caa6b769-555d-4b32-ad4a-9d1c25c4b859. machine # [ 8.854833] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 8.860150] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 8.863760] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 8.890101] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 8.908490] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 8.911454] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 8.940932] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 8.946046] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 9.010117] systemd[1]: Finished Apply Kernel Variables. machine # [ 9.022653] systemd[1]: Mounted FUSE Control File System. machine # [ 9.073884] systemd-journald[275]: Received client request to flush runtime journal. machine # [ 9.132699] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 9.143239] systemd[1]: Reached target First Boot Complete. machine # [ 9.144888] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 9.148378] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 9.153185] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 9.275554] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 9.277142] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 9.287807] systemd[1]: Mounting /run/wrappers... machine # [ 9.290334] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 9.372146] systemd[1]: Mounted /run/wrappers. machine # [ 9.373198] systemd[1]: Reached target Local File Systems. machine # [ 9.377556] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 9.380926] systemd[1]: Starting register-nix-paths.service... machine # [ 9.394427] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 9.395679] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 9.420970] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 9.458714] systemd[1]: Starting Create System Files and Directories... machine # [ 9.501185] systemd-udevd[320]: Using default interface naming scheme 'v261'. machine # [ 9.565689] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 9.577848] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 9.738833] systemd[1]: Finished Create System Files and Directories. machine # [ 9.756348] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 9.769391] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 9.921099] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 9.973739] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 10.047567] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 10.057152] systemd[1]: Starting Update is Completed... machine # [ 10.181982] systemd[1]: Finished Update is Completed. machine # [ 10.496988] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 10.500400] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 11.048385] systemd[1]: Finished Coldplug All udev Devices. machine # [ 11.124507] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 11.344233] systemd[1]: Finished register-nix-paths.service. machine # [ 11.345936] systemd[1]: Reached target System Initialization. machine # [ 11.351074] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 11.355208] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 11.360756] systemd[1]: Reached target Timer Units. machine # [ 11.364441] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 11.367575] systemd[1]: Listening on Nix Daemon Socket. machine # [ 11.374063] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 11.378557] systemd[1]: Reached target Socket Units. machine # [ 11.383095] systemd[1]: Reached target Basic System. machine # [ 11.388803] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 11.393151] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 11.415363] systemd[1]: Starting Post-Boot Actions... machine # [ 11.452888] systemd[1]: Started Reset console on configuration changes. machine # [ 11.482192] systemd[1]: Starting resolvconf update... machine # [ 11.500959] systemd[1]: Finished Firewall. machine # [ 11.566675] systemd[1]: Finished Post-Boot Actions. machine # [ 11.577287] nsncd[478]: Sep 06 02:30:30.112 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 11.586746] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 11.619982] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 11.626785] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 11.632636] systemd[1]: Reached target User and Group Name Lookups. machine # [ 11.640220] systemd[1]: Starting D-Bus System Message Bus... machine # [ 11.662463] systemd[1]: Starting User Login Management... machine # [ 11.678656] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 11.701317] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 11.732188] systemd[1]: Started backdoor.service. machine # connecting to host... machine # [ 11.949230] systemd-logind[504]: New seat seat0. machine # [ 11.958606] dbus-broker-launch[503]: Looking up NSS user entry for 'systemd-timesync'... machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine # [ 11.967663] systemd[1]: Started User Login Management. machine: (connecting took 12.24 seconds) machine # [ 11.974517] dbus-broker-launch[503]: NSS returned no entry for 'systemd-timesync' machine: (finished: waiting for the VM to finish booting, in 12.76 seconds) machine # [ 11.982701] dbus-broker-launch[503]: Invalid user-name in /nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 11.994271] systemd[1]: Starting linger-users.service... machine # [ 12.029113] systemd[1]: Started D-Bus System Message Bus. machine # [ 12.056440] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 12.057494] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 12.058342] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 12.059213] systemd[1]: Stopping User and Group Name Lookups... machine # [ 12.080266] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 12.088400] systemd[1]: nscd.service: Deactivated successfully. machine # [ 12.089365] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 12.113349] dbus-broker-launch[503]: Ready machine # [ 12.139158] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 12.241785] nsncd[567]: Sep 06 02:30:30.778 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 12.276573] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 12.316363] (udev-worker)[381]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 12.327155] (udev-worker)[389]: Network interface NamePolicy= disabled on kernel command line. machine # [ 12.333670] (udev-worker)[381]: Network interface NamePolicy= disabled on kernel command line. machine # [ 12.348525] systemd[1]: Finished resolvconf update. machine # [ 12.349460] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 12.350398] systemd[1]: Finished linger-users.service. machine # [ 12.383509] systemd[1]: Created slice Slice /user/1001. machine # [ 12.394733] systemd[1]: Reached target Preparation for Network. machine # [ 12.425897] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 12.462241] systemd[1]: Reached target User and Group Name Lookups. machine # [ 12.463285] systemd[1]: Starting DHCP Client... machine # [ 12.493522] systemd[1]: Starting Extra networking commands.... machine # [ 12.494490] systemd[1]: Starting User Runtime Directory /run/user/1001... machine # [ 12.523673] systemd[1]: Finished User Runtime Directory /run/user/1001. machine # [ 12.542457] systemd[1]: Starting User Manager for UID 1001... machine # [ 12.561190] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 12.580859] systemd[1]: Starting Address configuration of eth1... machine # [ 12.642270] (systemd)[603]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 12.658174] systemd-logind[504]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'. machine # [ 12.720719] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 12.947068] network-addresses-eth1-start[604]: adding address 192.168.1.1/24... done machine # [ 12.985895] systemd-logind[504]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 13.042924] network-addresses-eth1-start[604]: adding address 2001:db8:1::1/64... done machine # [ 13.066985] systemd[603]: Queued start job for default target Main User Target. machine # [ 13.106057] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 13.107671] systemd[603]: Created slice User Application Slice. machine # [ 13.170578] systemd[603]: Started Daily Cleanup of User's Temporary Directories. machine # [ 13.190516] systemd[603]: Reached target Paths. machine # [ 13.208999] systemd[603]: Reached target Timers. machine # [ 13.247403] systemd[603]: Starting D-Bus User Message Bus Socket... machine # [ 13.264848] systemd[603]: Starting Create User Files and Directories... machine # [ 13.292104] systemd[603]: Finished Create User Files and Directories. machine # [ 13.309466] mousedev: PS/2 mouse device common for all mice machine # [ 13.319438] systemd[1]: Finished Address configuration of eth1. machine # [ 13.335404] systemd[603]: Listening on D-Bus User Message Bus Socket. machine # [ 13.356207] systemd[603]: Reached target Sockets. machine # [ 13.357093] systemd[603]: Reached target Basic System. machine # [ 13.357797] systemd[1]: Started User Manager for UID 1001. machine # [ 13.358576] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 13.359496] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 13.405612] systemd[603]: Reached target Main User Target. machine # [ 13.406470] systemd[603]: Startup finished in 619ms. machine # [ 13.407164] systemd-logind[504]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 13.477200] dhcpcd[612]: dhcpcd-10.3.2 starting machine # [ 13.532395] dhcpcd[685]: dev: loaded udev machine # [ 13.603030] 8021q: 802.1Q VLAN Support v1.8 machine # [ 13.603427] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 13.703028] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 13.737191] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 13.740277] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 13.745054] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 13.745448] cfg80211: failed to load regulatory.db machine # [ 13.765078] systemd[1]: Finished Extra networking commands.. machine # [ 13.776527] systemd[1]: Reached target Network. machine # [ 13.789681] systemd[1]: Starting Permit User Sessions... machine # [ 14.047654] systemd[1]: Finished Permit User Sessions. machine # [ 14.063888] systemd[1]: Started Getty on tty1. machine # [ 14.078422] systemd[1]: Reached target Login Prompts. machine # [ 14.264088] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 14.260059] dhcpcd[685]: eth0: waiting for carrier machine # [ 14.277623] dhcpcd[685]: libudev: received NULL device machine # [ 14.286962] dhcpcd[685]: libudev: received NULL device machine # [ 14.287898] dhcpcd[685]: eth0: carrier acquired machine # [ 14.347687] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 machine # [ 14.420104] dhcpcd[685]: DUID 00:01:00:01:32:2f:8c:c8:52:54:00:12:34:56 machine # [ 14.421171] dhcpcd[685]: eth0: IAID 00:12:34:56 machine # [ 14.421814] dhcpcd[685]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 14.655507] systemd[1]: Starting Virtual Console Setup... machine # [ 14.678088] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 14.709568] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 14.711008] systemd[1]: Stopped Virtual Console Setup. machine # [ 14.715433] systemd[1]: Starting Virtual Console Setup... machine # [ 14.718376] systemd-logind[504]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 15.196481] systemd-vconsole-setup[723]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 15.206343] systemd[1]: Finished Virtual Console Setup. machine # [ 15.469841] dhcpcd[685]: eth0: soliciting a DHCP lease machine # [ 15.481314] dhcpcd[685]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 15.484432] dhcpcd[685]: eth0: probing address 10.0.2.15/24 machine # [ 16.814008] dhcpcd[685]: eth0: soliciting an IPv6 router machine # [ 16.817525] dhcpcd[685]: eth0: Router Advertisement from fe80::2 machine # [ 16.820412] dhcpcd[685]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 16.823271] dhcpcd[685]: eth0: adding route to fec0::/64 machine # [ 16.825730] dhcpcd[685]: eth0: adding default route via fe80::2 machine # [ 20.548705] dhcpcd[685]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 20.550761] dhcpcd[685]: eth0: adding route to 10.0.2.0/24 machine # [ 20.552647] dhcpcd[685]: eth0: adding default route via 10.0.2.2 machine # [ 20.675299] systemd[1]: Started DHCP Client. machine # [ 20.677658] systemd[1]: Reached target Multi-User System. machine # [ 20.678497] systemd[1]: Startup finished in 1.034s (kernel) + 5.240s (initrd) + 14.402s (userspace) = 20.677s. machine: (finished: waiting for unit multi-user.target, in 21.56 seconds) machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/bin/switch-to-configuration test machine # Checking switch inhibitors... done machine # [ 21.357194] nixos[840]: switching to system configuration /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test machine # [ 21.359604] systemd[1]: Stopped target Local File Systems. machine # activating the configuration... machine # [ 21.362455] systemd[1]: Stopped target Remote File Systems. machine # setting up /etc... machine # [ 21.847089] systemd[1]: Reload requested from client PID 840 ('.switch-to-conf') (unit backdoor.service)... machine # [ 21.849304] systemd[1]: Reloading... machine # [ 22.469221] systemd[1]: Reloading finished in 618 ms. machine # reloading user units for usertest... machine # [ 22.523525] systemd[603]: Created slice User Core Session Slice. machine # [ 22.528320] systemd[603]: Starting D-Bus User Message Bus... machine # [ 22.610062] dbus-broker-launch[910]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.import1.service' machine # [ 22.613303] dbus-broker-launch[910]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.machine1.service' machine # [ 22.616614] dbus-broker-launch[910]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.portable1.service' machine # [ 22.620119] dbus-broker-launch[910]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.systemd1.service' machine # [ 22.624064] dbus-broker-launch[910]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored machine # [ 22.627178] dbus-broker-launch[910]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored machine # [ 22.629450] systemd[603]: Started D-Bus User Message Bus. machine # [ 22.636849] dbus-broker-launch[910]: Ready machine # [ 22.757525] systemd[603]: Reexecution requested from client PID 909 ('switch-to-confi')... machine # [ 22.761384] systemd[603]: Reexecuting. machine # [ 22.819008] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.050663] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.059988] systemd[603]: Reload requested from client PID 909 ('switch-to-confi')... machine # [ 23.062817] systemd[603]: Reloading... machine # [ 23.301633] systemd[603]: Reloading finished in 237 ms. machine # [ 23.320197] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 23.332614] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 23.335389] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 23.339222] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 23.390935] systemd[603]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 23.410658] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... machine # [ 23.556478] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. machine # [ 23.561767] systemd[1]: Reached target Reactivate sysinit units. machine # [ 23.566379] systemd[1]: Reached target Local File Systems. machine # [ 23.571715] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 23.577402] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 23.578394] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 23.579611] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 23.587122] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 23.596942] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 23.603174] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 23.604707] systemd[1]: Reached target Remote File Systems. machine # [ 23.605518] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 23.606767] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 24.001330] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 24.006593] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 24.010545] systemd[1]: Starting linger-users.service... machine # [ 24.079561] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 24.083780] systemd[1]: Finished linger-users.service. machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service machine # [ 24.353144] nixos[840]: finished switching to system configuration /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/bin/switch-to-configuration test, in 3.60 seconds) machine: must succeed: rm /etc/NIXOS machine: (finished: must succeed: rm /etc/NIXOS, in 0.07 seconds) machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/7prbf4wwajd81mn77q9y0xvvg807vm95-nixos-system-other-test/bin/switch-to-configuration test machine # this should succeed (config: /nix/store/7prbf4wwajd81mn77q9y0xvvg807vm95-nixos-system-other-test, action: test) machine # Checking switch inhibitors... done machine # [ 25.184937] nixos[988]: switching to system configuration /nix/store/7prbf4wwajd81mn77q9y0xvvg807vm95-nixos-system-other-test machine # [ 25.188210] systemd[1]: Stopped target Remote File Systems. machine # [ 25.190084] systemd[1]: Stopped target Local File Systems. machine # activating the configuration... machine # removing user ‘usertest’ machine # setting up /etc... machine # [ 25.785689] systemd[1]: Reload requested from client PID 988 ('.switch-to-conf') (unit backdoor.service)... machine # [ 25.787600] systemd[1]: Reloading... machine # [ 26.526913] systemd[1]: Reloading finished in 736 ms. machine # reloading user units for usertest... machine # [ 26.716223] systemd[603]: Reexecution requested from client PID 1059 ('switch-to-confi')... machine # [ 26.721085] systemd[603]: Reexecuting. machine # [ 26.779035] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.018125] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.025823] systemd[603]: Reload requested from client PID 1059 ('switch-to-confi')... machine # [ 27.028914] systemd[603]: Reloading... machine # [ 27.293464] systemd[603]: Reloading finished in 262 ms. machine # [ 27.308184] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 27.319608] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 27.322369] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 27.325714] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 27.381126] systemd[603]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 27.386611] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 27.388894] systemd[1]: Stopping Reactivate sysinit units... machine # [ 27.389829] systemd[1]: Reached target Reactivate sysinit units. machine # reloading the following units: dbus-broker.service machine # [ 27.394095] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 27.395447] dbus-broker-launch[503]: Caught SIGHUP, trigger reload. machine # [ 27.417984] dbus-broker-launch[503]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 27.420647] dbus-broker-launch[503]: NSS returned no entry for 'systemd-timesync' machine # [ 27.421953] dbus-broker-launch[503]: Invalid user-name in /nix/store/2d37a0ag8593x6bsc14irprkj2vpg80c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 27.459242] systemd[1]: Reloaded D-Bus System Message Bus. machine # restarting the following units: network-addresses-eth1.service machine # [ 27.467961] systemd[1]: Stopping Address configuration of eth1... machine # [ 27.531733] network-addresses-eth1-pre-stop[1072]: deleting address 192.168.1.1/24... done machine # [ 27.548145] network-addresses-eth1-pre-stop[1072]: deleting address 2001:db8:1::1/64... done machine # [ 27.561294] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 27.564129] systemd[1]: Stopped Address configuration of eth1. machine # [ 27.570405] systemd[1]: Starting Address configuration of eth1... machine # [ 27.654916] network-addresses-eth1-start[1077]: adding address 192.168.1.2/24... done machine # [ 27.668086] network-addresses-eth1-start[1077]: adding address 2001:db8:1::2/64... done machine # [ 27.684699] systemd[1]: Finished Address configuration of eth1. machine # [ 27.693411] systemd[1]: Reached target Remote File Systems. machine # [ 27.699701] systemd[1]: Starting linger-users.service... machine # [ 27.703992] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.708406] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 27.716412] systemd[1]: Reached target Local File Systems. machine # [ 27.717415] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 27.720450] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 27.728925] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 27.730274] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 27.741829] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 27.745563] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.748301] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.793903] linger-users-start[1092]: id: ‘usertest’: no such user machine # [ 27.796731] linger-users-start[1088]: Removing linger for missing user usertest machine # [ 27.814166] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 27.815363] systemd[1]: Finished linger-users.service. machine # [ 28.198745] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 28.202915] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 28.463837] nixos[988]: finished switching to system configuration /nix/store/7prbf4wwajd81mn77q9y0xvvg807vm95-nixos-system-other-test machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/7prbf4wwajd81mn77q9y0xvvg807vm95-nixos-system-other-test/bin/switch-to-configuration test, in 4.04 seconds) subtest: services machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 29.141010] nixos[1155]: switching to system configuration /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test machine # [ 29.145147] systemd[1]: Stopped target Local File Systems. machine # [ 29.146638] systemd[1]: Stopped target Remote File Systems. machine # [ 29.701638] systemd[1]: Reload requested from client PID 1155 ('.switch-to-conf') (unit backdoor.service)... machine # [ 29.703669] systemd[1]: Reloading... machine # [ 30.373302] systemd[1]: Reloading finished in 666 ms. machine # [ 30.588357] systemd[603]: Reexecution requested from client PID 1224 ('switch-to-confi')... machine # [ 30.589835] systemd[603]: Reexecuting. machine # [ 30.659094] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.882569] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.891233] systemd[603]: Reload requested from client PID 1224 ('switch-to-confi')... machine # [ 30.894341] systemd[603]: Reloading... machine # [ 31.109432] systemd[603]: Reloading finished in 214 ms. machine # [ 31.124199] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.135503] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 31.138401] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 31.141448] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 31.197252] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 31.201753] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 31.206845] systemd[1]: Stopping Reactivate sysinit units... machine # [ 31.207970] systemd[1]: Reached target Reactivate sysinit units. machine # [ 31.209559] dbus-broker-launch[503]: Caught SIGHUP, trigger reload. machine # [ 31.210731] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 31.222997] dbus-broker-launch[503]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 31.225336] dbus-broker-launch[503]: NSS returned no entry for 'systemd-timesync' machine # [ 31.226590] dbus-broker-launch[503]: Invalid user-name in /nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 31.257742] systemd[1]: Reloaded D-Bus System Message Bus. machine # [ 31.265460] systemd[1]: Stopping Address configuration of eth1... machine # [ 31.324978] network-addresses-eth1-pre-stop[1237]: deleting address 192.168.1.2/24... done machine # [ 31.338070] network-addresses-eth1-pre-stop[1237]: deleting address 2001:db8:1::2/64... done machine # [ 31.349397] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 31.352224] systemd[1]: Stopped Address configuration of eth1. machine # [ 31.356874] systemd[1]: Starting Address configuration of eth1... machine # [ 31.433090] network-addresses-eth1-start[1242]: adding address 192.168.1.1/24... done machine # [ 31.444517] network-addresses-eth1-start[1242]: adding address 2001:db8:1::1/64... done machine # [ 31.460176] systemd[1]: Finished Address configuration of eth1. machine # [ 31.463002] systemd[1]: Reached target Remote File Systems. machine # [ 31.467467] systemd[1]: Reached target Local File Systems. machine # [ 31.471561] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 31.476683] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 31.477631] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 31.478874] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 31.492484] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 31.495543] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.497374] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.499066] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.502281] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 31.826787] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 31.829317] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 31.834776] systemd[1]: Starting linger-users.service... machine # [ 31.884488] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 31.887622] systemd[1]: Finished linger-users.service. machine # [ 32.147943] nixos[1155]: finished switching to system configuration /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.69 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 32.809911] nixos[1311]: switching to system configuration /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test machine # [ 32.813461] systemd[1]: Stopped target Local File Systems. machine # [ 32.814902] systemd[1]: Stopped target Remote File Systems. machine # [ 33.303430] systemd[1]: Reload requested from client PID 1311 ('.switch-to-conf') (unit backdoor.service)... machine # [ 33.305730] systemd[1]: Reloading... machine # [ 33.889806] systemd[1]: Reloading finished in 580 ms. machine # [ 34.056449] systemd[603]: Reexecution requested from client PID 1380 ('switch-to-confi')... machine # [ 34.059813] systemd[603]: Reexecuting. machine # [ 34.121869] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.290036] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.296832] systemd[603]: Reload requested from client PID 1380 ('switch-to-confi')... machine # [ 34.299205] systemd[603]: Reloading... machine # [ 34.492974] systemd[603]: Reloading finished in 192 ms. machine # [ 34.508198] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.519115] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 34.520302] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 34.523254] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 34.564468] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 34.569203] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 34.572338] systemd[1]: Stopping Reactivate sysinit units... machine # [ 34.573239] systemd[1]: Reached target Reactivate sysinit units. machine # [ 34.575348] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.578742] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 34.581900] systemd[1]: Reached target Local File Systems. machine # [ 34.586191] systemd[1]: Reached target Remote File Systems. machine # [ 34.591872] systemd[1]: Starting linger-users.service... machine # [ 34.595790] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 34.606218] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 34.607111] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 34.610643] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 34.614466] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 34.620228] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.623711] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.680768] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 34.682482] systemd[1]: Finished linger-users.service. machine # [ 34.932907] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 34.936130] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 35.193381] nixos[1311]: finished switching to system configuration /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.04 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1 machine # [ 35.778491] nixos[1452]: switching to system configuration /nix/store/pl2am5s3lbqd2zsyjh8ls885a80l7g54-nixos-system-machine-test machine # [ 35.781140] systemd[1]: Stopped target Local File Systems. machine # [ 35.783300] systemd[1]: Stopped target Remote File Systems. machine # [ 36.300840] systemd[1]: Reload requested from client PID 1452 ('.switch-to-conf') (unit backdoor.service)... machine # [ 36.302837] systemd[1]: Reloading... machine # [ 36.986742] systemd[1]: Reloading finished in 681 ms. machine # [ 37.161254] systemd[603]: Reexecution requested from client PID 1521 ('switch-to-confi')... machine # [ 37.166358] systemd[603]: Reexecuting. machine # [ 37.230708] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.430596] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.437935] systemd[603]: Reload requested from client PID 1521 ('switch-to-confi')... machine # [ 37.440566] systemd[603]: Reloading... machine # [ 37.637812] systemd[603]: Reloading finished in 196 ms. machine # [ 37.652354] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.662797] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 37.665922] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 37.669325] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 37.723635] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 37.728701] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 37.732448] systemd[1]: Stopping Reactivate sysinit units... machine # [ 37.733439] systemd[1]: Reached target Reactivate sysinit units. machine # [ 37.734275] systemd[1]: Reached target Local File Systems. machine # [ 37.740490] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.741854] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 37.744634] systemd[1]: Reached target Remote File Systems. machine # [ 37.752287] systemd[1]: Starting linger-users.service... machine # [ 37.756686] systemd[1]: Starting test.service... machine # [ 37.763483] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 37.776734] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 37.777656] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 37.778841] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 37.790728] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 37.794851] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.798366] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.832312] systemd[1]: Finished test.service. machine # [ 37.869700] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 37.873591] systemd[1]: Finished linger-users.service. machine # [ 38.176448] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 38.179955] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 38.438995] nixos[1452]: finished switching to system configuration /nix/store/pl2am5s3lbqd2zsyjh8ls885a80l7g54-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 3.24 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1 machine # [ 39.074809] nixos[1595]: switching to system configuration /nix/store/pl2am5s3lbqd2zsyjh8ls885a80l7g54-nixos-system-machine-test machine # [ 39.077715] systemd[1]: Stopped target Remote File Systems. machine # [ 39.080224] systemd[1]: Stopped target Local File Systems. machine # [ 39.579552] systemd[1]: Reload requested from client PID 1595 ('.switch-to-conf') (unit backdoor.service)... machine # [ 39.582168] systemd[1]: Reloading... machine # [ 40.229721] systemd[1]: Reloading finished in 643 ms. machine # [ 40.392116] systemd[603]: Reexecution requested from client PID 1664 ('switch-to-confi')... machine # [ 40.393523] systemd[603]: Reexecuting. machine # [ 40.477249] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.718090] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.726704] systemd[603]: Reload requested from client PID 1664 ('switch-to-confi')... machine # [ 40.729968] systemd[603]: Reloading... machine # [ 40.940975] systemd[603]: Reloading finished in 210 ms. machine # [ 40.957719] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.965954] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 40.968582] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 40.972200] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 41.021654] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 41.026567] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 41.029932] systemd[1]: Stopping Reactivate sysinit units... machine # [ 41.035512] systemd[1]: Reached target Reactivate sysinit units. machine # [ 41.037204] systemd[1]: Reached target Local File Systems. machine # [ 41.038430] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 41.044430] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 41.046843] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 41.052868] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 41.055387] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 41.066544] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.070667] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.073172] systemd[1]: Reached target Remote File Systems. machine # [ 41.074122] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.075926] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 41.436892] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 41.440935] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 41.446101] systemd[1]: Starting linger-users.service... machine # [ 41.511846] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 41.514681] systemd[1]: Finished linger-users.service. machine # [ 41.771242] nixos[1595]: finished switching to system configuration /nix/store/pl2am5s3lbqd2zsyjh8ls885a80l7g54-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 3.33 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&1 machine # [ 42.371751] nixos[1736]: switching to system configuration /nix/store/1pl13kblzmh2q7xw3nkykzxm06qbq0lh-nixos-system-machine-test machine # [ 42.374387] systemd[1]: Stopped target Local File Systems. machine # [ 42.376765] systemd[1]: Stopped target Remote File Systems. machine # [ 42.906957] systemd[1]: Reload requested from client PID 1736 ('.switch-to-conf') (unit backdoor.service)... machine # [ 42.909224] systemd[1]: Reloading... machine # [ 43.540106] systemd[1]: Reloading finished in 628 ms. machine # [ 43.692511] systemd[603]: Reexecution requested from client PID 1805 ('switch-to-confi')... machine # [ 43.693918] systemd[603]: Reexecuting. machine # [ 43.767262] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.020166] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.027088] systemd[603]: Reload requested from client PID 1805 ('switch-to-confi')... machine # [ 44.030030] systemd[603]: Reloading... machine # [ 44.239796] systemd[603]: Reloading finished in 208 ms. machine # [ 44.256177] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.265775] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 44.268146] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 44.271849] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 44.333591] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 44.338899] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 44.341363] systemd[1]: Stopping Reactivate sysinit units... machine # [ 44.343021] systemd[1]: Reached target Reactivate sysinit units. machine # [ 44.346812] systemd[1]: Reached target Local File Systems. machine # [ 44.350786] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 44.356214] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 44.357222] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 44.358445] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 44.374895] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 44.379394] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 44.380933] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 44.382126] systemd[1]: Reached target Remote File Systems. machine # [ 44.382961] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 44.385153] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 44.740204] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 44.744583] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 44.749326] systemd[1]: Starting linger-users.service... machine # [ 44.818883] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 44.822336] systemd[1]: Finished linger-users.service. machine # [ 45.079307] nixos[1736]: finished switching to system configuration /nix/store/1pl13kblzmh2q7xw3nkykzxm06qbq0lh-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&1, in 3.31 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 45.776355] nixos[1877]: switching to system configuration /nix/store/34wr58kgh4jvxi46ql47qackvp2l37vf-nixos-system-machine-test machine # [ 45.781102] systemd[1]: Stopped target Local File Systems. machine # [ 45.782039] systemd[1]: Stopped target Remote File Systems. machine # [ 45.782825] systemd[1]: test.service: Deactivated successfully. machine # [ 45.783640] systemd[1]: Stopped Test unit. machine # [ 46.327294] systemd[1]: Reload requested from client PID 1877 ('.switch-to-conf') (unit backdoor.service)... machine # [ 46.329795] systemd[1]: Reloading... machine # [ 47.679624] systemd[1]: Reloading finished in 1347 ms. machine # [ 47.877640] systemd[603]: Reexecution requested from client PID 1946 ('switch-to-confi')... machine # [ 47.882290] systemd[603]: Reexecuting. machine # [ 47.944003] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.165761] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.173019] systemd[603]: Reload requested from client PID 1946 ('switch-to-confi')... machine # [ 48.175596] systemd[603]: Reloading... machine # [ 48.389757] systemd[603]: Reloading finished in 212 ms. machine # [ 48.404229] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.414350] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 48.417436] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 48.420868] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 48.474225] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 48.478715] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 48.481864] systemd[1]: Stopping Reactivate sysinit units... machine # [ 48.482784] systemd[1]: Reached target Reactivate sysinit units. machine # [ 48.483572] systemd[1]: Reached target Remote File Systems. machine # [ 48.486573] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.489047] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 48.492958] systemd[1]: Reached target Local File Systems. machine # [ 48.493791] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 48.500697] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 48.503904] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 48.506889] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 48.515328] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 48.520183] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.522279] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.837165] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 48.841706] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 48.844353] systemd[1]: Starting linger-users.service... machine # [ 48.848847] systemd[1]: Starting test.service... machine # [ 48.894522] systemd[1]: Finished test.service. machine # [ 48.923388] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 48.926405] systemd[1]: Finished linger-users.service. machine # [ 49.183769] nixos[1877]: finished switching to system configuration /nix/store/34wr58kgh4jvxi46ql47qackvp2l37vf-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 4.10 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 49.936521] nixos[2020]: switching to system configuration /nix/store/21hfw8sh6q8izq23jfphrq020id5dlkc-nixos-system-machine-test machine # [ 49.939262] systemd[1]: Stopped target Remote File Systems. machine # [ 49.941801] systemd[1]: Stopped target Local File Systems. machine # [ 50.566955] systemd[1]: Reload requested from client PID 2020 ('.switch-to-conf') (unit backdoor.service)... machine # [ 50.568758] systemd[1]: Reloading... machine # [ 52.517886] systemd[1]: Reloading finished in 1944 ms. machine # [ 52.668352] systemd[603]: Reexecution requested from client PID 2089 ('switch-to-confi')... machine # [ 52.669809] systemd[603]: Reexecuting. machine # [ 52.743108] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.959976] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.967004] systemd[603]: Reload requested from client PID 2089 ('switch-to-confi')... machine # [ 52.969434] systemd[603]: Reloading... machine # [ 53.174389] systemd[603]: Reloading finished in 203 ms. machine # [ 53.188216] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.198703] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 53.201354] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 53.204603] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 53.257258] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 53.261784] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 53.263945] systemd[1]: Stopping Reactivate sysinit units... machine # [ 53.265709] systemd[1]: Reached target Reactivate sysinit units. machine # [ 53.268313] systemd[1]: test.service: Deactivated successfully. machine # [ 53.269209] systemd[1]: Stopped test.service. machine # [ 53.269861] systemd[1]: Stopping test.service... machine # [ 53.273738] systemd[1]: Starting test.service... machine # [ 53.317093] systemd[1]: Finished test.service. machine # [ 53.323070] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.326384] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 53.330954] systemd[1]: Reached target Local File Systems. machine # [ 53.334917] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 53.339784] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 53.342748] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 53.345890] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 53.356361] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 53.358022] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.359263] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.361727] systemd[1]: Reached target Remote File Systems. machine # [ 53.734368] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 53.739077] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 53.742394] systemd[1]: Starting linger-users.service... machine # [ 53.810515] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 53.813810] systemd[1]: Finished linger-users.service. machine # [ 54.071737] nixos[2020]: finished switching to system configuration /nix/store/21hfw8sh6q8izq23jfphrq020id5dlkc-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 4.89 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&1 machine # [ 54.758420] nixos[2162]: switching to system configuration /nix/store/ji4y94m7gmiwi658hkfd0fa8rd5dy4cp-nixos-system-machine-test machine # [ 54.761400] systemd[1]: Stopped target Local File Systems. machine # [ 54.763872] systemd[1]: Stopped target Remote File Systems. machine # [ 55.337396] systemd[1]: Reload requested from client PID 2162 ('.switch-to-conf') (unit backdoor.service)... machine # [ 55.339470] systemd[1]: Reloading... machine # [ 56.981462] systemd[1]: Reloading finished in 1639 ms. machine # [ 57.126193] systemd[603]: Reexecution requested from client PID 2231 ('switch-to-confi')... machine # [ 57.127631] systemd[603]: Reexecuting. machine # [ 57.190001] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.379917] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.386735] systemd[603]: Reload requested from client PID 2231 ('switch-to-confi')... machine # [ 57.388919] systemd[603]: Reloading... machine # [ 57.579704] systemd[603]: Reloading finished in 189 ms. machine # [ 57.592175] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.601381] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 57.603902] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 57.606928] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 57.654267] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 57.658424] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 57.662681] systemd[1]: Stopping Reactivate sysinit units... machine # [ 57.663909] systemd[1]: Reached target Reactivate sysinit units. machine # [ 57.666305] systemd[1]: Reloading test.service... machine # [ 57.707806] systemd[1]: Reloaded test.service. machine # [ 57.711772] systemd[1]: Reached target Remote File Systems. machine # [ 57.717440] systemd[1]: Reached target Local File Systems. machine # [ 57.719515] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 57.724364] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 57.725193] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 57.726356] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 57.738478] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 57.741525] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.742778] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.745189] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.746515] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 58.065138] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 58.068836] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 58.072750] systemd[1]: Starting linger-users.service... machine # [ 58.139107] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 58.141971] systemd[1]: Finished linger-users.service. machine # [ 58.402295] nixos[2162]: finished switching to system configuration /nix/store/ji4y94m7gmiwi658hkfd0fa8rd5dy4cp-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&1, in 4.33 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1 machine # [ 59.038975] nixos[2304]: switching to system configuration /nix/store/nmllh5hhgdabn4zv8kb77g1f6fsmcmd1-nixos-system-machine-test machine # [ 59.042828] systemd[1]: Stopped target Local File Systems. machine # [ 59.044359] systemd[1]: Stopped target Remote File Systems. machine # [ 59.568126] systemd[1]: Reload requested from client PID 2304 ('.switch-to-conf') (unit backdoor.service)... machine # [ 59.570037] systemd[1]: Reloading... machine # [ 60.965857] systemd[1]: Reloading finished in 1393 ms. machine # [ 61.110234] systemd[603]: Reexecution requested from client PID 2373 ('switch-to-confi')... machine # [ 61.111698] systemd[603]: Reexecuting. machine # [ 61.178331] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 61.382808] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 61.389363] systemd[603]: Reload requested from client PID 2373 ('switch-to-confi')... machine # [ 61.391758] systemd[603]: Reloading... machine # [ 61.584544] systemd[603]: Reloading finished in 191 ms. machine # [ 61.601592] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 61.610114] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 61.611179] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 61.614169] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 61.662300] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 61.666511] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 61.668390] systemd[1]: Stopping Reactivate sysinit units... machine # [ 61.669937] systemd[1]: Reached target Reactivate sysinit units. machine # [ 61.671560] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 61.674228] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 61.677679] systemd[1]: Reached target Remote File Systems. machine # [ 61.682750] systemd[1]: Starting linger-users.service... machine # [ 61.687243] systemd[1]: Reached target Local File Systems. machine # [ 61.692574] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 61.699378] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 61.706157] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 61.707302] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 61.713904] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 61.715591] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 61.718480] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 61.781328] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 61.782366] systemd[1]: Finished linger-users.service. machine # [ 62.077945] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 62.081641] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 62.338741] nixos[2304]: finished switching to system configuration /nix/store/nmllh5hhgdabn4zv8kb77g1f6fsmcmd1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 3.94 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&1 machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&1, in 0.79 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 63.813452] nixos[2454]: switching to system configuration /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test machine # [ 63.817704] systemd[1]: Stopped target Local File Systems. machine # [ 63.819646] systemd[1]: Stopped target Remote File Systems. machine # [ 63.821334] systemd[1]: test.service: Deactivated successfully. machine # [ 63.822226] systemd[1]: Stopped test.service. machine # [ 64.336425] systemd[1]: Reload requested from client PID 2454 ('.switch-to-conf') (unit backdoor.service)... machine # [ 64.338427] systemd[1]: Reloading... machine # [ 65.017714] systemd[1]: Reloading finished in 676 ms. machine # [ 65.179281] systemd[603]: Reexecution requested from client PID 2523 ('switch-to-confi')... machine # [ 65.183087] systemd[603]: Reexecuting. machine # [ 65.250965] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.472369] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.479459] systemd[603]: Reload requested from client PID 2523 ('switch-to-confi')... machine # [ 65.482706] systemd[603]: Reloading... machine # [ 65.702244] systemd[603]: Reloading finished in 218 ms. machine # [ 65.716705] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.730213] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 65.731287] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 65.734772] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 65.786112] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 65.790882] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 65.792999] systemd[1]: Stopping Reactivate sysinit units... machine # [ 65.794502] systemd[1]: Reached target Reactivate sysinit units. machine # [ 65.797126] systemd[1]: Reached target Remote File Systems. machine # [ 65.804567] systemd[1]: Starting linger-users.service... machine # [ 65.817141] systemd[1]: Reached target Local File Systems. machine # [ 65.819249] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 65.827339] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 65.831738] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 65.837596] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 65.843205] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 65.846271] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 65.847677] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 65.849087] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 65.850563] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 65.918534] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 65.921738] systemd[1]: Finished linger-users.service. machine # [ 66.334830] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 66.338530] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 66.595650] nixos[2454]: finished switching to system configuration /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.46 seconds) machine: must succeed: /nix/store/ic52wwm4qpvzays82bmmffyjfqs776cp-separate-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 67.351654] nixos[2594]: switching to system configuration /nix/store/mfbbc6b0191xm8f53inyhbpl90k62v5n-nixos-system-machine-test machine # [ 67.354753] systemd[1]: Stopped target Remote File Systems. machine # [ 67.356188] systemd[1]: Stopped target Local File Systems. machine # [ 68.034819] systemd[1]: Reload requested from client PID 2594 ('.switch-to-conf') (unit backdoor.service)... machine # [ 68.037117] systemd[1]: Reloading... machine # [ 68.774010] systemd[1]: Reloading finished in 733 ms. machine # [ 68.965744] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 68.970125] systemd[603]: Reexecution requested from client PID 2663 ('switch-to-confi')... machine # [ 68.971720] systemd[603]: Reexecuting. machine # [ 69.043431] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.269154] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.276922] systemd[603]: Reload requested from client PID 2663 ('switch-to-confi')... machine # [ 69.279616] systemd[603]: Reloading... machine # [ 69.502703] systemd[603]: Reloading finished in 221 ms. machine # [ 69.520364] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.532767] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 69.536220] systemd[1]: Stopping Reactivate sysinit units... machine # [ 69.537197] systemd[1]: Reached target Reactivate sysinit units. machine # [ 69.538064] systemd[1]: Reached target Remote File Systems. machine # [ 69.546055] systemd[1]: Reached target Local File Systems. machine # [ 69.550382] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 69.557599] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 69.558621] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 69.559905] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 69.563199] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 69.577169] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 69.578679] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 69.579930] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 69.583436] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 69.986935] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 69.992472] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 69.996508] systemd[1]: Starting linger-users.service... machine # [ 70.000896] systemd[1]: Starting test.service... machine # [ 70.049965] systemd[1]: Finished test.service. machine # [ 70.082670] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 70.086023] systemd[1]: Finished linger-users.service. machine # [ 70.345019] nixos[2594]: finished switching to system configuration /nix/store/mfbbc6b0191xm8f53inyhbpl90k62v5n-nixos-system-machine-test machine: (finished: must succeed: /nix/store/ic52wwm4qpvzays82bmmffyjfqs776cp-separate-activation-script/bin/switch-to-configuration test 2>&1, in 3.75 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.03 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&1 machine # [ 71.225492] nixos[2742]: switching to system configuration /nix/store/v84r7qrvadh7bpvfzv48vxb3dyhrk03l-nixos-system-machine-test machine # [ 71.230130] systemd[1]: Stopped target Local File Systems. machine # [ 71.232996] systemd[1]: Stopped target Remote File Systems. machine # [ 71.234112] systemd[1]: test.service: Deactivated successfully. machine # [ 71.235483] systemd[1]: Stopped test.service. machine # [ 71.875774] systemd[1]: Reload requested from client PID 2742 ('.switch-to-conf') (unit backdoor.service)... machine # [ 71.877931] systemd[1]: Reloading... machine # [ 72.624922] systemd[1]: Reloading finished in 744 ms. machine # [ 72.779107] systemd[603]: Reexecution requested from client PID 2811 ('switch-to-confi')... machine # [ 72.781601] systemd[603]: Reexecuting. machine # [ 72.842939] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.054824] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.061723] systemd[603]: Reload requested from client PID 2811 ('switch-to-confi')... machine # [ 73.064395] systemd[603]: Reloading... machine # [ 73.261274] systemd[603]: Reloading finished in 195 ms. machine # [ 73.276237] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.287669] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 73.339088] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 73.343846] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 73.345027] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 73.349210] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 73.352364] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 73.354862] systemd[1]: Stopping Reactivate sysinit units... machine # [ 73.361109] systemd[1]: Reached target Reactivate sysinit units. machine # [ 73.363060] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.366067] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 73.377359] systemd[1]: Started test.service. machine # [ 73.382187] systemd[1]: Reached target Local File Systems. machine # [ 73.391683] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 73.403008] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 73.410485] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 73.417385] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 73.426671] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 73.437103] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.442409] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.443831] systemd[1]: Reached target Remote File Systems. machine # [ 73.451968] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 73.477700] systemd[1]: test.service: Deactivated successfully. machine # [ 73.813057] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 73.816722] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 73.821463] systemd[1]: Starting linger-users.service... machine # [ 73.899113] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 73.901362] systemd[1]: Finished linger-users.service. machine # [ 74.163043] nixos[2742]: finished switching to system configuration /nix/store/v84r7qrvadh7bpvfzv48vxb3dyhrk03l-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&1, in 3.73 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&1 machine # [ 74.856645] nixos[2887]: switching to system configuration /nix/store/xcmcsgvhpps7kavrw95lgf7q8bzalyk1-nixos-system-machine-test machine # [ 74.860327] systemd[1]: Stopped target Local File Systems. machine # [ 74.862405] systemd[1]: Stopped target Remote File Systems. machine # [ 75.446165] systemd[1]: Reload requested from client PID 2887 ('.switch-to-conf') (unit backdoor.service)... machine # [ 75.448286] systemd[1]: Reloading... machine # [ 76.145763] systemd[1]: Reloading finished in 695 ms. machine # [ 76.298812] systemd[603]: Reexecution requested from client PID 2956 ('switch-to-confi')... machine # [ 76.300998] systemd[603]: Reexecuting. machine # [ 76.370425] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.572712] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.579767] systemd[603]: Reload requested from client PID 2956 ('switch-to-confi')... machine # [ 76.583099] systemd[603]: Reloading... machine # [ 76.777262] systemd[603]: Reloading finished in 194 ms. machine # [ 76.793616] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.801546] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 76.804002] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 76.807197] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 76.854423] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 76.858507] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 76.861505] systemd[1]: Stopping Reactivate sysinit units... machine # [ 76.863019] systemd[1]: Reached target Reactivate sysinit units. machine # [ 76.863927] systemd[1]: Reached target Local File Systems. machine # [ 76.866629] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 76.867947] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 76.874297] systemd[1]: Reached target Remote File Systems. machine # [ 76.880344] systemd[1]: Starting escaped\x2ddash.service... machine # [ 76.883319] systemd[1]: Starting linger-users.service... machine # [ 76.896171] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 76.904843] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 76.906179] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 76.907709] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 76.910160] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 76.917636] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 76.919525] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 76.954156] systemd[1]: Finished escaped\x2ddash.service. machine # [ 76.991019] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 76.993640] systemd[1]: Finished linger-users.service. machine # [ 77.293718] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 77.296924] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 77.554080] nixos[2887]: finished switching to system configuration /nix/store/xcmcsgvhpps7kavrw95lgf7q8bzalyk1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&1, in 3.39 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&1 machine # [ 78.210181] nixos[3029]: switching to system configuration /nix/store/54phmzckxhc22as6m32xb5ppy8mpyd1h-nixos-system-machine-test machine # [ 78.213864] systemd[1]: Stopped target Local File Systems. machine # [ 78.215657] systemd[1]: Stopped target Remote File Systems. machine # [ 78.217149] systemd[1]: escaped\x2ddash.service: Deactivated successfully. machine # [ 78.218133] systemd[1]: Stopped escaped\x2ddash.service. machine # [ 78.731807] systemd[1]: Reload requested from client PID 3029 ('.switch-to-conf') (unit backdoor.service)... machine # [ 78.733889] systemd[1]: Reloading... machine # [ 79.365390] systemd[1]: Reloading finished in 628 ms. machine # [ 79.515319] systemd[603]: Reexecution requested from client PID 3098 ('switch-to-confi')... machine # [ 79.519040] systemd[603]: Reexecuting. machine # [ 79.574655] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 79.766917] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 79.773652] systemd[603]: Reload requested from client PID 3098 ('switch-to-confi')... machine # [ 79.776144] systemd[603]: Reloading... machine # [ 79.971489] systemd[603]: Reloading finished in 194 ms. machine # [ 79.984169] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 79.994454] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 79.995537] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 79.998614] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 80.048109] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 80.052449] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 80.055747] systemd[1]: Stopping Reactivate sysinit units... machine # [ 80.057611] systemd[1]: Reached target Reactivate sysinit units. machine # [ 80.060334] systemd[1]: Reached target Remote File Systems. machine # [ 80.065740] systemd[1]: Starting escaped\x2ddash.service... machine # [ 80.069016] systemd[1]: Starting linger-users.service... machine # [ 80.081240] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 80.082668] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 80.091679] systemd[1]: Reached target Local File Systems. machine # [ 80.092906] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 80.100991] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 80.101950] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 80.103324] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 80.110718] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 80.112721] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 80.114492] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 80.149949] systemd[1]: Finished escaped\x2ddash.service. machine # [ 80.193818] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 80.196706] systemd[1]: Finished linger-users.service. machine # [ 80.511984] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 80.515769] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 80.772860] nixos[3029]: finished switching to system configuration /nix/store/54phmzckxhc22as6m32xb5ppy8mpyd1h-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&1, in 3.22 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&1 machine # [ 81.431813] nixos[3171]: switching to system configuration /nix/store/jf8hnhyv37d4xsv6g3pijf0iy62cl8ak-nixos-system-machine-test machine # [ 81.435844] systemd[1]: Stopped target Local File Systems. machine # [ 81.437573] systemd[1]: Stopped target Remote File Systems. machine # [ 81.438425] systemd[1]: escaped\x2ddash.service: Deactivated successfully. machine # [ 81.439374] systemd[1]: Stopped escaped\x2ddash.service. machine # [ 81.953067] systemd[1]: Reload requested from client PID 3171 ('.switch-to-conf') (unit backdoor.service)... machine # [ 81.954995] systemd[1]: Reloading... machine # [ 82.619938] systemd[1]: Reloading finished in 662 ms. machine # [ 82.764843] systemd[603]: Reexecution requested from client PID 3240 ('switch-to-confi')... machine # [ 82.768586] systemd[603]: Reexecuting. machine # [ 82.829871] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 83.022641] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 83.030219] systemd[603]: Reload requested from client PID 3240 ('switch-to-confi')... machine # [ 83.032698] systemd[603]: Reloading... machine # [ 83.230041] systemd[603]: Reloading finished in 195 ms. machine # [ 83.244197] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 83.253220] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 83.255809] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 83.258853] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 83.305673] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 83.309797] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 83.313184] systemd[1]: Stopping Reactivate sysinit units... machine # [ 83.314049] systemd[1]: Reached target Reactivate sysinit units. machine # [ 83.314880] systemd[1]: Reached target Remote File Systems. machine # [ 83.318605] systemd[1]: Reached target Local File Systems. machine # [ 83.322213] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 83.326503] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 83.327411] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 83.332387] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 83.340372] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 83.341953] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 83.343164] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 83.347952] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 83.349675] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 83.657203] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 83.661716] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 83.664313] systemd[1]: Starting -.service... machine # [ 83.668396] systemd[1]: Starting linger-users.service... machine # [ 83.711579] systemd[1]: Finished -.service. machine # [ 83.736987] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 83.739386] systemd[1]: Finished linger-users.service. machine # [ 83.998314] nixos[3171]: finished switching to system configuration /nix/store/jf8hnhyv37d4xsv6g3pijf0iy62cl8ak-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&1, in 3.23 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&1 machine # [ 84.631032] nixos[3313]: switching to system configuration /nix/store/ypy2c8qpa44q68lbarp4knj20czy30k3-nixos-system-machine-test machine # [ 84.634180] systemd[1]: Stopped target Local File Systems. machine # [ 84.635560] systemd[1]: Stopped target Remote File Systems. machine # [ 85.153502] systemd[1]: Reload requested from client PID 3313 ('.switch-to-conf') (unit backdoor.service)... machine # [ 85.155454] systemd[1]: Reloading... machine # [ 85.782442] systemd[1]: Reloading finished in 624 ms. machine # [ 85.935761] systemd[603]: Reexecution requested from client PID 3382 ('switch-to-confi')... machine # [ 85.939881] systemd[603]: Reexecuting. machine # [ 86.006689] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 86.206409] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 86.213968] systemd[603]: Reload requested from client PID 3382 ('switch-to-confi')... machine # [ 86.216749] systemd[603]: Reloading... machine # [ 86.400069] systemd[603]: Reloading finished in 182 ms. machine # [ 86.416185] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 86.425808] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 86.428141] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 86.431414] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 86.480600] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 86.485356] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 86.489890] systemd[1]: Stopping Reactivate sysinit units... machine # [ 86.491164] systemd[1]: Reached target Reactivate sysinit units. machine # [ 86.493805] systemd[1]: Reloading -.service... machine # [ 86.533546] systemd[1]: Reloaded -.service. machine # [ 86.537397] systemd[1]: Reached target Local File Systems. machine # [ 86.542340] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 86.550011] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 86.551026] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 86.557157] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 86.562987] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 86.568415] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 86.569712] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 86.570868] systemd[1]: Reached target Remote File Systems. machine # [ 86.571652] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 86.574277] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 86.901111] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 86.905742] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 86.908508] systemd[1]: Starting linger-users.service... machine # [ 86.967562] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 86.969904] systemd[1]: Finished linger-users.service. machine # [ 87.227734] nixos[3313]: finished switching to system configuration /nix/store/ypy2c8qpa44q68lbarp4knj20czy30k3-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&1, in 3.23 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&1 machine # [ 87.861473] nixos[3455]: switching to system configuration /nix/store/80pg31aaqmvzfy003blm6b5b4mbq7n4s-nixos-system-machine-test machine # [ 87.865426] systemd[1]: Stopped target Local File Systems. machine # [ 87.867136] systemd[1]: Stopped target Remote File Systems. machine # [ 87.867991] systemd[1]: -.service: Deactivated successfully. machine # [ 87.869600] systemd[1]: Stopped -.service. machine # [ 88.367536] systemd[1]: Reload requested from client PID 3455 ('.switch-to-conf') (unit backdoor.service)... machine # [ 88.369619] systemd[1]: Reloading... machine # [ 89.013111] systemd[1]: Reloading finished in 641 ms. machine # [ 89.156429] systemd[603]: Reexecution requested from client PID 3523 ('switch-to-confi')... machine # [ 89.159910] systemd[603]: Reexecuting. machine # [ 89.222559] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 89.413696] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 89.420436] systemd[603]: Reload requested from client PID 3523 ('switch-to-confi')... machine # [ 89.422705] systemd[603]: Reloading... machine # [ 89.619965] systemd[603]: Reloading finished in 196 ms. machine # [ 89.636176] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 89.645698] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 89.648066] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 89.650869] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 89.698028] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 89.702244] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 89.704684] systemd[1]: Stopping Reactivate sysinit units... machine # [ 89.705575] systemd[1]: Reached target Reactivate sysinit units. machine # [ 89.706392] systemd[1]: Reached target Remote File Systems. machine # [ 89.709166] systemd[1]: Reached target Local File Systems. machine # [ 89.711689] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 89.719248] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 89.720577] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 89.725224] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 89.727387] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 89.729832] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 89.736349] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 89.737552] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 89.738770] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 90.035027] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 90.037351] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 90.043164] systemd[1]: Starting linger-users.service... machine # [ 90.047051] systemd[1]: Starting required-service.service... machine # [ 90.050125] systemd[1]: Starting test-service.service... machine # [ 90.098081] systemd[1]: Finished test-service.service. machine # [ 90.103441] systemd[1]: Finished required-service.service. machine # [ 90.131579] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 90.134426] systemd[1]: Finished linger-users.service. machine # [ 90.394002] nixos[3455]: finished switching to system configuration /nix/store/80pg31aaqmvzfy003blm6b5b4mbq7n4s-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&1, in 3.16 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&1 machine # [ 91.050470] nixos[3599]: switching to system configuration /nix/store/3gd7mnncw2zpczkn8sxl3br68zg81ks8-nixos-system-machine-test machine # [ 91.054592] systemd[1]: Stopped target Local File Systems. machine # [ 91.056720] systemd[1]: Stopped target Remote File Systems. machine # [ 91.057608] systemd[1]: required-service.service: Deactivated successfully. machine # [ 91.058535] systemd[1]: Stopped required-service.service. machine # [ 91.059283] systemd[1]: test-service.service: Deactivated successfully. machine # [ 91.061058] systemd[1]: Stopped test-service.service. machine # [ 91.600240] systemd[1]: Reload requested from client PID 3599 ('.switch-to-conf') (unit backdoor.service)... machine # [ 91.602313] systemd[1]: Reloading... machine # [ 92.299144] systemd[1]: Reloading finished in 693 ms. machine # [ 92.447559] systemd[603]: Reexecution requested from client PID 3668 ('switch-to-confi')... machine # [ 92.451312] systemd[603]: Reexecuting. machine # [ 92.514037] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 92.707981] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 92.714586] systemd[603]: Reload requested from client PID 3668 ('switch-to-confi')... machine # [ 92.717204] systemd[603]: Reloading... machine # [ 92.905585] systemd[603]: Reloading finished in 187 ms. machine # [ 92.920188] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 92.931322] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 92.933960] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 92.937038] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 92.989284] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 92.993985] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 92.996250] systemd[1]: Stopping Reactivate sysinit units... machine # [ 92.997162] systemd[1]: Reached target Reactivate sysinit units. machine # [ 93.002613] systemd[1]: Reached target Remote File Systems. machine # [ 93.009808] systemd[1]: Starting required-service.service... machine # [ 93.018359] systemd[1]: Reached target Local File Systems. machine # [ 93.019352] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 93.027156] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 93.030383] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 93.031723] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 93.044209] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 93.045827] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 93.047008] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 93.051852] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 93.053999] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 93.080716] systemd[1]: Finished required-service.service. machine # [ 93.367800] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 93.373033] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 93.376310] systemd[1]: Starting linger-users.service... machine # [ 93.380562] systemd[1]: Starting test-service.service... machine # [ 93.427715] systemd[1]: Finished test-service.service. machine # [ 93.453152] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 93.455708] systemd[1]: Finished linger-users.service. machine # [ 93.714870] nixos[3599]: finished switching to system configuration /nix/store/3gd7mnncw2zpczkn8sxl3br68zg81ks8-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&1, in 3.32 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&1 machine # [ 94.351844] nixos[3743]: switching to system configuration /nix/store/5hv4apzv6d1d0i2w9wccz3rr7jzijmzh-nixos-system-machine-test machine # [ 94.355816] systemd[1]: Stopped target Local File Systems. machine # [ 94.357818] systemd[1]: Stopped target Remote File Systems. machine # [ 94.358648] systemd[1]: required-service.service: Deactivated successfully. machine # [ 94.359615] systemd[1]: Stopped required-service.service. machine # [ 94.361315] systemd[1]: test-service.service: Deactivated successfully. machine # [ 94.362380] systemd[1]: Stopped test-service.service. machine # [ 94.880412] systemd[1]: Reload requested from client PID 3743 ('.switch-to-conf') (unit backdoor.service)... machine # [ 94.882325] systemd[1]: Reloading... machine # [ 95.540789] systemd[1]: Reloading finished in 656 ms. machine # [ 95.689817] systemd[603]: Reexecution requested from client PID 3812 ('switch-to-confi')... machine # [ 95.691240] systemd[603]: Reexecuting. machine # [ 95.754081] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 95.950112] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 95.956694] systemd[603]: Reload requested from client PID 3812 ('switch-to-confi')... machine # [ 95.958964] systemd[603]: Reloading... machine # [ 96.149211] systemd[603]: Reloading finished in 188 ms. machine # [ 96.164157] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 96.174234] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 96.176631] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 96.179530] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 96.224819] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 96.229090] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 96.232271] systemd[1]: Stopping Reactivate sysinit units... machine # [ 96.234919] systemd[1]: Reached target Reactivate sysinit units. machine # [ 96.235810] systemd[1]: Reached target Local File Systems. machine # [ 96.237720] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 96.238987] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 96.242734] systemd[1]: Reached target Remote File Systems. machine # [ 96.248196] systemd[1]: Starting linger-users.service... machine # [ 96.252195] systemd[1]: Starting required-service.service... machine # [ 96.255465] systemd[1]: Starting test-service.service... machine # [ 96.264332] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 96.275518] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 96.287170] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 96.291437] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 96.297056] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 96.300651] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 96.305597] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 96.345539] systemd[1]: Finished required-service.service. machine # [ 96.349993] systemd[1]: Finished test-service.service. machine # [ 96.389566] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 96.393386] systemd[1]: Finished linger-users.service. machine # [ 96.672952] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 96.676902] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 96.934891] nixos[3743]: finished switching to system configuration /nix/store/5hv4apzv6d1d0i2w9wccz3rr7jzijmzh-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&1, in 3.22 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&1 machine # [ 97.556512] nixos[3887]: switching to system configuration /nix/store/1lh7v1b2cdaxkjg9ksbk35h53lmkw0nf-nixos-system-machine-test machine # [ 97.559362] systemd[1]: Stopped target Local File Systems. machine # [ 97.562853] systemd[1]: required-service.service: Deactivated successfully. machine # [ 97.563847] systemd[1]: Stopped required-service.service. machine # [ 97.565694] systemd[1]: test-service.service: Deactivated successfully. machine # [ 97.566596] systemd[1]: Stopped test-service.service. machine # [ 97.567302] systemd[1]: Stopped target Remote File Systems. machine # [ 98.084656] systemd[1]: Reload requested from client PID 3887 ('.switch-to-conf') (unit backdoor.service)... machine # [ 98.086585] systemd[1]: Reloading... machine # [ 98.748233] systemd[1]: Reloading finished in 659 ms. machine # [ 98.892399] systemd[603]: Reexecution requested from client PID 3956 ('switch-to-confi')... machine # [ 98.893827] systemd[603]: Reexecuting. machine # [ 98.962514] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 99.162767] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 99.169530] systemd[603]: Reload requested from client PID 3956 ('switch-to-confi')... machine # [ 99.172098] systemd[603]: Reloading... machine # [ 99.362092] systemd[603]: Reloading finished in 189 ms. machine # [ 99.376152] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 99.386108] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 99.387161] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 99.390008] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 99.438292] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 99.442453] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 99.444585] systemd[1]: Stopping Reactivate sysinit units... machine # [ 99.445841] systemd[1]: Reached target Reactivate sysinit units. machine # [ 99.449797] systemd[1]: Created slice Slice /system/instantiated. machine # [ 99.452332] systemd[1]: Reached target Remote File Systems. machine # [ 99.456266] systemd[1]: Starting instantiated@one.service... machine # [ 99.460277] systemd[1]: Starting instantiated@two.service... machine # [ 99.465923] systemd[1]: Starting linger-users.service... machine # [ 99.486515] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 99.487913] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 99.491682] systemd[1]: Reached target Local File Systems. machine # [ 99.493662] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 99.502538] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 99.505391] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 99.507542] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 99.511452] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 99.516537] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 99.520503] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 99.553723] systemd[1]: Finished instantiated@two.service. machine # [ 99.556846] systemd[1]: Finished instantiated@one.service. machine # [ 99.593653] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 99.596661] systemd[1]: Finished linger-users.service. machine # [ 99.886422] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 99.889918] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 100.149009] nixos[3887]: finished switching to system configuration /nix/store/1lh7v1b2cdaxkjg9ksbk35h53lmkw0nf-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&1, in 3.21 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&1 machine # [ 100.839812] nixos[4031]: switching to system configuration /nix/store/m3kwbblaadkysx03z5pd2ngpsjc5x4hs-nixos-system-machine-test machine # [ 100.844524] systemd[1]: Stopped target Local File Systems. machine # [ 100.847706] systemd[1]: Stopped target Remote File Systems. machine # [ 100.849170] systemd[1]: instantiated@one.service: Deactivated successfully. machine # [ 100.850485] systemd[1]: Stopped instantiated@one.service. machine # [ 100.851544] systemd[1]: instantiated@two.service: Deactivated successfully. machine # [ 100.852958] systemd[1]: Stopped instantiated@two.service. machine # [ 101.399060] systemd[1]: Reload requested from client PID 4031 ('.switch-to-conf') (unit backdoor.service)... machine # [ 101.401349] systemd[1]: Reloading... machine # [ 102.100488] systemd[1]: Reloading finished in 696 ms. machine # [ 102.252721] systemd[603]: Reexecution requested from client PID 4100 ('switch-to-confi')... machine # [ 102.255694] systemd[603]: Reexecuting. machine # [ 102.318217] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 102.513420] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 102.520448] systemd[603]: Reload requested from client PID 4100 ('switch-to-confi')... machine # [ 102.522280] systemd[603]: Reloading... machine # [ 102.714973] systemd[603]: Reloading finished in 191 ms. machine # [ 102.728159] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 102.738282] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 102.739299] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 102.742152] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 102.790412] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 102.794983] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 102.796533] systemd[1]: Stopping Reactivate sysinit units... machine # [ 102.797413] systemd[1]: Reached target Reactivate sysinit units. machine # [ 102.799629] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 102.802609] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 102.805639] systemd[1]: Reached target Remote File Systems. machine # [ 102.811368] systemd[1]: Starting instantiated@one.service... machine # [ 102.815255] systemd[1]: Starting instantiated@two.service... machine # [ 102.819077] systemd[1]: Starting linger-users.service... machine # [ 102.833250] systemd[1]: Reached target Local File Systems. machine # [ 102.839994] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 102.845703] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 102.846603] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 102.847849] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 102.860612] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 102.865154] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 102.869771] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 102.902232] systemd[1]: Finished instantiated@one.service. machine # [ 102.907945] systemd[1]: Finished instantiated@two.service. machine # [ 102.940454] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 102.943627] systemd[1]: Finished linger-users.service. machine # [ 103.206047] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 103.209150] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 103.466962] nixos[4031]: finished switching to system configuration /nix/store/m3kwbblaadkysx03z5pd2ngpsjc5x4hs-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&1, in 3.32 seconds) (finished: subtest: services, in 75.00 seconds) subtest: failing units machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 104.050203] nixos[4175]: switching to system configuration /nix/store/34wr58kgh4jvxi46ql47qackvp2l37vf-nixos-system-machine-test machine # [ 104.053653] systemd[1]: instantiated@one.service: Deactivated successfully. machine # [ 104.054688] systemd[1]: Stopped instantiated@one.service. machine # [ 104.056378] systemd[1]: instantiated@two.service: Deactivated successfully. machine # [ 104.058818] systemd[1]: Stopped instantiated@two.service. machine # [ 104.059654] systemd[1]: Stopped target Remote File Systems. machine # [ 104.061710] systemd[1]: Stopped target Local File Systems. machine # [ 104.509135] systemd[1]: Reload requested from client PID 4175 ('.switch-to-conf') (unit backdoor.service)... machine # [ 104.511128] systemd[1]: Reloading... machine # [ 105.099798] systemd[1]: Reloading finished in 586 ms. machine # [ 105.242652] systemd[603]: Reexecution requested from client PID 4244 ('switch-to-confi')... machine # [ 105.246434] systemd[603]: Reexecuting. machine # [ 105.310374] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 105.505781] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 105.512881] systemd[603]: Reload requested from client PID 4244 ('switch-to-confi')... machine # [ 105.515420] systemd[603]: Reloading... machine # [ 105.713771] systemd[603]: Reloading finished in 197 ms. machine # [ 105.728173] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 105.738826] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 105.741506] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 105.744933] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 105.794356] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 105.798896] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 105.801492] systemd[1]: Stopping Reactivate sysinit units... machine # [ 105.802402] systemd[1]: Reached target Reactivate sysinit units. machine # [ 105.805312] systemd[1]: Reached target Local File Systems. machine # [ 105.809184] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 105.813695] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 105.816460] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 105.817667] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 105.819830] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 105.821771] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 105.830535] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 105.831769] systemd[1]: Reached target Remote File Systems. machine # [ 105.835355] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 105.839225] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 106.139892] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 106.144571] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 106.148478] systemd[1]: Starting linger-users.service... machine # [ 106.151573] systemd[1]: Starting test.service... machine # [ 106.197031] systemd[1]: Finished test.service. machine # [ 106.224847] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 106.227122] systemd[1]: Finished linger-users.service. machine # [ 106.486370] nixos[4175]: finished switching to system configuration /nix/store/34wr58kgh4jvxi46ql47qackvp2l37vf-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 3.02 seconds) machine: must fail: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 107.141265] nixos[4317]: switching to system configuration /nix/store/8bh94w6vlxfl8c720pvr4s3ngam70l4f-nixos-system-machine-test machine # [ 107.145903] systemd[1]: Stopped target Local File Systems. machine # [ 107.148867] systemd[1]: Stopped target Remote File Systems. machine # [ 107.149987] systemd[1]: test.service: Deactivated successfully. machine # [ 107.150931] systemd[1]: Stopped test.service. machine # [ 107.658149] systemd[1]: Reload requested from client PID 4317 ('.switch-to-conf') (unit backdoor.service)... machine # [ 107.660204] systemd[1]: Reloading... machine # [ 108.293291] systemd[1]: Reloading finished in 630 ms. machine # [ 108.437610] systemd[603]: Reexecution requested from client PID 4386 ('switch-to-confi')... machine # [ 108.441799] systemd[603]: Reexecuting. machine # [ 108.506048] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 108.701066] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 108.708470] systemd[603]: Reload requested from client PID 4386 ('switch-to-confi')... machine # [ 108.711013] systemd[603]: Reloading... machine # [ 108.902128] systemd[603]: Reloading finished in 190 ms. machine # [ 108.916165] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 108.925572] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 108.927944] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 108.930620] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 108.977207] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 108.981196] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 108.983626] systemd[1]: Stopping Reactivate sysinit units... machine # [ 108.985205] systemd[1]: Reached target Reactivate sysinit units. machine # [ 108.989585] systemd[1]: Reached target Local File Systems. machine # [ 108.992158] systemd[1]: Reached target Remote File Systems. machine # [ 108.999011] systemd[1]: Starting linger-users.service... machine # [ 109.001365] systemd[1]: Starting test.service... machine # [ 109.010510] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 109.020549] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 109.021885] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 109.027221] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 109.030160] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 109.031602] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 109.035864] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 109.040443] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 109.044786] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 109.074347] systemd[1]: test.service: Main process exited, code=exited, status=1/FAILURE machine # [ 109.075761] systemd[1]: test.service: Failed with result 'exit-code'. machine # [ 109.079507] systemd[1]: Failed to start test.service. machine # [ 109.112279] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 109.115226] systemd[1]: Finished linger-users.service. machine # [ 109.393822] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 109.397055] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 109.710854] nixos[4317]: switching to system configuration /nix/store/8bh94w6vlxfl8c720pvr4s3ngam70l4f-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&1, in 3.22 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1 machine # [ 110.332716] nixos[4460]: switching to system configuration /nix/store/scxwdiaxg8wl6zciq16ch5f1kprp9hx9-nixos-system-machine-test machine # [ 110.335471] systemd[1]: Stopped target Local File Systems. machine # [ 110.337204] systemd[1]: Stopped target Remote File Systems. machine # [ 110.843708] systemd[1]: Reload requested from client PID 4460 ('.switch-to-conf') (unit backdoor.service)... machine # [ 110.845829] systemd[1]: Reloading... machine # [ 111.522308] systemd[1]: Reloading finished in 674 ms. machine # [ 111.668429] systemd[603]: Reexecution requested from client PID 4529 ('switch-to-confi')... machine # [ 111.669822] systemd[603]: Reexecuting. machine # [ 111.734294] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 111.937587] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 111.943907] systemd[603]: Reload requested from client PID 4529 ('switch-to-confi')... machine # [ 111.946814] systemd[603]: Reloading... machine # [ 112.142270] systemd[603]: Reloading finished in 194 ms. machine # [ 112.156204] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 112.165984] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 112.168854] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 112.171864] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 112.220276] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 112.224884] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 112.227016] systemd[1]: Stopping Reactivate sysinit units... machine # [ 112.228932] systemd[1]: Reached target Reactivate sysinit units. machine # [ 112.233602] systemd[1]: Reached target Local File Systems. machine # [ 112.236803] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 112.241768] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 112.242669] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 112.243950] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 112.248655] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 112.254486] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 112.261491] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 112.262732] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 112.266615] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 112.267938] systemd[1]: Reached target Remote File Systems. machine # [ 112.591653] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 112.596219] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 112.600493] systemd[1]: Started autorestart.service. machine # [ 112.604773] systemd[1]: Starting linger-users.service... machine # [ 112.649790] systemd[1]: autorestart.service: Deactivated successfully. machine # [ 112.679055] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 112.681358] systemd[1]: Finished linger-users.service. machine # [ 112.942320] nixos[4460]: finished switching to system configuration /nix/store/scxwdiaxg8wl6zciq16ch5f1kprp9hx9-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 3.23 seconds) machine # [ 113.038589] systemd[1]: Stopped autorestart.service. machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1 machine # [ 113.619062] nixos[4607]: switching to system configuration /nix/store/scxwdiaxg8wl6zciq16ch5f1kprp9hx9-nixos-system-machine-test machine # [ 113.623005] systemd[1]: Stopped target Local File Systems. machine # [ 113.624147] systemd[1]: Stopped target Remote File Systems. machine # [ 114.101496] systemd[1]: Reload requested from client PID 4607 ('.switch-to-conf') (unit backdoor.service)... machine # [ 114.103581] systemd[1]: Reloading... machine # [ 114.718894] systemd[1]: Reloading finished in 612 ms. machine # [ 114.863430] systemd[603]: Reexecution requested from client PID 4676 ('switch-to-confi')... machine # [ 114.865730] systemd[603]: Reexecuting. machine # [ 114.917702] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 115.109447] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 115.116726] systemd[603]: Reload requested from client PID 4676 ('switch-to-confi')... machine # [ 115.119375] systemd[603]: Reloading... machine # [ 115.306309] systemd[603]: Reloading finished in 185 ms. machine # [ 115.320186] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 115.329306] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 115.331684] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 115.334441] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 115.381389] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 115.385441] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 115.388631] systemd[1]: Stopping Reactivate sysinit units... machine # [ 115.389491] systemd[1]: Reached target Reactivate sysinit units. machine # [ 115.390357] systemd[1]: Reached target Local File Systems. machine # [ 115.394859] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 115.402327] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 115.405002] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 115.408781] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 115.412463] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 115.420401] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 115.421708] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 115.422867] systemd[1]: Reached target Remote File Systems. machine # [ 115.423665] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 115.426239] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 115.737402] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 115.739507] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 115.744582] systemd[1]: Started autorestart.service. machine # [ 115.749225] systemd[1]: Starting linger-users.service... machine # [ 115.794054] systemd[1]: autorestart.service: Deactivated successfully. machine # [ 115.820759] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 115.823384] systemd[1]: Finished linger-users.service. machine # [ 116.084673] nixos[4607]: finished switching to system configuration /nix/store/scxwdiaxg8wl6zciq16ch5f1kprp9hx9-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 3.05 seconds) machine # [ 116.170225] systemd[1]: Stopped autorestart.service. machine: must fail: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 116.785135] nixos[4754]: switching to system configuration /nix/store/r68wy6m6ibyl1qb75bp56wxfxzrnv0rx-nixos-system-machine-test machine # [ 116.787791] systemd[1]: Stopped target Local File Systems. machine # [ 116.790238] systemd[1]: Stopped target Remote File Systems. machine # [ 117.329070] systemd[1]: Reload requested from client PID 4754 ('.switch-to-conf') (unit backdoor.service)... machine # [ 117.331102] systemd[1]: Reloading... machine # [ 118.090241] systemd[1]: Reloading finished in 756 ms. machine # [ 118.240569] systemd[603]: Reexecution requested from client PID 4823 ('switch-to-confi')... machine # [ 118.242063] systemd[603]: Reexecuting. machine # [ 118.311325] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 118.522589] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 118.529410] systemd[603]: Reload requested from client PID 4823 ('switch-to-confi')... machine # [ 118.532357] systemd[603]: Reloading... machine # [ 118.735497] systemd[603]: Reloading finished in 202 ms. machine # [ 118.748189] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 118.758575] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 118.761315] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 118.765116] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 118.818806] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 118.823342] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 118.826210] systemd[1]: Stopping Reactivate sysinit units... machine # [ 118.827099] systemd[1]: Reached target Reactivate sysinit units. machine # [ 118.829702] systemd[1]: Reached target Local File Systems. machine # [ 118.833685] systemd[1]: Reached target Remote File Systems. machine # [ 118.840412] systemd[1]: Started autorestart.service. machine # [ 118.844460] systemd[1]: Starting linger-users.service... machine # [ 118.856178] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 118.857588] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 118.867026] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 118.871626] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 118.881625] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 118.882916] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 118.891596] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 118.895911] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 118.898171] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 118.928235] systemd[1]: autorestart.service: Main process exited, code=exited, status=1/FAILURE machine # [ 118.929683] systemd[1]: autorestart.service: Failed with result 'exit-code'. machine # [ 118.969798] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 118.973711] systemd[1]: Finished linger-users.service. machine # [ 119.277067] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 119.280399] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 119.586507] nixos[4754]: switching to system configuration /nix/store/r68wy6m6ibyl1qb75bp56wxfxzrnv0rx-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&1, in 3.41 seconds) (finished: subtest: failing units, in 16.11 seconds) subtest: unit file parser machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 120.172487] nixos[4897]: switching to system configuration /nix/store/21hfw8sh6q8izq23jfphrq020id5dlkc-nixos-system-machine-test machine # [ 120.174993] systemd[1]: Stopped target Remote File Systems. machine # [ 120.178334] systemd[1]: Stopped target Local File Systems. machine # [ 120.179397] systemd[1]: Stopped autorestart.service. machine # [ 120.651801] systemd[1]: Reload requested from client PID 4897 ('.switch-to-conf') (unit backdoor.service)... machine # [ 120.654023] systemd[1]: Reloading... machine # [ 121.273027] systemd[1]: Reloading finished in 617 ms. machine # [ 121.428555] systemd[603]: Reexecution requested from client PID 4966 ('switch-to-confi')... machine # [ 121.429952] systemd[603]: Reexecuting. machine # [ 121.478270] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 121.669608] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 121.677137] systemd[603]: Reload requested from client PID 4966 ('switch-to-confi')... machine # [ 121.678507] systemd[603]: Reloading... machine # [ 121.863827] systemd[603]: Reloading finished in 183 ms. machine # [ 121.880164] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 121.892091] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 121.893139] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 121.896517] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 121.941317] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 121.946951] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 121.950600] systemd[1]: Stopping Reactivate sysinit units... machine # [ 121.952953] systemd[1]: Reached target Reactivate sysinit units. machine # [ 121.953836] systemd[1]: Reached target Remote File Systems. machine # [ 121.956964] systemd[1]: Reached target Local File Systems. machine # [ 121.960534] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 121.965156] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 121.966092] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 121.967308] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 121.971668] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 121.983890] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 121.985503] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 121.986679] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 121.987944] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 122.297163] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 122.302164] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 122.306118] systemd[1]: Starting linger-users.service... machine # [ 122.309276] systemd[1]: Starting test.service... machine # [ 122.352860] systemd[1]: Finished test.service. machine # [ 122.382558] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 122.384534] systemd[1]: Finished linger-users.service. machine # [ 122.644886] nixos[4897]: finished switching to system configuration /nix/store/21hfw8sh6q8izq23jfphrq020id5dlkc-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 3.06 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&1 machine # [ 123.282256] nixos[5039]: switching to system configuration /nix/store/xsn0z04ayhiar0cg6zbial6vcy5mpmfq-nixos-system-machine-test machine # [ 123.285568] systemd[1]: Stopped target Local File Systems. machine # [ 123.287028] systemd[1]: Stopped target Remote File Systems. machine # [ 123.802262] systemd[1]: Reload requested from client PID 5039 ('.switch-to-conf') (unit backdoor.service)... machine # [ 123.804299] systemd[1]: Reloading... machine # [ 124.448760] systemd[1]: Reloading finished in 642 ms. machine # [ 124.611655] systemd[603]: Reexecution requested from client PID 5107 ('switch-to-confi')... machine # [ 124.615474] systemd[603]: Reexecuting. machine # [ 124.669895] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 124.870883] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 124.877552] systemd[603]: Reload requested from client PID 5107 ('switch-to-confi')... machine # [ 124.880131] systemd[603]: Reloading... machine # [ 125.071153] systemd[603]: Reloading finished in 189 ms. machine # [ 125.089640] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 125.097796] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 125.100246] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 125.103236] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 125.149742] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 125.153930] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 125.155802] systemd[1]: Stopping Reactivate sysinit units... machine # [ 125.157450] systemd[1]: Reached target Reactivate sysinit units. machine # [ 125.160506] systemd[1]: test.service: Deactivated successfully. machine # [ 125.161450] systemd[1]: Stopped test.service. machine # [ 125.162079] systemd[1]: Stopping test.service... machine # [ 125.165204] systemd[1]: Starting test.service... machine # [ 125.204228] systemd[1]: Finished test.service. machine # [ 125.206721] systemd[1]: Reached target Remote File Systems. machine # [ 125.211701] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 125.214377] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 125.220479] systemd[1]: Starting linger-users.service... machine # [ 125.222889] systemd[1]: Reached target Local File Systems. machine # [ 125.231244] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 125.238362] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 125.244246] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 125.245520] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 125.247653] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 125.251756] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 125.254847] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 125.316269] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 125.319046] systemd[1]: Finished linger-users.service. machine # [ 125.588197] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 125.591389] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 125.851325] nixos[5039]: finished switching to system configuration /nix/store/xsn0z04ayhiar0cg6zbial6vcy5mpmfq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&1, in 3.21 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&1 machine # [ 126.516330] nixos[5181]: switching to system configuration /nix/store/p27bhc8j4brvys48m2a3aglzryghb3ys-nixos-system-machine-test machine # [ 126.518608] systemd[1]: Stopped target Remote File Systems. machine # [ 126.520936] systemd[1]: Stopped target Local File Systems. machine # [ 127.033229] systemd[1]: Reload requested from client PID 5181 ('.switch-to-conf') (unit backdoor.service)... machine # [ 127.035289] systemd[1]: Reloading... machine # [ 127.686320] systemd[1]: Reloading finished in 648 ms. machine # [ 127.848467] systemd[603]: Reexecution requested from client PID 5250 ('switch-to-confi')... machine # [ 127.851722] systemd[603]: Reexecuting. machine # [ 127.918024] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 128.124248] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 128.131420] systemd[603]: Reload requested from client PID 5250 ('switch-to-confi')... machine # [ 128.134784] systemd[603]: Reloading... machine # [ 128.321943] systemd[603]: Reloading finished in 188 ms. machine # [ 128.336154] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 128.346916] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 128.347968] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 128.351021] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 128.397237] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 128.401457] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 128.403181] systemd[1]: Stopping Reactivate sysinit units... machine # [ 128.404761] systemd[1]: Reached target Reactivate sysinit units. machine # [ 128.407793] systemd[1]: test.service: Deactivated successfully. machine # [ 128.409113] systemd[1]: Stopped test.service. machine # [ 128.409923] systemd[1]: Stopping test.service... machine # [ 128.411791] systemd[1]: Starting test.service... machine # [ 128.446418] systemd[1]: Finished test.service. machine # [ 128.450476] systemd[1]: Reached target Remote File Systems. machine # [ 128.455218] systemd[1]: Starting linger-users.service... machine # [ 128.462135] systemd[1]: Reached target Local File Systems. machine # [ 128.467114] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 128.468877] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 128.473742] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 128.478215] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 128.479089] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 128.484731] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 128.488512] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 128.492184] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 128.495321] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 128.551530] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 128.554495] systemd[1]: Finished linger-users.service. machine # [ 128.818698] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 128.821804] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 129.078412] nixos[5181]: finished switching to system configuration /nix/store/p27bhc8j4brvys48m2a3aglzryghb3ys-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&1, in 3.23 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 129.668386] nixos[5323]: switching to system configuration /nix/store/21hfw8sh6q8izq23jfphrq020id5dlkc-nixos-system-machine-test machine # [ 129.671098] systemd[1]: Stopped target Local File Systems. machine # [ 129.673127] systemd[1]: Stopped target Remote File Systems. machine # [ 130.130789] systemd[1]: Reload requested from client PID 5323 ('.switch-to-conf') (unit backdoor.service)... machine # [ 130.133073] systemd[1]: Reloading... machine # [ 130.743922] systemd[1]: Reloading finished in 608 ms. machine # [ 130.888399] systemd[603]: Reexecution requested from client PID 5392 ('switch-to-confi')... machine # [ 130.891545] systemd[603]: Reexecuting. machine # [ 130.954428] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 131.148141] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 131.154536] systemd[603]: Reload requested from client PID 5392 ('switch-to-confi')... machine # [ 131.156886] systemd[603]: Reloading... machine # [ 131.349803] systemd[603]: Reloading finished in 191 ms. machine # [ 131.364147] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 131.374334] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 131.376718] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 131.379644] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 131.426209] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 131.430206] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 131.432146] systemd[1]: Stopping Reactivate sysinit units... machine # [ 131.433418] systemd[1]: Reached target Reactivate sysinit units. machine # [ 131.436389] systemd[1]: test.service: Deactivated successfully. machine # [ 131.437315] systemd[1]: Stopped test.service. machine # [ 131.437967] systemd[1]: Stopping test.service... machine # [ 131.440865] systemd[1]: Starting test.service... machine # [ 131.478550] systemd[1]: Finished test.service. machine # [ 131.483235] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 131.485361] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 131.489685] systemd[1]: Reached target Remote File Systems. machine # [ 131.494229] systemd[1]: Starting linger-users.service... machine # [ 131.502627] systemd[1]: Reached target Local File Systems. machine # [ 131.503512] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 131.510255] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 131.514473] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 131.515784] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 131.525392] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 131.527295] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 131.529036] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 131.588895] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 131.591246] systemd[1]: Finished linger-users.service. machine # [ 131.869125] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 131.872249] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 132.129106] nixos[5323]: finished switching to system configuration /nix/store/21hfw8sh6q8izq23jfphrq020id5dlkc-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 3.05 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&1 machine # [ 132.781370] nixos[5465]: switching to system configuration /nix/store/z6jgzw49zw88agvnfkhljbjzy7lf0q89-nixos-system-machine-test machine # [ 132.785160] systemd[1]: Stopped target Local File Systems. machine # [ 132.786710] systemd[1]: Stopped target Remote File Systems. machine # [ 133.291399] systemd[1]: Reload requested from client PID 5465 ('.switch-to-conf') (unit backdoor.service)... machine # [ 133.293547] systemd[1]: Reloading... machine # [ 133.932381] systemd[1]: Reloading finished in 637 ms. machine # [ 134.096481] systemd[603]: Reexecution requested from client PID 5534 ('switch-to-confi')... machine # [ 134.097879] systemd[603]: Reexecuting. machine # [ 134.153650] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 134.346809] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 134.353116] systemd[603]: Reload requested from client PID 5534 ('switch-to-confi')... machine # [ 134.355329] systemd[603]: Reloading... machine # [ 134.539455] systemd[603]: Reloading finished in 182 ms. machine # [ 134.552142] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 134.561556] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 134.563720] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 134.566491] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 134.612705] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 134.617008] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 134.618850] systemd[1]: Stopping Reactivate sysinit units... machine # [ 134.620467] systemd[1]: Reached target Reactivate sysinit units. machine # [ 134.622037] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 134.623375] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 134.627012] systemd[1]: Reached target Local File Systems. machine # [ 134.631734] systemd[1]: Reached target Remote File Systems. machine # [ 134.636867] systemd[1]: Starting linger-users.service... machine # [ 134.640833] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 134.644572] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 134.649321] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 134.659203] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 134.661661] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 134.663224] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 134.664624] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 134.727712] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 134.730546] systemd[1]: Finished linger-users.service. machine # [ 134.992649] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 134.995989] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 135.252896] nixos[5465]: finished switching to system configuration /nix/store/z6jgzw49zw88agvnfkhljbjzy7lf0q89-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&1, in 3.12 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&1 machine # [ 135.871578] nixos[5605]: switching to system configuration /nix/store/27c6n9kvxyrqnssprif04y25llqxa5mh-nixos-system-machine-test machine # [ 135.875597] systemd[1]: Stopped target Local File Systems. machine # [ 135.876788] systemd[1]: Stopped target Remote File Systems. machine # [ 136.359213] systemd[1]: Reload requested from client PID 5605 ('.switch-to-conf') (unit backdoor.service)... machine # [ 136.361368] systemd[1]: Reloading... machine # [ 137.003422] systemd[1]: Reloading finished in 640 ms. machine # [ 137.147600] systemd[603]: Reexecution requested from client PID 5674 ('switch-to-confi')... machine # [ 137.151398] systemd[603]: Reexecuting. machine # [ 137.201311] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 137.392629] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 137.398940] systemd[603]: Reload requested from client PID 5674 ('switch-to-confi')... machine # [ 137.401472] systemd[603]: Reloading... machine # [ 137.584951] systemd[603]: Reloading finished in 182 ms. machine # [ 137.596175] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 137.605785] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 137.608091] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 137.610911] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 137.656219] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 137.659974] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 137.661106] systemd[1]: Stopping Reactivate sysinit units... machine # [ 137.662735] systemd[1]: Reached target Reactivate sysinit units. machine # [ 137.666397] systemd[1]: test.service: Deactivated successfully. machine # [ 137.667627] systemd[1]: Stopped test.service. machine # [ 137.668490] systemd[1]: Stopping test.service... machine # [ 137.670167] systemd[1]: Starting test.service... machine # [ 137.702559] systemd[1]: Finished test.service. machine # [ 137.705640] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 137.710589] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 137.712405] systemd[1]: Reached target Remote File Systems. machine # [ 137.714637] systemd[1]: Starting linger-users.service... machine # [ 137.725515] systemd[1]: Reached target Local File Systems. machine # [ 137.729897] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 137.736483] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 137.737346] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 137.738542] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 137.741460] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 137.743602] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 137.745613] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 137.806303] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 137.809094] systemd[1]: Finished linger-users.service. machine # [ 138.087666] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 138.090968] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 138.347602] nixos[5605]: finished switching to system configuration /nix/store/27c6n9kvxyrqnssprif04y25llqxa5mh-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&1, in 3.09 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&1 machine # [ 138.998751] nixos[5747]: switching to system configuration /nix/store/64ciqw9gp4w8v58cy3j35anc4q14icha-nixos-system-machine-test machine # [ 139.001898] systemd[1]: Stopped target Local File Systems. machine # [ 139.003271] systemd[1]: Stopped target Remote File Systems. machine # [ 139.507069] systemd[1]: Reload requested from client PID 5747 ('.switch-to-conf') (unit backdoor.service)... machine # [ 139.509369] systemd[1]: Reloading... machine # [ 140.177671] systemd[1]: Reloading finished in 666 ms. machine # [ 140.320425] systemd[603]: Reexecution requested from client PID 5816 ('switch-to-confi')... machine # [ 140.323980] systemd[603]: Reexecuting. machine # [ 140.376973] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 140.565463] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 140.571750] systemd[603]: Reload requested from client PID 5816 ('switch-to-confi')... machine # [ 140.574346] systemd[603]: Reloading... machine # [ 140.760154] systemd[603]: Reloading finished in 184 ms. machine # [ 140.776138] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 140.785659] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 140.786639] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 140.789429] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 140.835039] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 140.838983] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 140.840905] systemd[1]: Stopping Reactivate sysinit units... machine # [ 140.844465] systemd[1]: Reached target Reactivate sysinit units. machine # [ 140.845729] systemd[1]: test.service: Deactivated successfully. machine # [ 140.847718] systemd[1]: Stopped test.service. machine # [ 140.848626] systemd[1]: Stopping test.service... machine # [ 140.849555] systemd[1]: Starting test.service... machine # [ 140.881550] systemd[1]: Finished test.service. machine # [ 140.889362] systemd[1]: Reached target Local File Systems. machine # [ 140.892618] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 140.896326] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 140.897169] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 140.898381] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 140.910504] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 140.912278] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 140.913507] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 140.914680] systemd[1]: Reached target Remote File Systems. machine # [ 140.915445] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 140.916908] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 141.214775] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 141.219047] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 141.221536] systemd[1]: Starting linger-users.service... machine # [ 141.276127] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 141.278215] systemd[1]: Finished linger-users.service. machine # [ 141.535973] nixos[5747]: finished switching to system configuration /nix/store/64ciqw9gp4w8v58cy3j35anc4q14icha-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&1, in 3.19 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&1 machine # [ 142.164325] nixos[5889]: switching to system configuration /nix/store/h910rr5zrfivw69svy126ifi2yra626d-nixos-system-machine-test machine # [ 142.168297] systemd[1]: Stopped target Local File Systems. machine # [ 142.169388] systemd[1]: Stopped target Remote File Systems. machine # [ 142.668886] systemd[1]: Reload requested from client PID 5889 ('.switch-to-conf') (unit backdoor.service)... machine # [ 142.670883] systemd[1]: Reloading... machine # [ 143.346004] systemd[1]: Reloading finished in 671 ms. machine # [ 143.488079] systemd[603]: Reexecution requested from client PID 5958 ('switch-to-confi')... machine # [ 143.489469] systemd[603]: Reexecuting. machine # [ 143.554007] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 143.753368] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 143.759736] systemd[603]: Reload requested from client PID 5958 ('switch-to-confi')... machine # [ 143.762072] systemd[603]: Reloading... machine # [ 143.958234] systemd[603]: Reloading finished in 194 ms. machine # [ 143.977750] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 143.987233] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 143.988307] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 143.991210] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 144.037780] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 144.041977] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 144.044650] systemd[1]: Stopping Reactivate sysinit units... machine # [ 144.045515] systemd[1]: Reached target Reactivate sysinit units. machine # [ 144.049003] systemd[1]: test.service: Deactivated successfully. machine # [ 144.050342] systemd[1]: Stopped test.service. machine # [ 144.051129] systemd[1]: Stopping test.service... machine # [ 144.052875] systemd[1]: Starting test.service... machine # [ 144.087666] systemd[1]: Finished test.service. machine # [ 144.090120] systemd[1]: Reached target Remote File Systems. machine # [ 144.094676] systemd[1]: Reached target Local File Systems. machine # [ 144.098310] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 144.103244] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 144.107567] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 144.113541] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 144.115734] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 144.124847] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 144.126137] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 144.127314] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 144.128671] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 144.468460] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 144.471242] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 144.477371] systemd[1]: Starting linger-users.service... machine # [ 144.550455] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 144.553142] systemd[1]: Finished linger-users.service. machine # [ 144.819801] nixos[5889]: finished switching to system configuration /nix/store/h910rr5zrfivw69svy126ifi2yra626d-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&1, in 3.29 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 145.540749] nixos[6031]: switching to system configuration /nix/store/21hfw8sh6q8izq23jfphrq020id5dlkc-nixos-system-machine-test machine # [ 145.544275] systemd[1]: Stopped target Local File Systems. machine # [ 145.545657] systemd[1]: Stopped target Remote File Systems. machine # [ 145.986320] systemd[1]: Reload requested from client PID 6031 ('.switch-to-conf') (unit backdoor.service)... machine # [ 145.988335] systemd[1]: Reloading... machine # [ 146.581291] systemd[1]: Reloading finished in 590 ms. machine # [ 146.720115] systemd[603]: Reexecution requested from client PID 6100 ('switch-to-confi')... machine # [ 146.723742] systemd[603]: Reexecuting. machine # [ 146.773489] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 146.957430] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 146.964446] systemd[603]: Reload requested from client PID 6100 ('switch-to-confi')... machine # [ 146.966672] systemd[603]: Reloading... machine # [ 147.150388] systemd[603]: Reloading finished in 182 ms. machine # [ 147.168146] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 147.177889] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 147.178858] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 147.181663] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 147.228318] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 147.232359] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 147.233534] systemd[1]: Stopping Reactivate sysinit units... machine # [ 147.234360] systemd[1]: Reached target Reactivate sysinit units. machine # [ 147.236664] systemd[1]: test.service: Deactivated successfully. machine # [ 147.239511] systemd[1]: Stopped test.service. machine # [ 147.241780] systemd[1]: Stopping test.service... machine # [ 147.243398] systemd[1]: Starting test.service... machine # [ 147.277842] systemd[1]: Finished test.service. machine # [ 147.281467] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 147.283845] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 147.288295] systemd[1]: Reached target Remote File Systems. machine # [ 147.290387] systemd[1]: Starting linger-users.service... machine # [ 147.294612] systemd[1]: Reached target Local File Systems. machine # [ 147.295749] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 147.307453] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 147.308691] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 147.309903] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 147.315557] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 147.318722] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 147.322457] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 147.379193] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 147.380445] systemd[1]: Finished linger-users.service. machine # [ 147.634822] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 147.638107] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 147.894520] nixos[6031]: finished switching to system configuration /nix/store/21hfw8sh6q8izq23jfphrq020id5dlkc-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 3.07 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&1 machine # [ 148.509431] nixos[6173]: switching to system configuration /nix/store/826zbz71lq31009wzfm8s0arcb4wag2l-nixos-system-machine-test machine # [ 148.512980] systemd[1]: Stopped target Remote File Systems. machine # [ 148.513964] systemd[1]: Stopped target Local File Systems. machine # [ 149.006248] systemd[1]: Reload requested from client PID 6173 ('.switch-to-conf') (unit backdoor.service)... machine # [ 149.008226] systemd[1]: Reloading... machine # [ 149.633822] systemd[1]: Reloading finished in 622 ms. machine # [ 149.780180] systemd[603]: Reexecution requested from client PID 6242 ('switch-to-confi')... machine # [ 149.781551] systemd[603]: Reexecuting. machine # [ 149.833782] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 150.025779] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 150.033389] systemd[603]: Reload requested from client PID 6242 ('switch-to-confi')... machine # [ 150.036208] systemd[603]: Reloading... machine # [ 150.223178] systemd[603]: Reloading finished in 186 ms. machine # [ 150.236160] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 150.245431] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 150.247853] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 150.250787] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 150.296899] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 150.301870] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 150.303136] systemd[1]: Stopping Reactivate sysinit units... machine # [ 150.303960] systemd[1]: Reached target Reactivate sysinit units. machine # [ 150.310227] systemd[1]: Reloading test.service... machine # [ 150.346150] systemd[1]: Reloaded test.service. machine # [ 150.351078] systemd[1]: Reached target Local File Systems. machine # [ 150.353454] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 150.355862] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 150.359019] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 150.366205] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 150.369529] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 150.372849] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 150.380064] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 150.381636] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 150.382880] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 150.385407] systemd[1]: Reached target Remote File Systems. machine # [ 150.690077] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 150.694134] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 150.696776] systemd[1]: Starting linger-users.service... machine # [ 150.753761] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 150.756214] systemd[1]: Finished linger-users.service. machine # [ 151.012955] nixos[6173]: finished switching to system configuration /nix/store/826zbz71lq31009wzfm8s0arcb4wag2l-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 3.12 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&1 machine # [ 151.642995] nixos[6315]: switching to system configuration /nix/store/98884919pflwqn7dlsxpf19b5w90kjj8-nixos-system-machine-test machine # [ 151.645706] systemd[1]: Stopped target Local File Systems. machine # [ 151.647950] systemd[1]: Stopped target Remote File Systems. machine # [ 152.150450] systemd[1]: Reload requested from client PID 6315 ('.switch-to-conf') (unit backdoor.service)... machine # [ 152.152454] systemd[1]: Reloading... machine # [ 152.810200] systemd[1]: Reloading finished in 655 ms. machine # [ 152.960440] systemd[603]: Reexecution requested from client PID 6384 ('switch-to-confi')... machine # [ 152.963587] systemd[603]: Reexecuting. machine # [ 153.026026] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 153.227807] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 153.234474] systemd[603]: Reload requested from client PID 6384 ('switch-to-confi')... machine # [ 153.236864] systemd[603]: Reloading... machine # [ 153.428883] systemd[603]: Reloading finished in 190 ms. machine # [ 153.445622] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 153.454451] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 153.456932] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 153.459841] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 153.506672] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 153.510916] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 153.515062] systemd[1]: Stopping Reactivate sysinit units... machine # [ 153.515951] systemd[1]: Reached target Reactivate sysinit units. machine # [ 153.518994] systemd[1]: Reloading test.service... machine # [ 153.556112] systemd[1]: Reloaded test.service. machine # [ 153.559753] systemd[1]: Reached target Remote File Systems. machine # [ 153.565074] systemd[1]: Starting linger-users.service... machine # [ 153.567784] systemd[1]: Reached target Local File Systems. machine # [ 153.578716] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 153.583857] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 153.586276] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 153.592958] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 153.595265] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 153.604883] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 153.606395] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 153.607967] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 153.609477] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 153.669709] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 153.670791] systemd[1]: Finished linger-users.service. machine # [ 153.929071] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 153.932571] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 154.190885] nixos[6315]: finished switching to system configuration /nix/store/98884919pflwqn7dlsxpf19b5w90kjj8-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&1, in 3.18 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&1 machine # [ 154.835325] nixos[6457]: switching to system configuration /nix/store/p7snjck4m0bx3d23vpvfr483xz1vac15-nixos-system-machine-test machine # [ 154.837802] systemd[1]: Stopped target Remote File Systems. machine # [ 154.840197] systemd[1]: Stopped target Local File Systems. machine # [ 155.362413] systemd[1]: Reload requested from client PID 6457 ('.switch-to-conf') (unit backdoor.service)... machine # [ 155.364482] systemd[1]: Reloading... machine # [ 156.019558] systemd[1]: Reloading finished in 652 ms. machine # [ 156.161694] systemd[603]: Reexecution requested from client PID 6526 ('switch-to-confi')... machine # [ 156.163131] systemd[603]: Reexecuting. machine # [ 156.230410] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 156.422350] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 156.429455] systemd[603]: Reload requested from client PID 6526 ('switch-to-confi')... machine # [ 156.431311] systemd[603]: Reloading... machine # [ 156.629109] systemd[603]: Reloading finished in 196 ms. machine # [ 156.644172] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 156.653971] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 156.656416] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 156.659504] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 156.706792] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 156.711256] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 156.713408] systemd[1]: Stopping Reactivate sysinit units... machine # [ 156.714282] systemd[1]: Reached target Reactivate sysinit units. machine # [ 156.717920] systemd[1]: test.service: Deactivated successfully. machine # [ 156.718848] systemd[1]: Stopped test.service. machine # [ 156.719474] systemd[1]: Stopping test.service... machine # [ 156.721930] systemd[1]: Starting test.service... machine # [ 156.756719] systemd[1]: Finished test.service. machine # [ 156.760283] systemd[1]: Reached target Remote File Systems. machine # [ 156.767443] systemd[1]: Starting linger-users.service... machine # [ 156.770994] systemd[1]: Reached target Local File Systems. machine # [ 156.778077] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 156.779434] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 156.786339] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 156.791524] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 156.793478] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 156.795113] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 156.798127] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 156.804312] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 156.805754] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 156.869106] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 156.873037] systemd[1]: Finished linger-users.service. machine # [ 157.144753] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 157.147955] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 157.404884] nixos[6457]: finished switching to system configuration /nix/store/p7snjck4m0bx3d23vpvfr483xz1vac15-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&1, in 3.22 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&1 machine # [ 158.009894] nixos[6599]: switching to system configuration /nix/store/27c6n9kvxyrqnssprif04y25llqxa5mh-nixos-system-machine-test machine # [ 158.012840] systemd[1]: Stopped target Local File Systems. machine # [ 158.015146] systemd[1]: Stopped target Remote File Systems. machine # [ 158.474230] systemd[1]: Reload requested from client PID 6599 ('.switch-to-conf') (unit backdoor.service)... machine # [ 158.475982] systemd[1]: Reloading... machine # [ 159.080040] systemd[1]: Reloading finished in 601 ms. machine # [ 159.229497] systemd[603]: Reexecution requested from client PID 6668 ('switch-to-confi')... machine # [ 159.230930] systemd[603]: Reexecuting. machine # [ 159.285774] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 159.476703] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 159.483809] systemd[603]: Reload requested from client PID 6668 ('switch-to-confi')... machine # [ 159.487103] systemd[603]: Reloading... machine # [ 159.674424] systemd[603]: Reloading finished in 188 ms. machine # [ 159.688173] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 159.696958] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 159.699199] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 159.701923] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 159.744605] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 159.748545] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 159.750367] systemd[1]: Stopping Reactivate sysinit units... machine # [ 159.752001] systemd[1]: Reached target Reactivate sysinit units. machine # [ 159.755258] systemd[1]: Reached target Remote File Systems. machine # [ 159.757848] systemd[1]: Starting linger-users.service... machine # [ 159.761399] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 159.764462] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 159.773054] systemd[1]: Reached target Local File Systems. machine # [ 159.775036] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 159.781443] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 159.782351] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 159.783571] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 159.795775] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 159.798324] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 159.799843] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 159.850558] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 159.853794] systemd[1]: Finished linger-users.service. machine # [ 160.124204] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 160.126831] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 160.384968] nixos[6599]: finished switching to system configuration /nix/store/27c6n9kvxyrqnssprif04y25llqxa5mh-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&1, in 2.98 seconds) (finished: subtest: unit file parser, in 40.80 seconds) subtest: restart and reload by activation script machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1 machine # [ 160.971443] nixos[6739]: switching to system configuration /nix/store/nmllh5hhgdabn4zv8kb77g1f6fsmcmd1-nixos-system-machine-test machine # [ 160.974057] systemd[1]: Stopped target Local File Systems. machine # [ 160.976326] systemd[1]: Stopped target Remote File Systems. machine # [ 161.406225] systemd[1]: Reload requested from client PID 6739 ('.switch-to-conf') (unit backdoor.service)... machine # [ 161.408224] systemd[1]: Reloading... machine # [ 161.985442] systemd[1]: Reloading finished in 575 ms. machine # [ 162.123055] systemd[603]: Reexecution requested from client PID 6808 ('switch-to-confi')... machine # [ 162.126486] systemd[603]: Reexecuting. machine # [ 162.181324] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 162.367578] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 162.373978] systemd[603]: Reload requested from client PID 6808 ('switch-to-confi')... machine # [ 162.376203] systemd[603]: Reloading... machine # [ 162.556844] systemd[603]: Reloading finished in 179 ms. machine # [ 162.572139] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 162.581602] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 162.583836] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 162.586579] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 162.631116] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 162.635480] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 162.636770] systemd[1]: Stopping Reactivate sysinit units... machine # [ 162.637565] systemd[1]: Reached target Reactivate sysinit units. machine # [ 162.641156] systemd[1]: Reached target Local File Systems. machine # [ 162.644198] systemd[1]: Reached target Remote File Systems. machine # [ 162.646948] systemd[1]: Starting linger-users.service... machine # [ 162.655833] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 162.665287] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 162.666129] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 162.667337] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 162.679469] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 162.681736] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 162.683354] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 162.684919] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 162.686464] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 162.739714] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 162.743191] systemd[1]: Finished linger-users.service. machine # [ 162.999912] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 163.002628] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 163.260521] nixos[6739]: finished switching to system configuration /nix/store/nmllh5hhgdabn4zv8kb77g1f6fsmcmd1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 2.88 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 163.874896] nixos[6879]: switching to system configuration /nix/store/xy19yz4crjpmzf5awnc5qq7ma5q0aay2-nixos-system-machine-test machine # [ 163.877741] systemd[1]: test.service: Deactivated successfully. machine # [ 163.881066] systemd[1]: Stopped test.service. machine # [ 163.882001] systemd[1]: Stopped target Local File Systems. machine # [ 163.883126] systemd[1]: Stopped target Remote File Systems. machine # [ 164.379081] systemd[1]: Reload requested from client PID 6879 ('.switch-to-conf') (unit backdoor.service)... machine # [ 164.381298] systemd[1]: Reloading... machine # [ 165.038613] systemd[1]: Reloading finished in 655 ms. machine # [ 165.162936] systemd[603]: Reexecution requested from client PID 6950 ('switch-to-confi')... machine # [ 165.166431] systemd[603]: Reexecuting. machine # [ 165.229886] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 165.418877] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 165.425543] systemd[603]: Reload requested from client PID 6950 ('switch-to-confi')... machine # [ 165.428105] systemd[603]: Reloading... machine # [ 165.618407] systemd[603]: Reloading finished in 189 ms. machine # [ 165.632169] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 165.643258] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 165.644411] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 165.647518] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 165.695799] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 165.700169] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 165.702108] systemd[1]: Stopping Reactivate sysinit units... machine # [ 165.703125] systemd[1]: Reached target Reactivate sysinit units. machine # [ 165.712986] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart-by-as. machine # [ 165.717921] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 165.721295] systemd[1]: Reached target Local File Systems. machine # [ 165.725292] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 165.734344] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 165.735277] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 165.736579] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 165.738704] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 165.744199] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 165.753335] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 165.769794] systemd[1]: Created slice Slice /system/templated-reload-triggers. machine # [ 165.776808] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart. machine # [ 165.778046] systemd[1]: Created slice Slice /system/templated-simple-service. machine # [ 165.779040] systemd[1]: Reached target Remote File Systems. machine # [ 165.785060] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 165.787761] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 165.800492] systemd[1]: Created slice Slice /system/templated-no-restart-service. machine # [ 165.805259] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 165.821640] systemd[1]: Created slice Slice /system/templated-simple-reload-service. machine # [ 165.848972] systemd[1]: Created slice Slice /system/templated-simple-restart-service. machine # [ 166.142113] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 166.147158] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 166.150617] systemd[1]: Starting linger-users.service... machine # [ 166.156228] systemd[1]: Starting no-restart-service.service... machine # [ 166.162280] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 166.172487] systemd[1]: Starting reload-triggers-and-restart.service... machine # [ 166.180571] systemd[1]: Starting reload-triggers.service... machine # [ 166.187995] systemd[1]: Starting simple-reload-service.service... machine # [ 166.200736] systemd[1]: Starting simple-restart-service.service... machine # [ 166.210345] systemd[1]: Starting simple-service.service... machine # [ 166.221495] systemd[1]: Starting templated-no-restart-service@instance.service... machine # [ 166.239499] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service... machine # [ 166.256659] systemd[1]: Starting templated-reload-triggers@instance.service... machine # [ 166.257888] systemd[1]: Starting templated-simple-reload-service@instance.service... machine # [ 166.299648] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 166.317772] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 166.363284] systemd[1]: Finished reload-triggers-and-restart.service. machine # [ 166.373344] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 166.388949] systemd[1]: Finished no-restart-service.service. machine # [ 166.405786] systemd[1]: Finished simple-reload-service.service. machine # [ 166.406734] systemd[1]: Finished simple-restart-service.service. machine # [ 166.430988] systemd[1]: Finished reload-triggers.service. machine # [ 166.436798] systemd[1]: Finished simple-service.service. machine # [ 166.458797] systemd[1]: Finished templated-reload-triggers@instance.service. machine # [ 166.459882] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 166.466851] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 166.469828] systemd[1]: Finished templated-no-restart-service@instance.service. machine # [ 166.470900] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service. machine # [ 166.474604] systemd[1]: Finished templated-simple-reload-service@instance.service. machine # [ 166.487618] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 166.489968] systemd[1]: Finished linger-users.service. machine # [ 166.748926] nixos[6879]: finished switching to system configuration /nix/store/xy19yz4crjpmzf5awnc5qq7ma5q0aay2-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 3.49 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 167.447252] nixos[7049]: switching to system configuration /nix/store/xy19yz4crjpmzf5awnc5qq7ma5q0aay2-nixos-system-machine-test machine # [ 167.450684] systemd[1]: Stopped target Local File Systems. machine # [ 167.452088] systemd[1]: Stopped target Remote File Systems. machine # [ 167.934311] systemd[1]: Reload requested from client PID 7049 ('.switch-to-conf') (unit backdoor.service)... machine # [ 167.936257] systemd[1]: Reloading... machine # [ 168.542928] systemd[1]: Reloading finished in 604 ms. machine # [ 168.685995] systemd[603]: Reexecution requested from client PID 7119 ('switch-to-confi')... machine # [ 168.687387] systemd[603]: Reexecuting. machine # [ 168.737360] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 168.920068] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 168.926779] systemd[603]: Reload requested from client PID 7119 ('switch-to-confi')... machine # [ 168.929211] systemd[603]: Reloading... machine # [ 169.111430] systemd[603]: Reloading finished in 181 ms. machine # [ 169.128133] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 169.137651] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 169.138612] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 169.141390] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 169.186622] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 169.190198] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 169.192716] systemd[1]: Stopping Reactivate sysinit units... machine # [ 169.193520] systemd[1]: Reached target Reactivate sysinit units. machine # [ 169.200307] systemd[1]: Reloading reload-triggers-and-restart.service... machine # [ 169.203514] systemd[1]: Reloading reload-triggers.service... machine # [ 169.213058] systemd[1]: Reloading simple-reload-service.service... machine # [ 169.221919] systemd[1]: Reloading templated-reload-triggers-and-restart@instance.service... machine # [ 169.229446] systemd[1]: Reloading templated-reload-triggers@instance.service... machine # [ 169.240304] systemd[1]: Reloading templated-simple-reload-service@instance.service... machine # [ 169.296323] systemd[1]: Reloaded reload-triggers-and-restart.service. machine # [ 169.298634] systemd[1]: Reloaded reload-triggers.service. machine # [ 169.302459] systemd[1]: Reloaded simple-reload-service.service. machine # [ 169.303698] systemd[1]: Reloaded templated-reload-triggers-and-restart@instance.service. machine # [ 169.309940] systemd[1]: Reloaded templated-simple-reload-service@instance.service. machine # [ 169.314087] systemd[1]: Reloaded templated-reload-triggers@instance.service. machine # [ 169.317113] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully. machine # [ 169.320422] systemd[1]: Stopped reload-triggers-and-restart-by-as.service. machine # [ 169.321614] systemd[1]: Stopping reload-triggers-and-restart-by-as.service... machine # [ 169.323911] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 169.327589] systemd[1]: simple-restart-service.service: Deactivated successfully. machine # [ 169.330390] systemd[1]: Stopped simple-restart-service.service. machine # [ 169.336426] systemd[1]: Stopping simple-restart-service.service... machine # [ 169.337593] systemd[1]: Starting simple-restart-service.service... machine # [ 169.346770] systemd[1]: simple-service.service: Deactivated successfully. machine # [ 169.350926] systemd[1]: Stopped simple-service.service. machine # [ 169.351705] systemd[1]: Stopping simple-service.service... machine # [ 169.352643] systemd[1]: Starting simple-service.service... machine # [ 169.353700] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully. machine # [ 169.363840] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 169.376370] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 169.383415] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 169.387052] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully. machine # [ 169.397468] systemd[1]: Stopped templated-simple-restart-service@instance.service. machine # [ 169.406433] systemd[1]: Stopping templated-simple-restart-service@instance.service... machine # [ 169.413396] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 169.416701] systemd[1]: templated-simple-service@instance.service: Deactivated successfully. machine # [ 169.418334] systemd[1]: Stopped templated-simple-service@instance.service. machine # [ 169.420820] systemd[1]: Stopping templated-simple-service@instance.service... machine # [ 169.422621] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 169.434746] systemd[1]: Finished simple-restart-service.service. machine # [ 169.440682] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 169.443265] systemd[1]: Finished simple-service.service. machine # [ 169.449061] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 169.457426] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 169.461118] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 169.464717] systemd[1]: Reached target Remote File Systems. machine # [ 169.468929] systemd[1]: Starting linger-users.service... machine # [ 169.478089] systemd[1]: Reached target Local File Systems. machine # [ 169.481275] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 169.484905] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 169.487293] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 169.494939] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 169.495781] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 169.498856] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 169.502691] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 169.507773] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 169.509603] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 169.557737] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 169.560341] systemd[1]: Finished linger-users.service. machine # [ 169.830455] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 169.833757] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 170.091345] nixos[7049]: finished switching to system configuration /nix/store/xy19yz4crjpmzf5awnc5qq7ma5q0aay2-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 3.34 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&1 machine # [ 170.849208] nixos[7215]: switching to system configuration /nix/store/b7j6pqb5bps2ni12znipfqryiv7x4m80-nixos-system-machine-test machine # [ 170.852502] systemd[1]: Stopped target Local File Systems. machine # [ 170.853806] systemd[1]: Stopped target Remote File Systems. machine # [ 171.391993] systemd[1]: Reload requested from client PID 7215 ('.switch-to-conf') (unit backdoor.service)... machine # [ 171.393985] systemd[1]: Reloading... machine # [ 172.066132] systemd[1]: Reloading finished in 668 ms. machine # [ 172.211193] systemd[603]: Reexecution requested from client PID 7286 ('switch-to-confi')... machine # [ 172.214751] systemd[603]: Reexecuting. machine # [ 172.269461] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 172.454365] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 172.460686] systemd[603]: Reload requested from client PID 7286 ('switch-to-confi')... machine # [ 172.463124] systemd[603]: Reloading... machine # [ 172.644743] systemd[603]: Reloading finished in 180 ms. machine # [ 172.660133] systemd[603]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 172.668942] systemd[603]: Stopped Run user-specific NixOS activation. machine # [ 172.671106] systemd[603]: Stopping Run user-specific NixOS activation... machine # [ 172.673718] systemd[603]: Starting Run user-specific NixOS activation... machine # [ 172.714440] systemd[603]: Finished Run user-specific NixOS activation. machine # [ 172.718146] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 172.720567] systemd[1]: Stopping Reactivate sysinit units... machine # [ 172.721377] systemd[1]: Reached target Reactivate sysinit units. machine # [ 172.726438] systemd[1]: Reloading reload-triggers.service... machine # [ 172.731242] systemd[1]: Reloading simple-reload-service.service... machine # [ 172.741874] systemd[1]: Reloading templated-reload-triggers@instance.service... machine # [ 172.748823] systemd[1]: Reloading templated-simple-reload-service@instance.service... machine # [ 172.801568] systemd[1]: Reloaded templated-reload-triggers@instance.service. machine # [ 172.804799] systemd[1]: Reloaded simple-reload-service.service. machine # [ 172.807343] systemd[1]: Reloaded templated-simple-reload-service@instance.service. machine # [ 172.809388] systemd[1]: Reloaded reload-triggers.service. machine # [ 172.811863] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully. machine # [ 172.815148] systemd[1]: Stopped reload-triggers-and-restart-by-as.service. machine # [ 172.816691] systemd[1]: Stopping reload-triggers-and-restart-by-as.service... machine # [ 172.818869] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 172.822728] systemd[1]: reload-triggers-and-restart.service: Deactivated successfully. machine # [ 172.824172] systemd[1]: Stopped reload-triggers-and-restart.service. machine # [ 172.830305] systemd[1]: Stopping reload-triggers-and-restart.service... machine # [ 172.836247] systemd[1]: Starting reload-triggers-and-restart.service... machine # [ 172.841546] systemd[1]: simple-restart-service.service: Deactivated successfully. machine # [ 172.846828] systemd[1]: Stopped simple-restart-service.service. machine # [ 172.854883] systemd[1]: Stopping simple-restart-service.service... machine # [ 172.855756] systemd[1]: Starting simple-restart-service.service... machine # [ 172.865415] systemd[1]: simple-service.service: Deactivated successfully. machine # [ 172.866481] systemd[1]: Stopped simple-service.service. machine # [ 172.867326] systemd[1]: Stopping simple-service.service... machine # [ 172.872691] systemd[1]: Starting simple-service.service... machine # [ 172.874077] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully. machine # [ 172.875998] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 172.889313] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 172.900775] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 172.906742] systemd[1]: templated-reload-triggers-and-restart@instance.service: Deactivated successfully. machine # [ 172.924237] systemd[1]: Stopped templated-reload-triggers-and-restart@instance.service. machine # [ 172.938329] systemd[1]: Stopping templated-reload-triggers-and-restart@instance.service... machine # [ 172.944571] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service... machine # [ 172.952437] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully. machine # [ 172.963787] systemd[1]: Stopped templated-simple-restart-service@instance.service. machine # [ 172.965215] systemd[1]: Stopping templated-simple-restart-service@instance.service... machine # [ 172.969522] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 172.979403] systemd[1]: templated-simple-service@instance.service: Deactivated successfully. machine # [ 172.981054] systemd[1]: Stopped templated-simple-service@instance.service. machine # [ 172.982148] systemd[1]: Stopping templated-simple-service@instance.service... machine # [ 172.983298] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 172.984930] systemd[1]: Finished reload-triggers-and-restart.service. machine # [ 172.986023] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 172.987109] systemd[1]: Finished simple-restart-service.service. machine # [ 172.988098] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 172.989427] systemd[1]: Finished simple-service.service. machine # [ 172.998529] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service. machine # [ 173.001753] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 173.002817] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 173.005848] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 173.008842] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 173.011811] systemd[1]: Reached target Local File Systems. machine # [ 173.014694] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 173.021369] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 173.022212] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 173.023405] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 173.035449] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 173.037309] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 173.038508] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 173.039680] systemd[1]: Reached target Remote File Systems. machine # [ 173.340971] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 173.344305] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 173.348280] systemd[1]: Starting linger-users.service... machine # [ 173.404747] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 173.407135] systemd[1]: Finished linger-users.service. machine # [ 173.667313] nixos[7215]: finished switching to system configuration /nix/store/b7j6pqb5bps2ni12znipfqryiv7x4m80-nixos-system-machine-test machine: (finished: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&1, in 3.57 seconds) machine: must succeed: /nix/store/05k3gqc39xkbhi8hd8g6j587i4y4r69j-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/05k3gqc39xkbhi8hd8g6j587i4y4r69j-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration dry-activate 2>&1, in 0.89 seconds) (finished: subtest: restart and reload by activation script, in 14.17 seconds) (finished: run the VM test script, in 175.36 seconds) test script finished in 175.41s cleanup kill QemuMachine (pid 45) machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14) (finished: cleanup, in 0.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