vm-test-run-switch-test-services
checks.aarch64-linux.switchTest
· build #62
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.01 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7machine: waiting for unit multi-user.target8machine: waiting for the VM to finish booting9machine: starting vm10machine # Disk image does not exist, creating the virtualisation disk image...11machine # Formatting '/build/vm-state-machine/tmp.beXYIWtn9n', fmt=raw size=107374182412machine: QEMU running (pid 45)13machine # mke2fs 1.47.4 (6-Mar-2025)14machine # Discarding device blocks: 0/262144 done15machine # Creating filesystem with 262144 4k blocks and 65536 inodes16machine # Filesystem UUID: b644028f-71ef-46ee-b947-a511097ff93317machine # Superblock backups stored on blocks:18machine # 32768, 98304, 163840, 22937619machine # 20machine # Allocating group tables: 0/8 done21machine # Writing inode tables: 0/8 done22machine # Creating journal (8192 blocks): done23machine # Writing superblocks and filesystem accounting information: 0/8 done24machine # 25machine # Virtualisation disk image created.26machine # Starting virtiofs daemons...27machine # [2026-10-20T03:08:06Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28machine # [2026-10-20T03:08:06Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29machine # [2026-10-20T03:08:06Z INFO virtiofsd] Waiting for vhost-user socket connection...30machine # [2026-10-20T03:08:06Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31machine # [2026-10-20T03:08:06Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32machine # [2026-10-20T03:08:06Z INFO virtiofsd] Waiting for vhost-user socket connection...33machine # [2026-10-20T03:08:06Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34machine # [2026-10-20T03:08:06Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35machine # [2026-10-20T03:08:06Z INFO virtiofsd] Waiting for vhost-user socket connection...36machine # [2026-10-20T03:08:06Z INFO virtiofsd] Client connected, servicing requests37machine # [2026-10-20T03:08:06Z INFO virtiofsd] Client connected, servicing requests38machine # [2026-10-20T03:08:06Z INFO virtiofsd] Client connected, servicing requests39machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]40machine # [ 0.000000] Linux version 6.18.57 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @179205830041machine # [ 0.000000] KASLR enabled42machine # [ 0.000000] random: crng init done43machine # [ 0.000000] Machine model: linux,dummy-virt44machine # [ 0.000000] efi: UEFI not found.45machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT46machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]47machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]48machine # [ 0.000000] Zone ranges:49machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]50machine # [ 0.000000] DMA32 empty51machine # [ 0.000000] Normal empty52machine # [ 0.000000] Device empty53machine # [ 0.000000] Movable zone start for each node54machine # [ 0.000000] Early memory node ranges55machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]56machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]57machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000058machine # [ 0.000000] psci: probing for conduit method from DT.59machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.60machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs61machine # [ 0.000000] psci: Trusted OS migration not required62machine # [ 0.000000] psci: SMC Calling Convention v1.163machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)64machine # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 u31129665machine # [ 0.000000] Detected PIPT I-cache on CPU066machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)67machine # [ 0.000000] CPU features: detected: GICv3 CPU interface68machine # [ 0.000000] CPU features: detected: Spectre-v469machine # [ 0.000000] CPU features: detected: Spectre-BHB70machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_3871machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_2372machine # [ 0.000000] alternatives: applying boot alternatives73machine # [ 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/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/init regInfo=/nix/.ro-store/j6qg2hx9r4q1pdyjf1crsmhk9v9q6bd7-closure-info/registration console=ttyAMA0,115200n8 console=tty074machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/j6qg2hx9r4q1pdyjf1crsmhk9v9q6bd7-closure-info/registration", will be passed to user space.75machine # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 bytes76machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)77machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)78machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB79machine # [ 0.000000] software IO TLB: area num 1.80machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)81machine # [ 0.000000] Fallback order for Node 0: 082machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214483machine # [ 0.000000] Policy zone: DMA84machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off85machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=186machine # [ 0.000000] allocated 2097152 bytes of page_ext87machine # [ 0.000000] ftrace: allocating 75301 entries in 296 pages88machine # [ 0.000000] ftrace: allocated 296 pages with 3 groups89machine # [ 0.000000] rcu: Hierarchical RCU implementation.90machine # [ 0.000000] rcu: RCU event tracing is enabled.91machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.92machine # [ 0.000000] Trampoline variant of Tasks RCU enabled.93machine # [ 0.000000] Rude variant of Tasks RCU enabled.94machine # [ 0.000000] Tracing variant of Tasks RCU enabled.95machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.96machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=197machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.98machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.99machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.100machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0101machine # [ 0.000000] GICv3: 256 SPIs implemented102machine # [ 0.000000] GICv3: 0 Extended SPIs implemented103machine # [ 0.000000] Root IRQ handler: gic_handle_irq104machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI105machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0106machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000107machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]108machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @450e0000 (indirect, esz 8, psz 64K, shr 1)109machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @450f0000 (flat, esz 8, psz 64K, shr 1)110machine # [ 0.000000] GICv3: using LPI property table @0x0000000045110000111machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000112machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.113machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns114machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).115machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns116machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns117machine # [ 0.000033] arm-pv: using stolen time PV118machine # [ 0.000398] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)119machine # [ 0.000555] Console: colour dummy device 80x25120machine # [ 0.000564] printk: legacy console [tty0] enabled121machine # [ 0.000750] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)122machine # [ 0.000757] pid_max: default: 32768 minimum: 301123machine # [ 0.000845] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima124machine # [ 0.000977] landlock: Up and running.125machine # [ 0.000981] Yama: becoming mindful.126machine # [ 0.001423] LSM support for eBPF active127machine # [ 0.001549] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)128machine # [ 0.001569] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)129machine # [ 0.003593] rcu: Hierarchical SRCU implementation.130machine # [ 0.003600] rcu: Max phase no-delay instances is 1000.131machine # [ 0.004921] fsl-mc MSI: its@8080000 domain created132machine # [ 0.005045] EFI services will not be available.133machine # [ 0.005140] smp: Bringing up secondary CPUs ...134machine # [ 0.005150] smp: Brought up 1 node, 1 CPU135machine # [ 0.005153] SMP: Total of 1 processors activated.136machine # [ 0.005156] CPU: All CPU(s) started at EL1137machine # [ 0.005167] CPU features: detected: Branch Target Identification138machine # [ 0.005171] CPU features: detected: ARMv8.4 Translation Table Level139machine # [ 0.005174] CPU features: detected: Instruction cache invalidation not required for I/D coherence140machine # [ 0.005178] CPU features: detected: Data cache clean to the PoU not required for I/D coherence141machine # [ 0.005186] CPU features: detected: Common not Private translations142machine # [ 0.005189] CPU features: detected: CRC32 instructions143machine # [ 0.005192] CPU features: detected: Data cache clean to Point of Deep Persistence144machine # [ 0.005195] CPU features: detected: Data cache clean to Point of Persistence145machine # [ 0.005198] CPU features: detected: Data independent timing control (DIT)146machine # [ 0.005201] CPU features: detected: E0PD147machine # [ 0.005204] CPU features: detected: Enhanced Counter Virtualization148machine # [ 0.005207] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)149machine # [ 0.005211] CPU features: detected: Enhanced Virtualization Traps150machine # [ 0.005214] CPU features: detected: Fine Grained Traps151machine # [ 0.005217] CPU features: detected: Generic authentication (architected QARMA5 algorithm)152machine # [ 0.005223] CPU features: detected: RCpc load-acquire (LDAPR)153machine # [ 0.005225] CPU features: detected: LSE atomic instructions154machine # [ 0.005228] CPU features: detected: Privileged Access Never155machine # [ 0.005231] CPU features: detected: PMUv3156machine # [ 0.005234] CPU features: detected: RAS Extension Support157machine # [ 0.005237] CPU features: detected: RASv1p1 Extension Support158machine # [ 0.005240] CPU features: detected: Random Number Generator159machine # [ 0.005245] CPU features: detected: Speculation barrier (SB)160machine # [ 0.005248] CPU features: detected: Stage-2 Force Write-Back161machine # [ 0.005250] CPU features: detected: TLB range maintenance instructions162machine # [ 0.005255] CPU features: detected: Speculative Store Bypassing Safe (SSBS)163machine # [ 0.005294] alternatives: applying system-wide alternatives164machine # [ 0.008513] CPU features: detected: BBM Level 2 without TLB conflict abort165machine # [ 0.008729] Memory: 886712K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 120600K reserved, 32768K cma-reserved)166machine # [ 0.009176] devtmpfs: initialized167machine # [ 0.011050] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)168machine # [ 0.011076] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).169machine # [ 0.011301] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL170machine # [ 0.011306] 0 pages in range for non-PLT usage171machine # [ 0.011307] 506368 pages in range for PLT usage172machine # [ 0.011410] pinctrl core: initialized pinctrl subsystem173machine # [ 0.012314] DMI not present or invalid.174machine # [ 0.015664] NET: Registered PF_NETLINK/PF_ROUTE protocol family175machine # [ 0.018252] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations176machine # [ 0.018409] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations177machine # [ 0.018569] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations178machine # [ 0.018593] audit: initializing netlink subsys (disabled)179machine # [ 0.019272] thermal_sys: Registered thermal governor 'fair_share'180machine # [ 0.019274] thermal_sys: Registered thermal governor 'bang_bang'181machine # [ 0.019278] thermal_sys: Registered thermal governor 'step_wise'182machine # [ 0.019281] thermal_sys: Registered thermal governor 'user_space'183machine # [ 0.019284] thermal_sys: Registered thermal governor 'power_allocator'184machine # [ 0.019313] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1185machine # [ 0.019322] cpuidle: using governor ladder186machine # [ 0.019328] cpuidle: using governor menu187machine # [ 0.019555] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.188machine # [ 0.019576] ASID allocator initialised with 65536 entries189machine # [ 0.020906] Serial: AMBA PL011 UART driver190machine # [ 0.026784] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1191machine # [ 0.026943] printk: console [ttyAMA0] enabled192machine # [ 0.158035] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages193machine # [ 0.158046] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page194machine # [ 0.158049] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages195machine # [ 0.158051] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page196machine # [ 0.158054] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages197machine # [ 0.158057] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page198machine # [ 0.158060] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages199machine # [ 0.158062] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page200machine # [ 0.159307] fbcon: Taking over console201machine # [ 0.159318] ACPI: Interpreter disabled.202machine # [ 0.169488] iommu: Default domain type: Translated203machine # [ 0.169495] iommu: DMA domain TLB invalidation policy: strict mode204machine # [ 0.169928] SCSI subsystem initialized205machine # [ 0.172096] usbcore: registered new interface driver usbfs206machine # [ 0.172128] usbcore: registered new interface driver hub207machine # [ 0.172143] usbcore: registered new device driver usb208machine # [ 0.172432] pps_core: LinuxPPS API ver. 1 registered209machine # [ 0.172436] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>210machine # [ 0.172444] PTP clock support registered211machine # [ 0.172485] EDAC MC: Ver: 3.0.0212machine # [ 0.179059] scmi_core: SCMI protocol bus registered213machine # [ 0.179470] FPGA manager framework214machine # [ 0.182601] vgaarb: loaded215machine # [ 0.184465] clocksource: Switched to clocksource arch_sys_counter216machine # [ 0.191795] VFS: Disk quotas dquot_6.6.0217machine # [ 0.191830] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)218machine # [ 0.195609] netfs: FS-Cache loaded219machine # [ 0.195761] pnp: PnP ACPI: disabled220machine # [ 0.200199] NET: Registered PF_INET protocol family221machine # [ 0.200370] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)222machine # [ 0.233188] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)223machine # [ 0.233235] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)224machine # [ 0.233259] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)225machine # [ 0.233304] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)226machine # [ 0.233376] TCP: Hash tables configured (established 8192 bind 8192)227machine # [ 0.233473] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)228machine # [ 0.233507] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)229machine # [ 0.233575] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)230machine # [ 0.233669] NET: Registered PF_UNIX/PF_LOCAL protocol family231machine # [ 0.233695] NET: Registered PF_XDP protocol family232machine # [ 0.233712] PCI: CLS 0 bytes, default 64233machine # [ 0.234125] Trying to unpack rootfs image as initramfs...234machine # [ 0.247061] kvm [1]: HYP mode not available235machine # [ 0.385100] Initialise system trusted keyrings236machine # [ 0.385139] Key type blacklist registered237machine # [ 0.385305] workingset: timestamp_bits=42 max_order=18 bucket_order=0238machine # [ 0.385691] squashfs: version 4.0 (2009/01/31) Phillip Lougher239machine # [ 0.385751] 9p: Installing v9fs 9p2000 file system support240machine # [ 0.385849] integrity: Platform Keyring initialized241machine # [ 0.385866] integrity: Machine keyring initialized242machine # [ 0.410665] Key type asymmetric registered243machine # [ 0.410685] Asymmetric key parser 'x509' registered244machine # [ 0.410774] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)245machine # [ 0.412535] io scheduler mq-deadline registered246machine # [ 0.412543] io scheduler kyber registered247machine # [ 0.424669] pl061_gpio 9030000.pl061: PL061 GPIO chip registered248machine # [ 0.429343] ledtrig-cpu: registered to indicate activity on CPUs249machine # [ 0.429841] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:250machine # [ 0.429859] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000251machine # [ 0.429874] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000252machine # [ 0.429881] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000253machine # [ 0.429901] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits254machine # [ 0.429927] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]255machine # [ 0.430031] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00256machine # [ 0.430039] pci_bus 0000:00: root bus resource [bus 00-ff]257machine # [ 0.430044] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]258machine # [ 0.430047] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]259machine # [ 0.430050] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]260machine # [ 0.430123] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint261machine # [ 0.430653] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint262machine # [ 0.430890] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]263machine # [ 0.430904] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]264machine # [ 0.430934] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]265machine # [ 0.430948] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]266machine # [ 0.431490] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint267machine # [ 0.431785] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]268machine # [ 0.431797] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]269machine # [ 0.431823] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]270machine # [ 0.432316] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint271machine # [ 0.460595] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]272machine # [ 0.460619] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]273machine # [ 0.460645] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]274machine # [ 0.461213] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint275machine # [ 0.461413] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]276machine # [ 0.461425] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]277machine # [ 0.461451] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]278machine # [ 0.461463] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]279machine # [ 0.461956] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint280machine # [ 0.462155] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.462181] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.462664] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint283machine # [ 0.462862] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]284machine # [ 0.462888] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]285machine # [ 0.463298] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint286machine # [ 0.463500] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]287machine # [ 0.463905] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint288machine # [ 0.464102] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]289machine # [ 0.464128] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]290machine # [ 0.481285] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint291machine # [ 0.481491] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]292machine # [ 0.481518] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]293machine # [ 0.482002] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint294machine # [ 0.482199] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]295machine # [ 0.482226] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]296machine # [ 0.482720] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint297machine # [ 0.483045] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]298machine # [ 0.483062] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]299machine # [ 0.483088] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]300machine # [ 0.483609] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint301machine # [ 0.483808] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]302machine # [ 0.483820] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]303machine # [ 0.483846] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]304machine # [ 0.504601] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned305machine # [ 0.504617] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned306machine # [ 0.504621] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned307machine # [ 0.504709] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned308machine # [ 0.504756] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned309machine # [ 0.504803] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned310machine # [ 0.504850] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned311machine # [ 0.504902] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned312machine # [ 0.504949] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned313machine # [ 0.505001] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned314machine # [ 0.505047] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned315machine # [ 0.505093] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned316machine # [ 0.505221] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned317machine # [ 0.505268] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned318machine # [ 0.505286] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned319machine # [ 0.505304] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned320machine # [ 0.505322] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned321machine # [ 0.505340] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned322machine # [ 0.505359] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned323machine # [ 0.505376] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned324machine # [ 0.505397] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned325machine # [ 0.505416] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned326machine # [ 0.505434] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned327machine # [ 0.505453] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned328machine # [ 0.505472] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned329machine # [ 0.505489] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned330machine # [ 0.505507] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned331machine # [ 0.505524] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned332machine # [ 0.505542] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned333machine # [ 0.505559] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned334machine # [ 0.505577] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned335machine # [ 0.505601] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]336machine # [ 0.505606] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]337machine # [ 0.505610] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]338machine # [ 0.506517] pci 0000:00:07.0: enabling device (0000 -> 0002)339machine # [ 0.560733] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)340machine # [ 0.562927] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)341machine # [ 0.572850] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)342machine # [ 0.575115] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)343machine # [ 0.585839] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)344machine # [ 0.588272] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)345machine # [ 0.598568] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)346machine # [ 0.608753] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)347machine # [ 0.611400] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)348machine # [ 0.615406] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)349machine # [ 0.618965] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)350machine # [ 0.637633] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled351machine # [ 0.639774] msm_serial: driver initialized352machine # [ 0.639934] SuperH (H)SCI(F) driver initialized353machine # [ 0.639995] STM32 USART driver initialized354machine # [ 0.678843] loop: module loaded355machine # [ 0.679046] virtio_blk virtio2: 1/0/0 default/read/poll queues356machine # [ 0.680112] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)357machine # [ 0.693314] megasas: 07.734.00.00-rc1358machine # [ 0.694118] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]359machine # [ 0.696447] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000360machine # [ 0.696468] Intel/Sharp Extended Query Table at 0x0031361machine # [ 0.701881] Using buffer write method362machine # [ 0.701924] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]363machine # [ 0.703458] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000364machine # [ 0.703632] Intel/Sharp Extended Query Table at 0x0031365machine # [ 0.718360] Using buffer write method366machine # [ 0.718386] Concatenating MTD devices:367machine # [ 0.718388] (0): "0.flash"368machine # [ 0.718391] (1): "0.flash"369machine # [ 0.718393] into device "0.flash"370machine # [ 1.009681] Freeing initrd memory: 26060K371machine # [ 1.018344] tun: Universal TUN/TAP device driver, 1.6372machine # [ 1.022578] thunder_xcv, ver 1.0373machine # [ 1.022617] thunder_bgx, ver 1.0374machine # [ 1.022636] nicpf, ver 1.0375machine # [ 1.023253] e1000: Intel(R) PRO/1000 Network Driver376machine # [ 1.023257] e1000: Copyright (c) 1999-2006 Intel Corporation.377machine # [ 1.023280] e1000e: Intel(R) PRO/1000 Network Driver378machine # [ 1.023290] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.379machine # [ 1.023327] igb: Intel(R) Gigabit Ethernet Network Driver380machine # [ 1.023331] igb: Copyright (c) 2007-2014 Intel Corporation.381machine # [ 1.023351] igbvf: Intel(R) Gigabit Virtual Function Network Driver382machine # [ 1.023355] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.383machine # [ 1.023493] sky2: driver version 1.30384machine # [ 1.033463] usbcore: registered new interface driver usb-storage385machine # [ 1.033551] usbcore: registered new interface driver usbserial_generic386machine # [ 1.033562] usbserial: USB Serial support registered for generic387machine # [ 1.034209] hv_vmbus: registering driver hyperv_keyboard388machine # [ 1.035615] ehci-pci 0000:00:07.0: EHCI Host Controller389machine # [ 1.035643] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1390machine # [ 1.035855] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000391machine # [ 1.036172] rtc-pl031 9010000.pl031: registered as rtc0392machine # [ 1.036194] rtc-pl031 9010000.pl031: setting system clock to 2026-10-20T03:08:08 UTC (1792465688)393machine # [ 1.044074] i2c_dev: i2c /dev entries driver394machine # [ 1.044982] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00395machine # [ 1.045276] hub 1-0:1.0: USB hub found396machine # [ 1.045294] hub 1-0:1.0: 6 ports detected397machine # [ 1.050713] sdhci: Secure Digital Host Controller Interface driver398machine # [ 1.050719] sdhci: Copyright(c) Pierre Ossman399machine # [ 1.051017] Synopsys Designware Multimedia Card Interface Driver400machine # [ 1.051420] sdhci-pltfm: SDHCI platform and OF driver helper401machine # [ 1.056135] hid: raw HID events driver (C) Jiri Kosina402machine # [ 1.056417] usbcore: registered new interface driver usbhid403machine # [ 1.056421] usbhid: USB HID core driver404machine # [ 1.059589] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available405machine # [ 1.062463] drop_monitor: Initializing network drop monitor service406machine # [ 1.062620] NET: Registered PF_INET6 protocol family407machine # [ 1.063177] Segment Routing with IPv6408machine # [ 1.063189] In-situ OAM (IOAM) with IPv6409machine # [ 1.063218] NET: Registered PF_PACKET protocol family410machine # [ 1.063277] 9pnet: Installing 9P2000 support411machine # [ 1.063318] Key type dns_resolver registered412machine # [ 1.074098] registered taskstats version 1413machine # [ 1.074234] Loading compiled-in X.509 certificates414machine # [ 1.083274] Demotion targets for Node 0: null415machine # [ 1.083407] Key type .fscrypt registered416machine # [ 1.083410] Key type fscrypt-provisioning registered417machine # [ 1.083505] ima: No TPM chip found, activating TPM-bypass!418machine # [ 1.083548] ima: Allocated hash algorithm: sha1419machine # [ 1.083571] ima: No architecture policies found420machine # [ 1.084277] input: gpio-keys as /devices/platform/gpio-keys/input/input0421machine # [ 1.108364] clk: Disabling unused clocks422machine # [ 1.108382] PM: genpd: Disabling unused power domains423machine # [ 1.114439] Freeing unused kernel memory: 7808K424machine # [ 1.114658] Run /init as init process425machine # [ 1.132420] fuse: module verification failed: signature and/or required key missing - tainting kernel426machine # [ 1.136598] fuse: init (API version 7.45)427machine # [ 1.139576] virtiofs virtio6: discovered new tag: nix-store428machine # [ 1.140403] virtiofs virtio6: virtio_fs_setup_dax: No cache capability429machine # [ 1.154097] virtiofs virtio7: discovered new tag: shared430machine # [ 1.154903] virtiofs virtio7: virtio_fs_setup_dax: No cache capability431machine # [ 1.156400] virtiofs virtio8: discovered new tag: xchg432machine # [ 1.159426] virtiofs virtio8: virtio_fs_setup_dax: No cache capability433machine # [ 1.173917] systemd[1]: Successfully made /usr/ read-only.434machine # [ 1.174477] systemd[1]: systemd 263-cf8ebfc 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 +EXPERIMENTAL-ENVVAR)435machine # [ 1.174492] systemd[1]: Detected virtualization qemu.436machine # [ 1.174515] systemd[1]: Detected architecture arm64.437machine # [ 1.174521] systemd[1]: Running in initrd.438machine # [ 1.174831] systemd[1]: Initializing machine ID from random generator.439machine # [ 1.174930] systemd[1]: Hostname set to <machine>.440machine # [ 1.278812] systemd[1]: bpf-restrict-fs: LSM BPF program attached441machine # [ 1.296653] usb 1-1: new high-speed USB device number 2 using ehci-pci442machine # [ 1.338586] systemd[1]: Queued start job for default target Initrd Default Target.443machine # [ 1.343803] systemd[1]: Created slice Slice /system/modprobe.444machine # [ 1.344002] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.445machine # [ 1.344032] systemd[1]: Expecting device /dev/disk/by-label/nixos...446machine # [ 1.344054] systemd[1]: Reached target Path Units.447machine # [ 1.344067] systemd[1]: Reached target Slice Units.448machine # [ 1.344081] systemd[1]: Reached target Swaps.449machine # [ 1.344094] systemd[1]: Reached target Timer Units.450machine # [ 1.344230] systemd[1]: Listening on D-Bus System Message Bus Socket.451machine # [ 1.344383] systemd[1]: Listening on Journal Socket (/dev/log).452machine # [ 1.351315] systemd[1]: Listening on Journal Sockets.453machine # [ 1.351415] systemd[1]: Listening on udev Kernel Socket.454machine # [ 1.351683] systemd[1]: Listening on udev Varlink Socket.455machine # [ 1.351702] systemd[1]: Reached target Socket Units.456machine # [ 1.356030] systemd[1]: Starting Create List of Static Device Nodes...457machine # [ 1.356090] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs458machine # [ 1.361972] systemd[1]: Mounting Kernel Configuration File System...459machine # [ 1.376095] systemd[1]: Starting Journal Service...460machine # [ 1.381961] systemd[1]: Starting Load Kernel Modules...461machine # [ 1.382063] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os462machine # [ 1.395294] systemd[1]: Starting Coldplug All udev Devices...463machine # [ 1.404636] systemd[1]: Finished Create List of Static Device Nodes.464machine # [ 1.424750] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...465machine # [ 1.455634] systemd[1]: Mounted Kernel Configuration File System.466machine # [ 1.455976] systemd-journald[71]: Collecting audit messages is disabled.467machine # [ 1.481039] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1468machine # [ 1.491308] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.469machine # [ 1.494286] systemd[1]: Starting Create Static Device Nodes in /dev...470machine # [ 1.507180] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.471machine # [ 1.512548] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev472machine # [ 1.523236] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0473machine # [ 1.523531] [drm] features: -virgl +edid -resource_blob -host_visible474machine # [ 1.523538] [drm] features: -context_init475machine # [ 1.524382] [drm] number of scanouts: 1476machine # [ 1.524403] [drm] number of cap sets: 0477machine # [ 1.551335] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic478machine # [ 1.551360] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0479machine # [ 1.573250] systemd[1]: Finished Create Static Device Nodes in /dev.480machine # [ 1.573464] systemd[1]: Reached target Preparation for Local File Systems.481machine # [ 1.573488] systemd[1]: Reached target Local File Systems.482machine # [ 1.576768] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0483machine # [ 1.581483] Console: switching to colour frame buffer device 160x50484machine # [ 1.588990] systemd[1]: Starting Rule-based Manager for Device Events and Files...485machine # [ 1.602386] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device486machine # [ 1.617264] systemd[1]: Finished Load Kernel Modules.487machine # [ 1.625137] systemd[1]: Starting Apply Kernel Variables...488machine # [ 1.642203] systemd-modules-load[72]: Inserted module 'dm_mod'489machine # [ 1.643441] systemd-modules-load[72]: Module 'virtio_balloon' is built in490machine # [ 1.664894] systemd[1]: Started Journal Service.491machine # [ 1.653128] systemd-modules-load[72]: Module 'virtio_console' is built in492machine # [ 1.660367] systemd-modules-load[72]: Inserted module 'virtio_gpu'493machine # [ 1.670094] systemd-modules-load[72]: Module 'virtio_rng' is built in494machine # [ 1.676399] systemd[1]: Starting Create System Files and Directories...495machine # [ 1.678579] systemd[1]: Finished Apply Kernel Variables.496machine # [ 1.696613] usb 1-2: new high-speed USB device number 3 using ehci-pci497machine # [ 1.704817] systemd-udevd[79]: Using default interface naming scheme 'v261'.498machine # [ 1.737001] systemd[1]: Finished Create System Files and Directories.499machine # [ 1.754744] systemd[1]: Started Rule-based Manager for Device Events and Files.500machine # [ 1.839298] systemd[1]: Starting Virtual Console Setup...501machine # [ 1.860832] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2502machine # [ 1.861137] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0503machine # [ 1.934300] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones.504machine # [ 1.940307] systemd[1]: Finished Virtual Console Setup.505machine # [ 2.619866] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line.506machine # [ 2.685223] systemd[1]: Finished Coldplug All udev Devices.507machine # [ 2.688224] systemd[1]: Reached target System Initialization.508machine # [ 2.691190] systemd[1]: Reached target Basic System.509machine # [ 2.695840] systemd[1]: Found device /dev/disk/by-label/nixos.510machine # [ 2.700174] systemd[1]: Reached target Initrd Root Device.511machine # [ 2.703659] (udev-worker)[105]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.512machine # [ 2.712412] (udev-worker)[105]: Network interface NamePolicy= disabled on kernel command line.513machine # [ 2.716309] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...514machine # [ 2.769035] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks515machine # [ 2.776199] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.516machine # [ 2.780780] systemd[1]: Mounting /sysroot...517machine # [ 2.834253] EXT4-fs (vda): mounted filesystem b644028f-71ef-46ee-b947-a511097ff933 r/w with ordered data mode. Quota mode: none.518machine # [ 2.823009] systemd[1]: Mounted /sysroot.519machine # [ 2.825527] systemd[1]: Reached target Initrd Root File System.520machine # [ 2.831337] systemd[1]: Starting Mountpoints Configured in the Real Root...521machine # [ 2.859794] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.522machine # [ 2.867781] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...523machine # [ 2.870907] systemd[1]: Reloading...524machine # [ 3.070848] systemd[1]: Reloading finished in 204 ms.525machine # [ 3.105223] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...526machine # [ 3.112213] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...527machine # [ 3.117813] systemd[1]: initrd-parse-etc.service: Deactivated successfully.528machine # [ 3.122326] systemd[1]: Finished Mountpoints Configured in the Real Root.529machine # [ 3.123981] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.530machine # [ 3.391287] systemd[1]: Mounting /sysroot/nix/.ro-store...531machine # [ 3.403348] systemd[1]: Mounting /sysroot/nix/.rw-store...532machine # [ 3.420216] systemd[1]: Mounting /sysroot/run...533machine # [ 3.426537] systemd[1]: Mounting /sysroot/tmp/shared...534machine # [ 3.454674] systemd[1]: Mounting /sysroot/tmp/xchg...535machine # [ 3.527330] systemd[1]: Mounted /sysroot/nix/.ro-store.536machine # [ 3.534238] systemd[1]: Mounted /sysroot/nix/.rw-store.537machine # [ 3.538058] systemd[1]: Mounted /sysroot/run.538machine # [ 3.542098] systemd[1]: Mounted /sysroot/tmp/shared.539machine # [ 3.545439] systemd[1]: Mounted /sysroot/tmp/xchg.540machine # [ 3.555963] systemd[1]: Starting rw-sysroot-nix-store.service...541machine # [ 3.587355] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.542machine # [ 3.589147] systemd[1]: Finished rw-sysroot-nix-store.service.543machine # [ 3.994116] (udev-worker)[104]: mtd0ro: Failed to find and pin callout binary "/nix/store/rqml5nvpjfjxxijlgvjhvvpy2iwg7axc-systemd-263-cf8ebfc/lib/udev/mtd_probe": No such file or directory544machine # [ 3.999782] (udev-worker)[104]: mtd0ro: /etc/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 directory545machine # [ 4.038661] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.546machine # [ 4.040553] systemd[1]: Stopped Virtual Console Setup.547machine # [ 4.044271] systemd[1]: Stopping Virtual Console Setup...548machine # [ 4.045232] systemd[1]: Starting Virtual Console Setup...549machine # [ 4.072995] systemd-vconsole-setup[150]: Configuration of first virtual console was skipped, ignoring remaining ones.550machine # [ 4.077345] systemd[1]: Finished Virtual Console Setup.551machine # [ 4.390473] systemd[1]: Mounting /sysroot/nix/store...552machine # [ 4.437342] systemd[1]: Mounted /sysroot/nix/store.553machine # [ 4.440146] systemd[1]: Reached target Initrd File Systems.554machine # [ 4.444624] systemd[1]: Starting Find NixOS closure...555machine # [ 4.456480] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...556machine # [ 4.498775] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.557machine # [ 4.504431] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.558machine # [ 4.517213] systemd[1]: Finished Find NixOS closure.559machine # [ 4.520293] systemd[1]: Reached target Initrd Default Target.560machine # [ 4.521821] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...561machine # [ 4.551159] systemd[1]: Stopped target Initrd Default Target.562machine # [ 4.556577] systemd[1]: Stopped target Basic System.563machine # [ 4.557470] systemd[1]: Stopped target Initrd Root Device.564machine # [ 4.558356] systemd[1]: Stopped target Path Units.565machine # [ 4.559259] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.566machine # [ 4.562047] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.567machine # [ 4.565203] systemd[1]: Stopped target Slice Units.568machine # [ 4.570418] systemd[1]: Stopped target Socket Units.569machine # [ 4.571944] systemd[1]: Stopped target System Initialization.570machine # [ 4.577430] systemd[1]: Stopped target Swaps.571machine # [ 4.579075] systemd[1]: Stopped target Timer Units.572machine # [ 4.585204] systemd[1]: dbus.socket: Deactivated successfully.573machine # [ 4.587215] systemd[1]: Closed D-Bus System Message Bus Socket.574machine # [ 4.589832] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.575machine # [ 4.591194] systemd[1]: Stopped Find NixOS closure.576machine # [ 4.593362] systemd[1]: Starting rw-sysroot-nix-store.service...577machine # [ 4.595601] systemd[1]: systemd-sysctl.service: Deactivated successfully.578machine # [ 4.597916] systemd[1]: Stopped Apply Kernel Variables.579machine # [ 4.599393] systemd[1]: systemd-modules-load.service: Deactivated successfully.580machine # [ 4.603872] systemd[1]: Stopped Load Kernel Modules.581machine # [ 4.608505] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.582machine # [ 4.613555] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.583machine # [ 4.616439] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.584machine # [ 4.620292] systemd[1]: Stopped Create System Files and Directories.585machine # [ 4.622191] systemd[1]: Stopped target Local File Systems.586machine # [ 4.624219] systemd[1]: Stopped target Preparation for Local File Systems.587machine # [ 4.627711] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.588machine # [ 4.630725] systemd[1]: Stopped Coldplug All udev Devices.589machine # [ 4.631730] systemd[1]: Stopping Rule-based Manager for Device Events and Files...590machine # [ 4.632965] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.591machine # [ 4.634074] systemd[1]: Stopped Virtual Console Setup.592machine # [ 4.634840] systemd[1]: systemd-udevd.service: Deactivated successfully.593machine # [ 4.635808] systemd[1]: Stopped Rule-based Manager for Device Events and Files.594machine # [ 4.640314] systemd[1]: systemd-udevd.service: Consumed 1.357s CPU time over 3.020s wall clock time, 26M memory peak.595machine # [ 4.643564] systemd[1]: initrd-cleanup.service: Deactivated successfully.596machine # [ 4.648537] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.597machine # [ 4.649762] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully.598machine # [ 4.652216] systemd[1]: Closed udev Varlink Socket.599machine # [ 4.653049] systemd[1]: Starting Cleanup udev Database...600machine # [ 4.656161] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.601machine # [ 4.657326] systemd[1]: Stopped Create Static Device Nodes in /dev.602machine # [ 4.660154] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.603machine # [ 4.661392] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.604machine # [ 4.664433] systemd[1]: kmod-static-nodes.service: Deactivated successfully.605machine # [ 4.668234] systemd[1]: Stopped Create List of Static Device Nodes.606machine # [ 4.669207] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.607machine # [ 4.670268] systemd[1]: Finished rw-sysroot-nix-store.service.608machine # [ 4.695651] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.609machine # [ 4.699720] systemd[1]: Finished Cleanup udev Database.610machine # [ 4.700921] systemd[1]: Reached target Switch Root.611machine # [ 4.704342] systemd[1]: Starting NixOS Activation...612machine # [ 4.786179] initrd-nixos-activation-start[173]: booting system configuration /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test613machine # [ 4.819405] initrd-nixos-activation-start[173]: running activation script...614machine # [ 5.068620] initrd-nixos-activation-start[196]: setting up /etc...615machine # [ 5.191557] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.616machine # [ 5.194818] systemd[1]: Finished NixOS Activation.617machine # [ 5.200275] systemd[1]: Starting Switch Root...618machine # [ 5.222223] systemd[1]: Switching root.619machine # [ 5.410650] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).620machine # [ 5.509957] NET: Registered PF_VSOCK protocol family621machine # [ 5.581794] systemd[1]: systemd 263-cf8ebfc 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 +EXPERIMENTAL-ENVVAR)622machine # [ 5.582710] systemd[1]: Detected virtualization qemu.623machine # [ 5.582754] systemd[1]: Detected architecture arm64.624machine # [ 5.582819] systemd[1]: Detected first boot.625machine # [ 5.584279] systemd[1]: Initializing machine ID from random generator.626machine # [ 5.718942] systemd[1]: bpf-restrict-fs: LSM BPF program attached627machine # [ 5.872887] systemd[1]: Applying preset policy.628machine # [ 6.072522] systemd[1]: Populated /etc with preset unit settings.629machine # [ 6.279287] systemd[1]: initrd-switch-root.service: Deactivated successfully.630machine # [ 6.279804] systemd[1]: Stopped initrd-switch-root.service.631machine # [ 6.284009] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.632machine # [ 6.288227] systemd[1]: Created slice Slice /system/getty.633machine # [ 6.291228] systemd[1]: Created slice User and Session Slice.634machine # [ 6.291884] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.635machine # [ 6.295101] systemd[1]: Started Forward Password Requests to Wall Directory Watch.636machine # [ 6.295676] systemd[1]: Expecting device /dev/hvc0...637machine # [ 6.296328] systemd[1]: Expecting device /dev/ttyAMA0...638machine # [ 6.299943] systemd[1]: Reached target Local Encrypted Volumes.639machine # [ 6.300387] systemd[1]: Stopped target initrd-fs.target.640machine # [ 6.303120] systemd[1]: Stopped target initrd-root-fs.target.641machine # [ 6.303614] systemd[1]: Stopped target initrd-switch-root.target.642machine # [ 6.304288] systemd[1]: Reached target Virtual Machines and Containers.643machine # [ 6.308131] systemd[1]: Reached target Path Units.644machine # [ 6.309884] systemd[1]: Reached target Remote File Systems.645machine # [ 6.310286] systemd[1]: Reached target Slice Units.646machine # [ 6.310958] systemd[1]: Reached target Swaps.647machine # [ 6.315075] systemd[1]: Listening on Query the User Interactively for a Password.648machine # [ 6.318204] systemd[1]: Listening on Process Core Dump Socket.649machine # [ 6.319718] systemd[1]: Listening on Credential Encryption/Decryption.650machine # [ 6.323259] systemd[1]: Listening on Factory Reset Management.651machine # [ 6.323821] systemd[1]: Listening on Hostname Service Socket.652machine # [ 6.329374] systemd[1]: Starting Journal Log Access Socket...653machine # [ 6.331124] systemd[1]: Listening on Journal Audit Socket.654machine # [ 6.335269] systemd[1]: Listening on Console Output Muting Service Socket.655machine # [ 6.336225] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.656machine # [ 6.338648] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os657machine # [ 6.339903] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki658machine # [ 6.348715] systemd[1]: Listening on Disk Repartitioning Service Socket.659machine # [ 6.349386] systemd[1]: Listening on udev Varlink Socket.660machine # [ 6.354272] systemd[1]: Mounting Huge Pages File System...661machine # [ 6.361499] systemd[1]: Mounting POSIX Message Queue File System...662machine # [ 6.372679] systemd[1]: Mounting Kernel Debug File System...663machine # [ 6.384715] systemd[1]: Mounting Kernel Trace File System...664machine # [ 6.398179] systemd[1]: Starting Create List of Static Device Nodes...665machine # [ 6.399702] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs666machine # [ 6.418283] systemd[1]: Mounting Kernel Configuration File System...667machine # [ 6.419554] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm668machine # [ 6.426059] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore669machine # [ 6.430331] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse670machine # [ 6.469793] systemd[1]: Mounting FUSE Control File System...671machine # [ 6.470231] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67672machine # [ 6.508898] systemd[1]: Starting Journal Service...673machine # [ 6.526688] systemd[1]: Starting Load Kernel Modules...674machine # [ 6.542454] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...675machine # [ 6.572703] systemd[1]: Starting Remount Root and Kernel File Systems...676machine # [ 6.573140] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os677machine # [ 6.584385] systemd[1]: Starting Coldplug All udev Devices...678machine # [ 6.588203] systemd[1]: Listening on Journal Log Access Socket.679machine # [ 6.595421] systemd[1]: Mounted Huge Pages File System.680machine # [ 6.596000] systemd[1]: Mounted POSIX Message Queue File System.681machine # [ 6.596412] systemd[1]: Mounted Kernel Debug File System.682machine # [ 6.605374] systemd[1]: Mounted Kernel Trace File System.683machine # [ 6.621683] systemd-journald[267]: Collecting audit messages is enabled.684machine # [ 6.628321] systemd[1]: Finished Create List of Static Device Nodes.685machine # [ 6.630021] systemd[1]: Mounted Kernel Configuration File System.686machine # [ 6.621612] systemd[1]: Queued start job for default target Multi-User System.687machine # [ 6.627111] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0.688machine # [ 6.637410] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0.689machine # [ 6.638850] systemd[1]: systemd-journald.service: Deactivated successfully.690machine # [ 6.660994] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...691machine # [ 6.661688] systemd[1]: Started Journal Service.692machine # [ 6.650785] systemd-modules-load[268]: Module 'atkbd' is built in693machine # [ 6.660285] systemd-modules-load[268]: Module 'loop' is built in694machine # [ 6.689565] systemd[1]: Finished Load Kernel Modules.695machine # [ 6.712773] systemd[1]: Mounted FUSE Control File System.696machine # [ 6.732796] systemd-oomd[269]: No swap; memory pressure usage will be degraded697machine # [ 6.760554] EXT4-fs (vda): re-mounted b644028f-71ef-46ee-b947-a511097ff933.698machine # [ 6.750868] systemd[1]: Starting Firewall...699machine # [ 6.759400] systemd[1]: Starting Apply Kernel Variables...700machine # [ 6.765261] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.701machine # [ 6.767498] systemd[1]: Finished Remount Root and Kernel File Systems.702machine # [ 6.782372] systemd[1]: Listening on Disk Image Download Service Socket.703machine # [ 6.792145] systemd[1]: Starting Flush Journal to Persistent Storage...704machine # [ 6.793294] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore705machine # [ 6.804173] systemd[1]: Starting Load/Save OS Random Seed...706machine # [ 6.805209] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os707machine # [ 6.940752] systemd-journald[267]: Received client request to flush runtime journal.708machine # [ 7.012570] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.709machine # [ 7.013970] systemd[1]: Starting Create Static Device Nodes in /dev...710machine # [ 7.019481] systemd[1]: Finished Load/Save OS Random Seed.711machine # [ 7.024476] systemd[1]: Reached target First Boot Complete.712machine # [ 7.025384] systemd[1]: Finished Apply Kernel Variables.713machine # [ 7.026219] systemd[1]: Finished Flush Journal to Persistent Storage.714machine # [ 7.150339] systemd[1]: Finished Create Static Device Nodes in /dev.715machine # [ 7.156150] systemd[1]: Reached target Preparation for Local File Systems.716machine # [ 7.161834] systemd[1]: Starting Rule-based Manager for Device Events and Files...717machine # [ 7.276691] systemd[1]: Mounting /run/wrappers...718machine # [ 7.313910] systemd-udevd[303]: Using default interface naming scheme 'v261'.719machine # [ 7.360637] systemd[1]: Mounted /run/wrappers.720machine # [ 7.361565] systemd[1]: Reached target Local File Systems.721machine # [ 7.374138] systemd[1]: Listening on Boot Loader Control Service Socket.722machine # [ 7.380302] systemd[1]: Starting Create SUID/SGID Wrappers...723machine # [ 7.381396] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.724machine # [ 7.390832] systemd[1]: Starting Save Transient machine-id to Disk...725machine # [ 7.404408] systemd[1]: Starting Create System Files and Directories...726machine # [ 7.569930] systemd[1]: Started Rule-based Manager for Device Events and Files.727machine # [ 7.633213] systemd[1]: Finished Create System Files and Directories.728machine # [ 7.634462] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.729machine # [ 7.655333] systemd[1]: Starting Rebuild Journal Catalog...730machine # [ 7.669879] systemd[1]: Starting Record System Boot/Shutdown in UTMP...731machine # [ 7.676177] systemd[1]: Finished Save Transient machine-id to Disk.732machine # [ 7.809574] systemd[1]: Finished Record System Boot/Shutdown in UTMP.733machine # [ 7.824199] systemd[1]: Finished Rebuild Journal Catalog.734machine # [ 7.829659] systemd[1]: Starting Update is Completed...735machine # [ 7.906577] systemd[1]: Finished Update is Completed.736machine # [ 8.194300] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs737machine # [ 8.255919] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse738machine # [ 8.381079] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.739machine # [ 8.390533] systemd[1]: Finished Create SUID/SGID Wrappers.740machine # [ 8.583151] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.741machine # [ 8.594045] systemd[1]: Finished Coldplug All udev Devices.742machine # [ 8.597663] systemd[1]: Reached target System Initialization.743machine # [ 8.598718] systemd[1]: Started Discard unused filesystem blocks once a week.744machine # [ 8.599938] systemd[1]: Started Daily Cleanup of Temporary Directories.745machine # [ 8.608538] systemd[1]: Reached target Timer Units.746machine # [ 8.609437] systemd[1]: Listening on D-Bus System Message Bus Socket.747machine # [ 8.610410] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.748machine # [ 8.611696] systemd[1]: Reached target Socket Units.749machine # [ 8.623223] systemd[1]: Reached target Basic System.750machine # [ 8.629376] systemd[1]: Starting Import lastlog data into lastlog2 database...751machine # [ 8.630627] systemd[1]: Starting Name Service Cache Daemon (nsncd)...752machine # [ 8.639669] systemd[1]: Starting Post-Boot Actions...753machine # [ 8.666174] systemd[1]: Started Reset console on configuration changes.754machine # [ 8.696260] systemd[1]: Starting resolvconf update...755machine # [ 8.700936] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.756machine # [ 8.767635] systemd[1]: Started backdoor.service.757machine # [ 8.813430] systemd[1]: Starting D-Bus System Message Bus...758machine # [ 8.817938] nsncd[427]: Oct 20 03:08:16.291 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"759machine # [ 8.832685] systemd[1]: Started Name Service Cache Daemon (nsncd).760machine # [ 8.874068] (udev-worker)[331]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.761machine # [ 8.895401] systemd[1]: Finished Post-Boot Actions.762machine # [ 8.901015] (udev-worker)[402]: Network interface NamePolicy= disabled on kernel command line.763machine # [ 8.916590] (udev-worker)[331]: Network interface NamePolicy= disabled on kernel command line.764machine # [ 8.970298] systemd[1]: Reached target Host and Network Name Lookups.765machine # [ 8.978227] systemd[1]: Reached target User and Group Name Lookups.766machine # [ 9.010130] systemd[1]: Starting User Login Management...767machine # connecting to host...768machine # [ 9.043639] systemd[1]: Finished Import lastlog data into lastlog2 database.769machine # [ 9.101905] dbus-broker-launch[437]: Looking up NSS user entry for 'systemd-timesync'...770machine # [ 9.117401] dbus-broker-launch[437]: NSS returned no entry for 'systemd-timesync'771machine # [ 9.118638] dbus-broker-launch[437]: Invalid user-name in /nix/store/lwvd8zypc7d831h4k85sqslcsqwvyagn-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"772machine: Guest shell says: b'Spawning backdoor root shell...\n'773machine: connected to guest root shell774machine: (connecting took 9.44 seconds)775machine: (finished: waiting for the VM to finish booting, in 9.86 seconds)776machine # [ 9.171753] systemd[1]: Started D-Bus System Message Bus.777machine # [ 9.237286] dbus-broker-launch[437]: Ready778machine # [ 9.328446] systemd-logind[461]: New seat seat0.779machine # [ 9.415512] systemd[1]: Started User Login Management.780machine # [ 9.460131] systemd[1]: Stopped target Host and Network Name Lookups.781machine # [ 9.466579] systemd[1]: Stopping Host and Network Name Lookups...782machine # [ 9.467888] systemd[1]: Stopped target User and Group Name Lookups.783machine # [ 9.483538] systemd[1]: Stopping User and Group Name Lookups...784machine # [ 9.488864] systemd[1]: Starting linger-users.service...785machine # [ 9.489879] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...786machine # [ 9.501868] systemd[1]: nscd.service: Deactivated successfully.787machine # [ 9.510954] systemd[1]: Stopped Name Service Cache Daemon (nsncd).788machine # [ 9.578251] systemd[1]: Starting Name Service Cache Daemon (nsncd)...789machine # [ 9.597721] systemd[1]: Condition check resulted in Virtio network device being skipped.790machine # [ 9.599214] systemd[1]: Condition check resulted in Virtio network device being skipped.791machine # [ 9.664682] systemd[1]: Finished resolvconf update.792machine # [ 9.675477] systemd[1]: Starting DHCP Client...793machine # [ 9.717694] nsncd[552]: Oct 20 03:08:17.196 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"794machine # [ 9.732345] systemd[1]: Started Name Service Cache Daemon (nsncd).795machine # [ 9.763661] systemd[1]: Created slice Slice /user/1001.796machine # [ 9.784492] systemd[1]: Reached target Host and Network Name Lookups.797machine # [ 9.785680] systemd[1]: Reached target User and Group Name Lookups.798machine # [ 9.786651] systemd[1]: Starting User Runtime Directory /run/user/1001...799machine # [ 9.858474] systemd[1]: linger-users.service: Deactivated successfully.800machine # [ 9.859625] systemd[1]: Finished linger-users.service.801machine # [ 9.885637] systemd-logind[461]: Watching system buttons on /dev/input/event0 (gpio-keys)802machine # [ 9.931078] systemd[1]: Finished User Runtime Directory /run/user/1001.803machine # [ 9.953422] systemd[1]: Starting User Manager for UID 1001...804machine # [ 10.020021] mousedev: PS/2 mouse device common for all mice805machine # [ 10.051726] systemd[1]: Finished Firewall.806machine # [ 10.065315] systemd[1]: Reached target Preparation for Network.807machine # [ 10.066338] systemd[1]: Starting Address configuration of eth1...808machine # [ 10.067264] systemd[1]: Starting Extra networking commands....809machine # [ 10.140670] systemd-logind[461]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)810machine # [ 10.166845] (systemd)[587]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)811machine # [ 10.207983] systemd-logind[461]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.812machine # [ 10.268746] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy813machine # [ 10.493909] systemd[587]: Queued start job for default target Main User Target.814machine # [ 10.514279] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy815machine # [ 10.520400] systemd[587]: Created slice User Application Slice.816machine # [ 10.521473] systemd[587]: Started Daily Cleanup of User's Temporary Directories.817machine # [ 10.522621] systemd[587]: Reached target Paths.818machine # [ 10.523337] systemd[587]: Reached target Timers.819machine # [ 10.562805] systemd[587]: Starting D-Bus User Message Bus Socket...820machine # [ 10.568655] systemd[587]: Starting Create User Files and Directories...821machine # [ 10.574908] systemd[587]: Finished Create User Files and Directories.822machine # [ 10.584158] systemd[587]: Listening on D-Bus User Message Bus Socket.823machine # [ 10.585271] systemd[587]: Reached target Sockets.824machine # [ 10.597394] systemd[587]: Reached target Basic System.825machine # [ 10.598282] systemd[1]: Started User Manager for UID 1001.826machine # [ 10.599297] systemd[587]: Starting Run user-specific NixOS activation...827machine # [ 10.624694] dhcpcd[588]: dhcpcd-10.3.2 starting828machine # [ 10.658612] network-addresses-eth1-start[592]: adding address 192.168.1.1/24... done829machine # [ 10.681273] dhcpcd[620]: dev: loaded udev830machine # [ 10.682138] systemd[587]: Finished Run user-specific NixOS activation.831machine # [ 10.683176] systemd[587]: Reached target Main User Target.832machine # [ 10.713554] 8021q: 802.1Q VLAN Support v1.8833machine # [ 10.713972] 8021q: adding VLAN 0 to HW filter on device eth1834machine # [ 10.723948] systemd[587]: Startup finished in 461ms.835machine # [ 10.745650] network-addresses-eth1-start[592]: adding address 2001:db8:1::1/64... done836machine # [ 10.853883] cfg80211: Loading compiled-in X.509 certificates for regulatory database837machine # [ 10.859015] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'838machine # [ 10.859555] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'839machine # [ 10.864071] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2840machine # [ 10.864416] cfg80211: failed to load regulatory.db841machine # [ 10.939841] systemd[1]: Finished Address configuration of eth1.842machine # [ 11.229080] dhcpcd[667]: /nix/store/48w99jvsb41hnyml943xqzrklzdb5fww-openresolv-3.17.4/sbin/.resolvconf-wrapped: line 1250: kill: (601) - Operation not permitted843machine # [ 11.231294] dhcpcd[667]: .resolvconf-wrapped: clearing stale lock pid 601844machine # [ 11.492849] 8021q: adding VLAN 0 to HW filter on device eth0845machine # [ 11.486910] dhcpcd[620]: eth0: waiting for carrier846machine # [ 11.487936] dhcpcd[620]: eth0: carrier acquired847machine # [ 11.567920] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3848machine # [ 11.584872] dhcpcd[620]: DUID 00:01:00:01:32:69:97:a3:52:54:00:12:34:56849machine # [ 11.586004] dhcpcd[620]: eth0: IAID 00:12:34:56850machine # [ 11.586729] dhcpcd[620]: eth0: adding address fe80::5054:ff:fe12:3456851machine # [ 11.622188] systemd[1]: Finished Extra networking commands..852machine # [ 11.623312] systemd[1]: Reached target Network.853machine # [ 11.624005] systemd[1]: Starting Permit User Sessions...854machine # [ 11.910231] systemd[1]: Finished Permit User Sessions.855machine # [ 11.945337] systemd[1]: Started Getty on tty1.856machine # [ 11.946218] systemd[1]: Reached target Login Prompts.857machine # [ 11.969576] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.858machine # [ 12.000824] systemd[1]: Starting Virtual Console Setup...859machine # [ 12.018770] systemd-logind[461]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)860machine # [ 12.035881] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.861machine # [ 12.040387] systemd[1]: Stopped Virtual Console Setup.862machine # [ 12.046678] systemd[1]: Starting Virtual Console Setup...863machine # [ 12.156765] systemd-vconsole-setup[708]: Configuration of first virtual console was skipped, ignoring remaining ones.864machine # [ 12.162894] systemd[1]: Finished Virtual Console Setup.865machine # [ 13.224385] dhcpcd[620]: eth0: soliciting a DHCP lease866machine # [ 13.226554] dhcpcd[620]: eth0: offered 10.0.2.15 from 10.0.2.2867machine # [ 13.228706] dhcpcd[620]: eth0: probing address 10.0.2.15/24868machine # [ 14.349400] dhcpcd[620]: eth0: soliciting an IPv6 router869machine # [ 14.351637] dhcpcd[620]: eth0: Router Advertisement from fe80::2870machine # [ 14.353309] dhcpcd[620]: eth0: adding address fec0::5054:ff:fe12:3456/64871machine # [ 14.354793] dhcpcd[620]: eth0: adding route to fec0::/64872machine # [ 14.356003] dhcpcd[620]: eth0: adding default route via fe80::2873machine # [ 17.961790] dhcpcd[620]: eth0: leased 10.0.2.15 for 86400 seconds874machine # [ 17.961993] dhcpcd[620]: eth0: adding route to 10.0.2.0/24875machine # [ 17.962114] dhcpcd[620]: eth0: adding default route via 10.0.2.2876machine # [ 18.065087] systemd[1]: Started DHCP Client.877machine # [ 18.067538] systemd[1]: Reached target Multi-User System.878machine # [ 18.069117] systemd[1]: Startup finished in 1.103s (kernel) + 4.370s (initrd) + 12.594s (userspace) = 18.067s.879machine: (finished: waiting for unit multi-user.target, in 19.48 seconds)880machine: must succeed: /nix/store/kabiwhgyb1j3wv90hzpkic8zvq2cw8s3-stderr-runner /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/bin/switch-to-configuration test881machine # Checking switch inhibitors... done882machine # [ 18.988344] nixos[834]: switching to system configuration /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test883machine # [ 18.991194] systemd[1]: Stopped target Local File Systems.884machine # [ 18.993199] systemd[1]: Stopped target Remote File Systems.885machine # activating the configuration...886machine # setting up /etc...887machine # [ 19.303608] systemd[1]: Reload requested from client PID 834 ('.switch-to-conf') (unit backdoor.service)...888machine # [ 19.305889] systemd[1]: Reloading...889machine # [ 19.659281] systemd[1]: Reloading finished in 351 ms.890machine # reloading user units for usertest...891machine # [ 19.718287] systemd[587]: Created slice User Core Session Slice.892machine # [ 19.722264] systemd[587]: Starting D-Bus User Message Bus...893machine # [ 19.756885] dbus-broker-launch[904]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/lwvd8zypc7d831h4k85sqslcsqwvyagn-system-path/share/dbus-1/services/org.freedesktop.import1.service'894machine # [ 19.760151] dbus-broker-launch[904]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/lwvd8zypc7d831h4k85sqslcsqwvyagn-system-path/share/dbus-1/services/org.freedesktop.machine1.service'895machine # [ 19.763135] dbus-broker-launch[904]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/lwvd8zypc7d831h4k85sqslcsqwvyagn-system-path/share/dbus-1/services/org.freedesktop.portable1.service'896machine # [ 19.766276] dbus-broker-launch[904]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/lwvd8zypc7d831h4k85sqslcsqwvyagn-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'897machine # [ 19.772367] systemd[587]: Started D-Bus User Message Bus.898machine # [ 19.773244] dbus-broker-launch[904]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored899machine # [ 19.774964] dbus-broker-launch[904]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored900machine # [ 19.780347] dbus-broker-launch[904]: Ready901machine # [ 19.832327] systemd[587]: Reexecution requested from client PID 903 ('switch-to-confi')...902machine # [ 19.835653] systemd[587]: Reexecuting.903machine # [ 19.895817] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy904machine # [ 20.018705] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy905machine # [ 20.027574] systemd[587]: Reload requested from client PID 903 ('switch-to-confi')...906machine # [ 20.032351] systemd[587]: Reloading...907machine # [ 20.158063] systemd[587]: Reloading finished in 127 ms.908machine # [ 20.172244] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy909machine # restarting the following user units: nixos-activation.service910machine # [ 20.184783] systemd[587]: Stopped Run user-specific NixOS activation.911machine # [ 20.187765] systemd[587]: Stopping Run user-specific NixOS activation...912machine # [ 20.190426] systemd[587]: Starting Run user-specific NixOS activation...913machine # [ 20.229933] systemd[587]: Finished Run user-specific NixOS activation.914machine # restarting sysinit-reactivation.target915machine # [ 20.245155] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....916machine # [ 20.359598] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..917machine # [ 20.362340] systemd[1]: Reached target Reactivate sysinit units.918machine # [ 20.371816] systemd[1]: Reached target Remote File Systems.919machine # [ 20.376248] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os920machine # [ 20.377779] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki921machine # [ 20.381250] systemd[1]: Starting linger-users.service...922machine # [ 20.385259] systemd[1]: Reached target Local File Systems.923machine # [ 20.394708] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore924machine # [ 20.406004] systemd[1]: Starting Create SUID/SGID Wrappers...925machine # [ 20.407122] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.926machine # [ 20.414461] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67927machine # [ 20.417597] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore928machine # [ 20.419397] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os929machine # [ 20.420878] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os930machine # [ 20.493228] systemd[1]: linger-users.service: Deactivated successfully.931machine # [ 20.495957] systemd[1]: Finished linger-users.service.932machine # [ 20.645783] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.933machine # [ 20.649310] systemd[1]: Finished Create SUID/SGID Wrappers.934machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service935machine # [ 20.907696] nixos[834]: finished switching to system configuration /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test936machine: (finished: must succeed: /nix/store/kabiwhgyb1j3wv90hzpkic8zvq2cw8s3-stderr-runner /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/bin/switch-to-configuration test, in 2.16 seconds)937machine: must succeed: rm /etc/NIXOS938machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds)939machine: must succeed: /nix/store/kabiwhgyb1j3wv90hzpkic8zvq2cw8s3-stderr-runner /nix/store/vyc9823rbn3dgdj17a37652ajil02dnv-nixos-system-other-test/bin/switch-to-configuration test940machine # this should succeed (config: /nix/store/vyc9823rbn3dgdj17a37652ajil02dnv-nixos-system-other-test, action: test)941machine # Checking switch inhibitors... done942machine # [ 21.204820] nixos[982]: switching to system configuration /nix/store/vyc9823rbn3dgdj17a37652ajil02dnv-nixos-system-other-test943machine # [ 21.207540] systemd[1]: Stopped target Local File Systems.944machine # activating the configuration...945machine # [ 21.210358] systemd[1]: Stopped target Remote File Systems.946machine # removing user ‘usertest’947machine # setting up /etc...948machine # [ 21.539149] systemd[1]: Reload requested from client PID 982 ('.switch-to-conf') (unit backdoor.service)...949machine # [ 21.541436] systemd[1]: Reloading...950machine # [ 21.915981] systemd[1]: Reloading finished in 373 ms.951machine # reloading user units for usertest...952machine # [ 22.016791] systemd[587]: Reexecution requested from client PID 1053 ('switch-to-confi')...953machine # [ 22.020169] systemd[587]: Reexecuting.954machine # [ 22.071990] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy955machine # [ 22.192722] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy956machine # [ 22.199968] systemd[587]: Reload requested from client PID 1053 ('switch-to-confi')...957machine # [ 22.202423] systemd[587]: Reloading...958machine # [ 22.317971] systemd[587]: Reloading finished in 114 ms.959machine # [ 22.332919] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy960machine # restarting the following user units: nixos-activation.service961machine # [ 22.349231] systemd[587]: Stopped Run user-specific NixOS activation.962machine # [ 22.352110] systemd[587]: Stopping Run user-specific NixOS activation...963machine # [ 22.354549] systemd[587]: Starting Run user-specific NixOS activation...964machine # [ 22.392228] systemd[587]: Finished Run user-specific NixOS activation.965machine # restarting sysinit-reactivation.target966machine # [ 22.397117] systemd[1]: Stopped target Reactivate sysinit units.967machine # reloading the following units: dbus-broker.service968machine # [ 22.401740] systemd[1]: Stopping Reactivate sysinit units...969machine # [ 22.402663] systemd[1]: Reached target Reactivate sysinit units.970machine # [ 22.403922] dbus-broker-launch[437]: Caught SIGHUP, trigger reload.971machine # [ 22.407197] systemd[1]: Reloading D-Bus System Message Bus...972machine # [ 22.412419] dbus-broker-launch[437]: Looking up NSS user entry for 'systemd-timesync'...973machine # [ 22.414764] dbus-broker-launch[437]: NSS returned no entry for 'systemd-timesync'974machine # [ 22.416005] dbus-broker-launch[437]: Invalid user-name in /nix/store/dywaqxfqn4w86xba4frwwckp6r9njfan-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"975machine # [ 22.434797] systemd[1]: Reloaded D-Bus System Message Bus.976machine # restarting the following units: network-addresses-eth1.service977machine # [ 22.443084] systemd[1]: Stopping Address configuration of eth1...978machine # [ 22.478671] network-addresses-eth1-pre-stop[1065]: deleting address 192.168.1.1/24... done979machine # [ 22.486268] network-addresses-eth1-pre-stop[1065]: deleting address 2001:db8:1::1/64... done980machine # [ 22.494736] systemd[1]: network-addresses-eth1.service: Deactivated successfully.981machine # [ 22.497620] systemd[1]: Stopped Address configuration of eth1.982machine # [ 22.503378] systemd[1]: Starting Address configuration of eth1...983machine # [ 22.553703] network-addresses-eth1-start[1070]: adding address 192.168.1.2/24... done984machine # [ 22.562443] network-addresses-eth1-start[1070]: adding address 2001:db8:1::2/64... done985machine # [ 22.576316] systemd[1]: Finished Address configuration of eth1.986machine # [ 22.586238] systemd[1]: Reached target Local File Systems.987machine # [ 22.590110] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore988machine # [ 22.599558] systemd[1]: Starting Create SUID/SGID Wrappers...989machine # [ 22.605302] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.990machine # [ 22.606745] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67991machine # [ 22.613126] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore992machine # [ 22.615058] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os993machine # [ 22.623045] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os994machine # [ 22.626992] systemd[1]: Reached target Remote File Systems.995machine # [ 22.627948] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os996machine # [ 22.629536] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki997machine # [ 22.858240] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.998machine # [ 22.860649] systemd[1]: Finished Create SUID/SGID Wrappers.999machine # [ 22.866999] systemd[1]: Starting linger-users.service...1000machine # [ 22.902283] linger-users-start[1142]: id: ‘usertest’: no such user1001machine # [ 22.904970] linger-users-start[1140]: Removing linger for missing user usertest1002machine # [ 22.914253] systemd[1]: linger-users.service: Deactivated successfully.1003machine # [ 22.916561] systemd[1]: Finished linger-users.service.1004machine # [ 23.175452] nixos[982]: finished switching to system configuration /nix/store/vyc9823rbn3dgdj17a37652ajil02dnv-nixos-system-other-test1005machine: (finished: must succeed: /nix/store/kabiwhgyb1j3wv90hzpkic8zvq2cw8s3-stderr-runner /nix/store/vyc9823rbn3dgdj17a37652ajil02dnv-nixos-system-other-test/bin/switch-to-configuration test, in 2.24 seconds)1006subtest: services1007machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/bin/switch-to-configuration test 2>&11008machine # [ 23.421623] nixos[1148]: switching to system configuration /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test1009machine # [ 23.424574] systemd[1]: Stopped target Remote File Systems.1010machine # [ 23.427198] systemd[1]: Stopped target Local File Systems.1011machine # [ 23.748814] systemd[1]: Reload requested from client PID 1148 ('.switch-to-conf') (unit backdoor.service)...1012machine # [ 23.750565] systemd[1]: Reloading...1013machine # [ 24.141707] systemd[1]: Reloading finished in 387 ms.1014machine # [ 24.286453] systemd[587]: Reexecution requested from client PID 1217 ('switch-to-confi')...1015machine # [ 24.288981] systemd[587]: Reexecuting.1016machine # [ 24.343933] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1017machine # [ 24.484422] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1018machine # [ 24.494096] systemd[587]: Reload requested from client PID 1217 ('switch-to-confi')...1019machine # [ 24.496377] systemd[587]: Reloading...1020machine # [ 24.649608] systemd[587]: Reloading finished in 152 ms.1021machine # [ 24.664313] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1022machine # [ 24.676306] systemd[587]: Stopped Run user-specific NixOS activation.1023machine # [ 24.679518] systemd[587]: Stopping Run user-specific NixOS activation...1024machine # [ 24.682668] systemd[587]: Starting Run user-specific NixOS activation...1025machine # [ 24.721400] systemd[587]: Finished Run user-specific NixOS activation.1026machine # [ 24.727661] systemd[1]: Stopped target Reactivate sysinit units.1027machine # [ 24.730030] systemd[1]: Stopping Reactivate sysinit units...1028machine # [ 24.731067] systemd[1]: Reached target Reactivate sysinit units.1029machine # [ 24.736458] systemd[1]: Reloading D-Bus System Message Bus...1030machine # [ 24.737948] dbus-broker-launch[437]: Caught SIGHUP, trigger reload.1031machine # [ 24.742576] dbus-broker-launch[437]: Looking up NSS user entry for 'systemd-timesync'...1032machine # [ 24.745359] dbus-broker-launch[437]: NSS returned no entry for 'systemd-timesync'1033machine # [ 24.746626] dbus-broker-launch[437]: Invalid user-name in /nix/store/lwvd8zypc7d831h4k85sqslcsqwvyagn-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1034machine # [ 24.774951] systemd[1]: Reloaded D-Bus System Message Bus.1035machine # [ 24.782193] systemd[1]: Stopping Address configuration of eth1...1036machine # [ 24.817594] network-addresses-eth1-pre-stop[1230]: deleting address 192.168.1.2/24... done1037machine # [ 24.824838] network-addresses-eth1-pre-stop[1230]: deleting address 2001:db8:1::2/64... done1038machine # [ 24.832569] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1039machine # [ 24.834986] systemd[1]: Stopped Address configuration of eth1.1040machine # [ 24.839790] systemd[1]: Starting Address configuration of eth1...1041machine # [ 24.891725] network-addresses-eth1-start[1235]: adding address 192.168.1.1/24... done1042machine # [ 24.904233] network-addresses-eth1-start[1235]: adding address 2001:db8:1::1/64... done1043machine # [ 24.920775] systemd[1]: Finished Address configuration of eth1.1044machine # [ 24.926385] systemd[1]: Reached target Local File Systems.1045machine # [ 24.933020] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1046machine # [ 24.940727] systemd[1]: Starting Create SUID/SGID Wrappers...1047machine # [ 24.941742] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1048machine # [ 24.953266] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671049machine # [ 24.955738] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1050machine # [ 24.965663] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1051machine # [ 24.967373] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1052machine # [ 24.971826] systemd[1]: Reached target Remote File Systems.1053machine # [ 24.972941] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1054machine # [ 24.974269] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1055machine # [ 25.192585] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1056machine # [ 25.195750] systemd[1]: Finished Create SUID/SGID Wrappers.1057machine # [ 25.202879] systemd[1]: Starting linger-users.service...1058machine # [ 25.249868] systemd[1]: linger-users.service: Deactivated successfully.1059machine # [ 25.253310] systemd[1]: Finished linger-users.service.1060machine # [ 25.509913] nixos[1148]: finished switching to system configuration /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test1061machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.33 seconds)1062machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/bin/switch-to-configuration test 2>&11063machine # [ 25.739345] nixos[1304]: switching to system configuration /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test1064machine # [ 25.742057] systemd[1]: Stopped target Local File Systems.1065machine # [ 25.745075] systemd[1]: Stopped target Remote File Systems.1066machine # [ 26.052729] systemd[1]: Reload requested from client PID 1304 ('.switch-to-conf') (unit backdoor.service)...1067machine # [ 26.054366] systemd[1]: Reloading...1068machine # [ 26.406949] systemd[1]: Reloading finished in 350 ms.1069machine # [ 26.513701] systemd[587]: Reexecution requested from client PID 1372 ('switch-to-confi')...1070machine # [ 26.518055] systemd[587]: Reexecuting.1071machine # [ 26.571787] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1072machine # [ 26.692632] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1073machine # [ 26.700655] systemd[587]: Reload requested from client PID 1372 ('switch-to-confi')...1074machine # [ 26.705248] systemd[587]: Reloading...1075machine # [ 26.823303] systemd[587]: Reloading finished in 119 ms.1076machine # [ 26.840274] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1077machine # [ 26.850954] systemd[587]: Stopped Run user-specific NixOS activation.1078machine # [ 26.853574] systemd[587]: Stopping Run user-specific NixOS activation...1079machine # [ 26.856167] systemd[587]: Starting Run user-specific NixOS activation...1080machine # [ 26.892718] systemd[587]: Finished Run user-specific NixOS activation.1081machine # [ 26.897338] systemd[1]: Stopped target Reactivate sysinit units.1082machine # [ 26.899446] systemd[1]: Stopping Reactivate sysinit units...1083machine # [ 26.904921] systemd[1]: Reached target Reactivate sysinit units.1084machine # [ 26.905915] systemd[1]: Reached target Remote File Systems.1085machine # [ 26.908138] systemd[1]: Starting linger-users.service...1086machine # [ 26.912416] systemd[1]: Reached target Local File Systems.1087machine # [ 26.924747] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1088machine # [ 26.926253] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1089machine # [ 26.927791] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1090machine # [ 26.944625] systemd[1]: Starting Create SUID/SGID Wrappers...1091machine # [ 26.945700] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1092machine # [ 26.952797] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671093machine # [ 26.957600] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1094machine # [ 26.959482] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1095machine # [ 26.966293] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1096machine # [ 27.009888] systemd[1]: linger-users.service: Deactivated successfully.1097machine # [ 27.014533] systemd[1]: Finished linger-users.service.1098machine # [ 27.165851] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1099machine # [ 27.170269] systemd[1]: Finished Create SUID/SGID Wrappers.1100machine # [ 27.425676] nixos[1304]: finished switching to system configuration /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test1101machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.91 seconds)1102machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&11103machine # [ 27.668955] nixos[1445]: switching to system configuration /nix/store/mrc4p9kx9rs2w8glis3kbhjvg1v952np-nixos-system-machine-test1104machine # [ 27.672425] systemd[1]: Stopped target Local File Systems.1105machine # [ 27.675645] systemd[1]: Stopped target Remote File Systems.1106machine # [ 28.006999] systemd[1]: Reload requested from client PID 1445 ('.switch-to-conf') (unit backdoor.service)...1107machine # [ 28.009274] systemd[1]: Reloading...1108machine # [ 28.425735] systemd[1]: Reloading finished in 414 ms.1109machine # [ 28.533077] systemd[587]: Reexecution requested from client PID 1514 ('switch-to-confi')...1110machine # [ 28.537759] systemd[587]: Reexecuting.1111machine # [ 28.583566] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1112machine # [ 28.713695] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1113machine # [ 28.721124] systemd[587]: Reload requested from client PID 1514 ('switch-to-confi')...1114machine # [ 28.725223] systemd[587]: Reloading...1115machine # [ 28.841972] systemd[587]: Reloading finished in 117 ms.1116machine # [ 28.856197] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1117machine # [ 28.867415] systemd[587]: Stopped Run user-specific NixOS activation.1118machine # [ 28.870005] systemd[587]: Stopping Run user-specific NixOS activation...1119machine # [ 28.872922] systemd[587]: Starting Run user-specific NixOS activation...1120machine # [ 28.906517] systemd[587]: Finished Run user-specific NixOS activation.1121machine # [ 28.911651] systemd[1]: Stopped target Reactivate sysinit units.1122machine # [ 28.913264] systemd[1]: Stopping Reactivate sysinit units...1123machine # [ 28.914151] systemd[1]: Reached target Reactivate sysinit units.1124machine # [ 28.919869] systemd[1]: Reached target Local File Systems.1125machine # [ 28.923638] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1126machine # [ 28.928222] systemd[1]: Starting Create SUID/SGID Wrappers...1127machine # [ 28.929261] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1128machine # [ 28.930664] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671129machine # [ 28.940926] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1130machine # [ 28.942960] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1131machine # [ 28.952897] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1132machine # [ 28.954372] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1133machine # [ 28.955792] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1134machine # [ 28.957669] systemd[1]: Reached target Remote File Systems.1135machine # [ 29.133127] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1136machine # [ 29.136310] systemd[1]: Finished Create SUID/SGID Wrappers.1137machine # [ 29.141919] systemd[1]: Starting linger-users.service...1138machine # [ 29.145241] systemd[1]: Starting test.service...1139machine # [ 29.188428] systemd[1]: Finished test.service.1140machine # [ 29.211069] systemd[1]: linger-users.service: Deactivated successfully.1141machine # [ 29.214366] systemd[1]: Finished linger-users.service.1142machine # [ 29.471980] nixos[1445]: finished switching to system configuration /nix/store/mrc4p9kx9rs2w8glis3kbhjvg1v952np-nixos-system-machine-test1143machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 2.05 seconds)1144machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&11145machine # [ 29.721427] nixos[1587]: switching to system configuration /nix/store/mrc4p9kx9rs2w8glis3kbhjvg1v952np-nixos-system-machine-test1146machine # [ 29.724722] systemd[1]: Stopped target Local File Systems.1147machine # [ 29.726534] systemd[1]: Stopped target Remote File Systems.1148machine # [ 30.030703] systemd[1]: Reload requested from client PID 1587 ('.switch-to-conf') (unit backdoor.service)...1149machine # [ 30.032981] systemd[1]: Reloading...1150machine # [ 30.402605] systemd[1]: Reloading finished in 366 ms.1151machine # [ 30.498731] systemd[587]: Reexecution requested from client PID 1656 ('switch-to-confi')...1152machine # [ 30.501092] systemd[587]: Reexecuting.1153machine # [ 30.560376] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1154machine # [ 30.685820] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1155machine # [ 30.698032] systemd[587]: Reload requested from client PID 1656 ('switch-to-confi')...1156machine # [ 30.707858] systemd[587]: Reloading...1157machine # [ 30.893797] systemd[587]: Reloading finished in 192 ms.1158machine # [ 30.908317] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1159machine # [ 30.920733] systemd[587]: Stopped Run user-specific NixOS activation.1160machine # [ 30.923784] systemd[587]: Stopping Run user-specific NixOS activation...1161machine # [ 30.926666] systemd[587]: Starting Run user-specific NixOS activation...1162machine # [ 30.965759] systemd[587]: Finished Run user-specific NixOS activation.1163machine # [ 30.971101] systemd[1]: Stopped target Reactivate sysinit units.1164machine # [ 30.974394] systemd[1]: Stopping Reactivate sysinit units...1165machine # [ 30.975514] systemd[1]: Reached target Reactivate sysinit units.1166machine # [ 30.980297] systemd[1]: Reached target Remote File Systems.1167machine # [ 30.985571] systemd[1]: Starting linger-users.service...1168machine # [ 30.996230] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1169machine # [ 30.997818] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1170machine # [ 31.003129] systemd[1]: Reached target Local File Systems.1171machine # [ 31.006653] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1172machine # [ 31.016597] systemd[1]: Starting Create SUID/SGID Wrappers...1173machine # [ 31.017759] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1174machine # [ 31.019486] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671175machine # [ 31.036530] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1176machine # [ 31.038386] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1177machine # [ 31.039954] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1178machine # [ 31.098964] systemd[1]: linger-users.service: Deactivated successfully.1179machine # [ 31.102302] systemd[1]: Finished linger-users.service.1180machine # [ 31.302654] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1181machine # [ 31.306167] systemd[1]: Finished Create SUID/SGID Wrappers.1182machine # [ 31.563962] nixos[1587]: finished switching to system configuration /nix/store/mrc4p9kx9rs2w8glis3kbhjvg1v952np-nixos-system-machine-test1183machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 2.09 seconds)1184machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&11185machine # [ 31.844854] nixos[1728]: switching to system configuration /nix/store/pwkg7l5crd1nxgnwn4mnw39l97c1f9xy-nixos-system-machine-test1186machine # [ 31.847972] systemd[1]: Stopped target Local File Systems.1187machine # [ 31.851073] systemd[1]: Stopped target Remote File Systems.1188machine # [ 32.197501] systemd[1]: Reload requested from client PID 1728 ('.switch-to-conf') (unit backdoor.service)...1189machine # [ 32.199727] systemd[1]: Reloading...1190machine # [ 32.641045] systemd[1]: Reloading finished in 439 ms.1191machine # [ 32.746124] systemd[587]: Reexecution requested from client PID 1797 ('switch-to-confi')...1192machine # [ 32.750685] systemd[587]: Reexecuting.1193machine # [ 32.800288] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1194machine # [ 32.927921] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1195machine # [ 32.936291] systemd[587]: Reload requested from client PID 1797 ('switch-to-confi')...1196machine # [ 32.941282] systemd[587]: Reloading...1197machine # [ 33.064073] systemd[587]: Reloading finished in 124 ms.1198machine # [ 33.080269] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1199machine # [ 33.091182] systemd[587]: Stopped Run user-specific NixOS activation.1200machine # [ 33.093952] systemd[587]: Stopping Run user-specific NixOS activation...1201machine # [ 33.096398] systemd[587]: Starting Run user-specific NixOS activation...1202machine # [ 33.136294] systemd[587]: Finished Run user-specific NixOS activation.1203machine # [ 33.141320] systemd[1]: Stopped target Reactivate sysinit units.1204machine # [ 33.145226] systemd[1]: Stopping Reactivate sysinit units...1205machine # [ 33.146306] systemd[1]: Reached target Reactivate sysinit units.1206machine # [ 33.148636] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1207machine # [ 33.150178] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1208machine # [ 33.154629] systemd[1]: Reached target Remote File Systems.1209machine # [ 33.157823] systemd[1]: Reached target Local File Systems.1210machine # [ 33.163778] systemd[1]: Starting linger-users.service...1211machine # [ 33.170755] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1212machine # [ 33.183675] systemd[1]: Starting Create SUID/SGID Wrappers...1213machine # [ 33.189608] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1214machine # [ 33.191127] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671215machine # [ 33.199975] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1216machine # [ 33.206006] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1217machine # [ 33.207639] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1218machine # [ 33.270139] systemd[1]: linger-users.service: Deactivated successfully.1219machine # [ 33.274023] systemd[1]: Finished linger-users.service.1220machine # [ 33.478043] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1221machine # [ 33.482262] systemd[1]: Finished Create SUID/SGID Wrappers.1222machine # [ 33.740162] nixos[1728]: finished switching to system configuration /nix/store/pwkg7l5crd1nxgnwn4mnw39l97c1f9xy-nixos-system-machine-test1223machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&1, in 2.17 seconds)1224machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&11225machine # [ 34.021024] nixos[1868]: switching to system configuration /nix/store/rcd74gbcbvclm8kbhf20l2fxwyrds99l-nixos-system-machine-test1226machine # [ 34.030593] systemd[1]: Stopped target Local File Systems.1227machine # [ 34.031916] systemd[1]: Stopped target Remote File Systems.1228machine # [ 34.037833] systemd[1]: test.service: Deactivated successfully.1229machine # [ 34.038925] systemd[1]: Stopped Test unit.1230machine # [ 34.483273] systemd[1]: Reload requested from client PID 1868 ('.switch-to-conf') (unit backdoor.service)...1231machine # [ 34.485742] systemd[1]: Reloading...1232machine # [ 35.049620] systemd[1]: Reloading finished in 562 ms.1233machine # [ 35.150435] systemd[587]: Reexecution requested from client PID 1937 ('switch-to-confi')...1234machine # [ 35.153070] systemd[587]: Reexecuting.1235machine # [ 35.203827] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1236machine # [ 35.328345] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1237machine # [ 35.336696] systemd[587]: Reload requested from client PID 1937 ('switch-to-confi')...1238machine # [ 35.341096] systemd[587]: Reloading...1239machine # [ 35.460923] systemd[587]: Reloading finished in 120 ms.1240machine # [ 35.476293] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1241machine # [ 35.486944] systemd[587]: Stopped Run user-specific NixOS activation.1242machine # [ 35.489984] systemd[587]: Stopping Run user-specific NixOS activation...1243machine # [ 35.492375] systemd[587]: Starting Run user-specific NixOS activation...1244machine # [ 35.530070] systemd[587]: Finished Run user-specific NixOS activation.1245machine # [ 35.537464] systemd[1]: Stopped target Reactivate sysinit units.1246machine # [ 35.538641] systemd[1]: Stopping Reactivate sysinit units...1247machine # [ 35.539675] systemd[1]: Reached target Reactivate sysinit units.1248machine # [ 35.554119] systemd[1]: Reached target Remote File Systems.1249machine # [ 35.555203] systemd[1]: Reached target Local File Systems.1250machine # [ 35.563674] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1251machine # [ 35.574504] systemd[1]: Starting Create SUID/SGID Wrappers...1252machine # [ 35.575679] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1253machine # [ 35.578901] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671254machine # [ 35.582248] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1255machine # [ 35.584654] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1256machine # [ 35.586022] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1257machine # [ 35.588373] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1258machine # [ 35.591085] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1259machine # [ 35.794806] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1260machine # [ 35.797185] systemd[1]: Finished Create SUID/SGID Wrappers.1261machine # [ 35.804538] systemd[1]: Starting linger-users.service...1262machine # [ 35.810627] systemd[1]: Starting test.service...1263machine # [ 35.859030] systemd[1]: Finished test.service.1264machine # [ 35.885182] systemd[1]: linger-users.service: Deactivated successfully.1265machine # [ 35.887970] systemd[1]: Finished linger-users.service.1266machine # [ 36.146303] nixos[1868]: finished switching to system configuration /nix/store/rcd74gbcbvclm8kbhf20l2fxwyrds99l-nixos-system-machine-test1267machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 2.41 seconds)1268machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&11269machine # [ 36.605784] nixos[2011]: switching to system configuration /nix/store/g4z5yys9wjkvy6hdkvy00nrgjm02yvnf-nixos-system-machine-test1270machine # [ 36.610433] systemd[1]: Stopped target Remote File Systems.1271machine # [ 36.613209] systemd[1]: Stopped target Local File Systems.1272machine # [ 37.137446] systemd[1]: Reload requested from client PID 2011 ('.switch-to-conf') (unit backdoor.service)...1273machine # [ 37.142990] systemd[1]: Reloading...1274machine # [ 37.756952] systemd[1]: Reloading finished in 610 ms.1275machine # [ 37.905560] systemd[587]: Reexecution requested from client PID 2080 ('switch-to-confi')...1276machine # [ 37.911266] systemd[587]: Reexecuting.1277machine # [ 37.972230] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1278machine # [ 38.114529] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1279machine # [ 38.122813] systemd[587]: Reload requested from client PID 2080 ('switch-to-confi')...1280machine # [ 38.126926] systemd[587]: Reloading...1281machine # [ 38.247437] systemd[587]: Reloading finished in 121 ms.1282machine # [ 38.264203] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1283machine # [ 38.275557] systemd[587]: Stopped Run user-specific NixOS activation.1284machine # [ 38.278303] systemd[587]: Stopping Run user-specific NixOS activation...1285machine # [ 38.280851] systemd[587]: Starting Run user-specific NixOS activation...1286machine # [ 38.320082] systemd[587]: Finished Run user-specific NixOS activation.1287machine # [ 38.325954] systemd[1]: Stopped target Reactivate sysinit units.1288machine # [ 38.329132] systemd[1]: Stopping Reactivate sysinit units...1289machine # [ 38.330086] systemd[1]: Reached target Reactivate sysinit units.1290machine # [ 38.334455] systemd[1]: test.service: Deactivated successfully.1291machine # [ 38.335654] systemd[1]: Stopped test.service.1292machine # [ 38.340473] systemd[1]: Stopping test.service...1293machine # [ 38.342288] systemd[1]: Starting test.service...1294machine # [ 38.364490] systemd[1]: Finished test.service.1295machine # [ 38.372182] systemd[1]: Reached target Local File Systems.1296machine # [ 38.377762] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1297machine # [ 38.388272] systemd[1]: Starting Create SUID/SGID Wrappers...1298machine # [ 38.389443] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1299machine # [ 38.390957] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671300machine # [ 38.401155] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1301machine # [ 38.403061] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1302machine # [ 38.411761] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1303machine # [ 38.414476] systemd[1]: Reached target Remote File Systems.1304machine # [ 38.422368] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1305machine # [ 38.425688] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1306machine # [ 38.638338] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1307machine # [ 38.641670] systemd[1]: Finished Create SUID/SGID Wrappers.1308machine # [ 38.649752] systemd[1]: Starting linger-users.service...1309machine # [ 38.721163] systemd[1]: linger-users.service: Deactivated successfully.1310machine # [ 38.723991] systemd[1]: Finished linger-users.service.1311machine # [ 38.981978] nixos[2011]: finished switching to system configuration /nix/store/g4z5yys9wjkvy6hdkvy00nrgjm02yvnf-nixos-system-machine-test1312machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 2.84 seconds)1313machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&11314machine # [ 39.245801] nixos[2154]: switching to system configuration /nix/store/b6js6vr3x5vlaal462psa4dlabb0v4nc-nixos-system-machine-test1315machine # [ 39.250804] systemd[1]: Stopped target Remote File Systems.1316machine # [ 39.252247] systemd[1]: Stopped target Local File Systems.1317machine # [ 39.618051] systemd[1]: Reload requested from client PID 2154 ('.switch-to-conf') (unit backdoor.service)...1318machine # [ 39.620720] systemd[1]: Reloading...1319machine # [ 40.254994] systemd[1]: Reloading finished in 632 ms.1320machine # [ 40.354382] systemd[587]: Reexecution requested from client PID 2222 ('switch-to-confi')...1321machine # [ 40.359467] systemd[587]: Reexecuting.1322machine # [ 40.423788] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1323machine # [ 40.924688] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1324machine # [ 40.932561] systemd[587]: Reload requested from client PID 2222 ('switch-to-confi')...1325machine # [ 40.933942] systemd[587]: Reloading...1326machine # [ 41.053179] systemd[587]: Reloading finished in 117 ms.1327machine # [ 41.070428] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1328machine # [ 41.080099] systemd[587]: Stopped Run user-specific NixOS activation.1329machine # [ 41.081268] systemd[587]: Stopping Run user-specific NixOS activation...1330machine # [ 41.084098] systemd[587]: Starting Run user-specific NixOS activation...1331machine # [ 41.122073] systemd[587]: Finished Run user-specific NixOS activation.1332machine # [ 41.127608] systemd[1]: Stopped target Reactivate sysinit units.1333machine # [ 41.129981] systemd[1]: Stopping Reactivate sysinit units...1334machine # [ 41.130940] systemd[1]: Reached target Reactivate sysinit units.1335machine # [ 41.137495] systemd[1]: Reloading test.service...1336machine # [ 41.173045] systemd[1]: Reloaded test.service.1337machine # [ 41.178344] systemd[1]: Reached target Local File Systems.1338machine # [ 41.183802] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1339machine # [ 41.186958] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1340machine # [ 41.193155] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1341machine # [ 41.197597] systemd[1]: Starting Create SUID/SGID Wrappers...1342machine # [ 41.198627] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1343machine # [ 41.210234] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671344machine # [ 41.213004] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1345machine # [ 41.214809] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1346machine # [ 41.220681] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1347machine # [ 41.222031] systemd[1]: Reached target Remote File Systems.1348machine # [ 41.461886] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1349machine # [ 41.464479] systemd[1]: Finished Create SUID/SGID Wrappers.1350machine # [ 41.471029] systemd[1]: Starting linger-users.service...1351machine # [ 41.524820] systemd[1]: linger-users.service: Deactivated successfully.1352machine # [ 41.527917] systemd[1]: Finished linger-users.service.1353machine # [ 41.790066] nixos[2154]: finished switching to system configuration /nix/store/b6js6vr3x5vlaal462psa4dlabb0v4nc-nixos-system-machine-test1354machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&1, in 2.81 seconds)1355machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&11356machine # [ 42.047203] nixos[2297]: switching to system configuration /nix/store/d6fzs56x0w5gcby739vjjaddab4izqsp-nixos-system-machine-test1357machine # [ 42.050430] systemd[1]: Stopped target Remote File Systems.1358machine # [ 42.052424] systemd[1]: Stopped target Local File Systems.1359machine # [ 42.395924] systemd[1]: Reload requested from client PID 2297 ('.switch-to-conf') (unit backdoor.service)...1360machine # [ 42.398235] systemd[1]: Reloading...1361machine # [ 42.765269] systemd[1]: Reloading finished in 364 ms.1362machine # [ 42.868595] systemd[587]: Reexecution requested from client PID 2366 ('switch-to-confi')...1363machine # [ 42.872491] systemd[587]: Reexecuting.1364machine # [ 42.924316] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1365machine # [ 43.041917] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1366machine # [ 43.049217] systemd[587]: Reload requested from client PID 2366 ('switch-to-confi')...1367machine # [ 43.053392] systemd[587]: Reloading...1368machine # [ 43.163961] systemd[587]: Reloading finished in 111 ms.1369machine # [ 43.181880] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1370machine # [ 43.191509] systemd[587]: Stopped Run user-specific NixOS activation.1371machine # [ 43.194117] systemd[587]: Stopping Run user-specific NixOS activation...1372machine # [ 43.198223] systemd[587]: Starting Run user-specific NixOS activation...1373machine # [ 43.233773] systemd[587]: Finished Run user-specific NixOS activation.1374machine # [ 43.238128] systemd[1]: Stopped target Reactivate sysinit units.1375machine # [ 43.241450] systemd[1]: Stopping Reactivate sysinit units...1376machine # [ 43.242418] systemd[1]: Reached target Reactivate sysinit units.1377machine # [ 43.246875] systemd[1]: Reached target Remote File Systems.1378machine # [ 43.249682] systemd[1]: Starting linger-users.service...1379machine # [ 43.253750] systemd[1]: Reached target Local File Systems.1380machine # [ 43.264261] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1381machine # [ 43.276561] systemd[1]: Starting Create SUID/SGID Wrappers...1382machine # [ 43.277644] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1383machine # [ 43.279035] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671384machine # [ 43.290636] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1385machine # [ 43.295682] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1386machine # [ 43.303406] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1387machine # [ 43.308573] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1388machine # [ 43.310043] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1389machine # [ 43.353509] systemd[1]: linger-users.service: Deactivated successfully.1390machine # [ 43.356877] systemd[1]: Finished linger-users.service.1391machine # [ 43.501016] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1392machine # [ 43.502954] systemd[1]: Finished Create SUID/SGID Wrappers.1393machine # [ 43.760263] nixos[2297]: finished switching to system configuration /nix/store/d6fzs56x0w5gcby739vjjaddab4izqsp-nixos-system-machine-test1394machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 1.97 seconds)1395machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&11396machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&1, in 0.43 seconds)1397machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/bin/switch-to-configuration test 2>&11398machine # [ 44.401161] nixos[2448]: switching to system configuration /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test1399machine # [ 44.405779] systemd[1]: Stopped target Local File Systems.1400machine # [ 44.407959] systemd[1]: Stopped target Remote File Systems.1401machine # [ 44.409982] systemd[1]: test.service: Deactivated successfully.1402machine # [ 44.410969] systemd[1]: Stopped test.service.1403machine # [ 44.719979] systemd[1]: Reload requested from client PID 2448 ('.switch-to-conf') (unit backdoor.service)...1404machine # [ 44.722225] systemd[1]: Reloading...1405machine # [ 45.060392] systemd[1]: Reloading finished in 335 ms.1406machine # [ 45.162903] systemd[587]: Reexecution requested from client PID 2517 ('switch-to-confi')...1407machine # [ 45.167832] systemd[587]: Reexecuting.1408machine # [ 45.211911] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1409machine # [ 45.322902] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1410machine # [ 45.329821] systemd[587]: Reload requested from client PID 2517 ('switch-to-confi')...1411machine # [ 45.332510] systemd[587]: Reloading...1412machine # [ 45.439449] systemd[587]: Reloading finished in 105 ms.1413machine # [ 45.456230] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1414machine # [ 45.468144] systemd[587]: Stopped Run user-specific NixOS activation.1415machine # [ 45.470813] systemd[587]: Stopping Run user-specific NixOS activation...1416machine # [ 45.473125] systemd[587]: Starting Run user-specific NixOS activation...1417machine # [ 45.523324] systemd[587]: Finished Run user-specific NixOS activation.1418machine # [ 45.528893] systemd[1]: Stopped target Reactivate sysinit units.1419machine # [ 45.529903] systemd[1]: Stopping Reactivate sysinit units...1420machine # [ 45.530810] systemd[1]: Reached target Reactivate sysinit units.1421machine # [ 45.538180] systemd[1]: Reached target Remote File Systems.1422machine # [ 45.545269] systemd[1]: Starting linger-users.service...1423machine # [ 45.554100] systemd[1]: Reached target Local File Systems.1424machine # [ 45.556814] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1425machine # [ 45.561339] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1426machine # [ 45.562840] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1427machine # [ 45.569994] systemd[1]: Starting Create SUID/SGID Wrappers...1428machine # [ 45.571024] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1429machine # [ 45.580529] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671430machine # [ 45.582994] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1431machine # [ 45.590357] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1432machine # [ 45.591914] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1433machine # [ 45.630991] systemd[1]: linger-users.service: Deactivated successfully.1434machine # [ 45.635694] systemd[1]: Finished linger-users.service.1435machine # [ 45.777437] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1436machine # [ 45.780503] systemd[1]: Finished Create SUID/SGID Wrappers.1437machine # [ 46.036416] nixos[2448]: finished switching to system configuration /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test1438machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.85 seconds)1439machine: must succeed: /nix/store/qdpknrfwzf0p4ncdm0awksgvandh5x95-separate-activation-script/bin/switch-to-configuration test 2>&11440machine # [ 46.258914] nixos[2588]: switching to system configuration /nix/store/sini1f5j2f1lj558xgzsay2dxvks1h8b-nixos-system-machine-test1441machine # [ 46.261804] systemd[1]: Stopped target Local File Systems.1442machine # [ 46.264534] systemd[1]: Stopped target Remote File Systems.1443machine # [ 46.567359] systemd[1]: Reload requested from client PID 2588 ('.switch-to-conf') (unit backdoor.service)...1444machine # [ 46.569717] systemd[1]: Reloading...1445machine # [ 46.895220] systemd[1]: Reloading finished in 324 ms.1446machine # [ 46.994997] systemd[587]: Stopped Run user-specific NixOS activation.1447machine # [ 46.998906] systemd[587]: Reexecution requested from client PID 2657 ('switch-to-confi')...1448machine # [ 47.000996] systemd[587]: Reexecuting.1449machine # [ 47.060304] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1450machine # [ 47.179194] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1451machine # [ 47.186166] systemd[587]: Reload requested from client PID 2657 ('switch-to-confi')...1452machine # [ 47.189205] systemd[587]: Reloading...1453machine # [ 47.299079] systemd[587]: Reloading finished in 108 ms.1454machine # [ 47.320236] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1455machine # [ 47.332159] systemd[1]: Stopped target Reactivate sysinit units.1456machine # [ 47.335260] systemd[1]: Stopping Reactivate sysinit units...1457machine # [ 47.337736] systemd[1]: Reached target Reactivate sysinit units.1458machine # [ 47.340363] systemd[1]: Reached target Local File Systems.1459machine # [ 47.341288] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1460machine # [ 47.356306] systemd[1]: Starting Create SUID/SGID Wrappers...1461machine # [ 47.357389] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1462machine # [ 47.358817] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671463machine # [ 47.365196] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1464machine # [ 47.367028] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1465machine # [ 47.370711] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1466machine # [ 47.374371] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1467machine # [ 47.375947] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1468machine # [ 47.379999] systemd[1]: Reached target Remote File Systems.1469machine # [ 47.545493] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1470machine # [ 47.551118] systemd[1]: Finished Create SUID/SGID Wrappers.1471machine # [ 47.554974] systemd[1]: Starting linger-users.service...1472machine # [ 47.558932] systemd[1]: Starting test.service...1473machine # [ 47.601455] systemd[1]: Finished test.service.1474machine # [ 47.621858] systemd[1]: linger-users.service: Deactivated successfully.1475machine # [ 47.625531] systemd[1]: Finished linger-users.service.1476machine # [ 47.880946] nixos[2588]: finished switching to system configuration /nix/store/sini1f5j2f1lj558xgzsay2dxvks1h8b-nixos-system-machine-test1477machine: (finished: must succeed: /nix/store/qdpknrfwzf0p4ncdm0awksgvandh5x95-separate-activation-script/bin/switch-to-configuration test 2>&1, in 1.84 seconds)1478machine: must succeed: ! test -e /run/current-system/activate1479machine: (finished: must succeed: ! test -e /run/current-system/activate, in 0.02 seconds)1480machine: must succeed: ! test -e /run/current-system/dry-activate1481machine: (finished: must succeed: ! test -e /run/current-system/dry-activate, in 0.02 seconds)1482machine: must succeed: ! test -e /run/current-system/bin/switch-to-configuration1483machine: (finished: must succeed: ! test -e /run/current-system/bin/switch-to-configuration, in 0.02 seconds)1484machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&11485machine # [ 48.182515] nixos[2736]: switching to system configuration /nix/store/dhgwbfjgmd0ri3dqjy5nr1ziv8ri3yn0-nixos-system-machine-test1486machine # [ 48.185996] systemd[1]: Stopped target Remote File Systems.1487machine # [ 48.187913] systemd[1]: test.service: Deactivated successfully.1488machine # [ 48.191673] systemd[1]: Stopped test.service.1489machine # [ 48.192905] systemd[1]: Stopped target Local File Systems.1490machine # [ 48.493829] systemd[1]: Reload requested from client PID 2736 ('.switch-to-conf') (unit backdoor.service)...1491machine # [ 48.496110] systemd[1]: Reloading...1492machine # [ 48.837755] systemd[1]: Reloading finished in 339 ms.1493machine # [ 48.928288] systemd[587]: Reexecution requested from client PID 2805 ('switch-to-confi')...1494machine # [ 48.929757] systemd[587]: Reexecuting.1495machine # [ 48.987905] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1496machine # [ 49.101289] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1497machine # [ 49.107998] systemd[587]: Reload requested from client PID 2805 ('switch-to-confi')...1498machine # [ 49.112433] systemd[587]: Reloading...1499machine # [ 49.218223] systemd[587]: Reloading finished in 107 ms.1500machine # [ 49.232210] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1501machine # [ 49.243720] systemd[587]: Starting Run user-specific NixOS activation...1502machine # [ 49.276661] systemd[587]: Finished Run user-specific NixOS activation.1503machine # [ 49.279859] systemd[587]: Stopped Run user-specific NixOS activation.1504machine # [ 49.282288] systemd[587]: Stopping Run user-specific NixOS activation...1505machine # [ 49.288223] systemd[587]: Starting Run user-specific NixOS activation...1506machine # [ 49.290781] systemd[1]: Stopped target Reactivate sysinit units.1507machine # [ 49.291746] systemd[1]: Stopping Reactivate sysinit units...1508machine # [ 49.296612] systemd[1]: Reached target Reactivate sysinit units.1509machine # [ 49.300644] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1510machine # [ 49.303567] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1511machine # [ 49.329870] systemd[1]: Started test.service.1512machine # [ 49.335012] systemd[1]: Reached target Remote File Systems.1513machine # [ 49.344435] systemd[1]: Starting linger-users.service...1514machine # [ 49.355860] systemd[1]: Reached target Local File Systems.1515machine # [ 49.358789] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1516machine # [ 49.374760] systemd[587]: Finished Run user-specific NixOS activation.1517machine # [ 49.382756] systemd[1]: Starting Create SUID/SGID Wrappers...1518machine # [ 49.383732] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1519machine # [ 49.390323] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671520machine # [ 49.395895] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1521machine # [ 49.405741] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1522machine # [ 49.407428] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1523machine # [ 49.415154] systemd[1]: test.service: Deactivated successfully.1524machine # [ 49.470600] systemd[1]: linger-users.service: Deactivated successfully.1525machine # [ 49.473753] systemd[1]: Finished linger-users.service.1526machine # [ 49.621884] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1527machine # [ 49.625057] systemd[1]: Finished Create SUID/SGID Wrappers.1528machine # [ 49.879369] nixos[2736]: finished switching to system configuration /nix/store/dhgwbfjgmd0ri3dqjy5nr1ziv8ri3yn0-nixos-system-machine-test1529machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&1, in 1.94 seconds)1530machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&11531machine # [ 50.116627] nixos[2881]: switching to system configuration /nix/store/2vrh94gvnaxqiql17g1q62fs8bhmk9sp-nixos-system-machine-test1532machine # [ 50.119732] systemd[1]: Stopped target Local File Systems.1533machine # [ 50.122173] systemd[1]: Stopped target Remote File Systems.1534machine # [ 50.443163] systemd[1]: Reload requested from client PID 2881 ('.switch-to-conf') (unit backdoor.service)...1535machine # [ 50.445585] systemd[1]: Reloading...1536machine # [ 51.134351] systemd[1]: Reloading finished in 686 ms.1537machine # [ 51.222087] systemd[587]: Reexecution requested from client PID 2950 ('switch-to-confi')...1538machine # [ 51.223747] systemd[587]: Reexecuting.1539machine # [ 51.287708] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1540machine # [ 51.402793] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1541machine # [ 51.409637] systemd[587]: Reload requested from client PID 2950 ('switch-to-confi')...1542machine # [ 51.413721] systemd[587]: Reloading...1543machine # [ 51.521654] systemd[587]: Reloading finished in 108 ms.1544machine # [ 51.536291] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1545machine # [ 51.547349] systemd[587]: Stopped Run user-specific NixOS activation.1546machine # [ 51.549929] systemd[587]: Stopping Run user-specific NixOS activation...1547machine # [ 51.552280] systemd[587]: Starting Run user-specific NixOS activation...1548machine # [ 51.586116] systemd[587]: Finished Run user-specific NixOS activation.1549machine # [ 51.590508] systemd[1]: Stopped target Reactivate sysinit units.1550machine # [ 51.592584] systemd[1]: Stopping Reactivate sysinit units...1551machine # [ 51.593613] systemd[1]: Reached target Reactivate sysinit units.1552machine # [ 51.599469] systemd[1]: Reached target Remote File Systems.1553machine # [ 51.603925] systemd[1]: Starting escaped\x2ddash.service...1554machine # [ 51.609841] systemd[1]: Starting linger-users.service...1555machine # [ 51.622976] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1556machine # [ 51.634362] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1557machine # [ 51.635888] systemd[1]: Reached target Local File Systems.1558machine # [ 51.650096] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1559machine # [ 51.651864] systemd[1]: Starting Create SUID/SGID Wrappers...1560machine # [ 51.661444] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1561machine # [ 51.668380] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671562machine # [ 51.682217] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1563machine # [ 51.691711] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1564machine # [ 51.698224] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1565machine # [ 51.699633] systemd[1]: Finished escaped\x2ddash.service.1566machine # [ 51.737125] systemd[1]: linger-users.service: Deactivated successfully.1567machine # [ 51.741962] systemd[1]: Finished linger-users.service.1568machine # [ 51.905830] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1569machine # [ 51.909349] systemd[1]: Finished Create SUID/SGID Wrappers.1570machine # [ 52.167006] nixos[2881]: finished switching to system configuration /nix/store/2vrh94gvnaxqiql17g1q62fs8bhmk9sp-nixos-system-machine-test1571machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&1, in 2.29 seconds)1572machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&11573machine # [ 52.414008] nixos[3023]: switching to system configuration /nix/store/g67asrrlk1m4qbp13icwnq21694d7ric-nixos-system-machine-test1574machine # [ 52.417345] systemd[1]: Stopped target Remote File Systems.1575machine # [ 52.421217] systemd[1]: escaped\x2ddash.service: Deactivated successfully.1576machine # [ 52.422899] systemd[1]: Stopped escaped\x2ddash.service.1577machine # [ 52.423783] systemd[1]: Stopped target Local File Systems.1578machine # [ 52.750379] systemd[1]: Reload requested from client PID 3023 ('.switch-to-conf') (unit backdoor.service)...1579machine # [ 52.752759] systemd[1]: Reloading...1580machine # [ 53.120663] systemd[1]: Reloading finished in 366 ms.1581machine # [ 53.217630] systemd[587]: Reexecution requested from client PID 3092 ('switch-to-confi')...1582machine # [ 53.222403] systemd[587]: Reexecuting.1583machine # [ 53.285292] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1584machine # [ 53.404821] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1585machine # [ 53.412114] systemd[587]: Reload requested from client PID 3092 ('switch-to-confi')...1586machine # [ 53.413571] systemd[587]: Reloading...1587machine # [ 53.527863] systemd[587]: Reloading finished in 112 ms.1588machine # [ 53.544276] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1589machine # [ 53.554965] systemd[587]: Stopped Run user-specific NixOS activation.1590machine # [ 53.557526] systemd[587]: Stopping Run user-specific NixOS activation...1591machine # [ 53.561166] systemd[587]: Starting Run user-specific NixOS activation...1592machine # [ 53.592630] systemd[587]: Finished Run user-specific NixOS activation.1593machine # [ 53.597040] systemd[1]: Stopped target Reactivate sysinit units.1594machine # [ 53.599937] systemd[1]: Stopping Reactivate sysinit units...1595machine # [ 53.604891] systemd[1]: Reached target Reactivate sysinit units.1596machine # [ 53.605847] systemd[1]: Reached target Remote File Systems.1597machine # [ 53.608308] systemd[1]: Starting escaped\x2ddash.service...1598machine # [ 53.612886] systemd[1]: Starting linger-users.service...1599machine # [ 53.625616] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1600machine # [ 53.627148] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1601machine # [ 53.644318] systemd[1]: Reached target Local File Systems.1602machine # [ 53.645260] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1603machine # [ 53.646950] systemd[1]: Starting Create SUID/SGID Wrappers...1604machine # [ 53.647795] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1605machine # [ 53.656548] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671606machine # [ 53.658942] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1607machine # [ 53.673677] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1608machine # [ 53.681888] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1609machine # [ 53.695575] systemd[1]: Finished escaped\x2ddash.service.1610machine # [ 53.733877] systemd[1]: linger-users.service: Deactivated successfully.1611machine # [ 53.736987] systemd[1]: Finished linger-users.service.1612machine # [ 53.872968] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1613machine # [ 53.877120] systemd[1]: Finished Create SUID/SGID Wrappers.1614machine # [ 54.131064] nixos[3023]: finished switching to system configuration /nix/store/g67asrrlk1m4qbp13icwnq21694d7ric-nixos-system-machine-test1615machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&1, in 1.96 seconds)1616machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&11617machine # [ 54.360399] nixos[3165]: switching to system configuration /nix/store/b8jx62l33rxa430h0hirgbk5mywq1qap-nixos-system-machine-test1618machine # [ 54.363108] systemd[1]: Stopped target Remote File Systems.1619machine # [ 54.366716] systemd[1]: Stopped target Local File Systems.1620machine # [ 54.367593] systemd[1]: escaped\x2ddash.service: Deactivated successfully.1621machine # [ 54.368649] systemd[1]: Stopped escaped\x2ddash.service.1622machine # [ 54.670776] systemd[1]: Reload requested from client PID 3165 ('.switch-to-conf') (unit backdoor.service)...1623machine # [ 54.673216] systemd[1]: Reloading...1624machine # [ 55.035526] systemd[1]: Reloading finished in 360 ms.1625machine # [ 55.132554] systemd[587]: Reexecution requested from client PID 3234 ('switch-to-confi')...1626machine # [ 55.137226] systemd[587]: Reexecuting.1627machine # [ 55.196702] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1628machine # [ 55.317274] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1629machine # [ 55.324524] systemd[587]: Reload requested from client PID 3234 ('switch-to-confi')...1630machine # [ 55.328658] systemd[587]: Reloading...1631machine # [ 55.485052] systemd[587]: Reloading finished in 157 ms.1632machine # [ 55.500341] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1633machine # [ 55.511326] systemd[587]: Stopped Run user-specific NixOS activation.1634machine # [ 55.514256] systemd[587]: Stopping Run user-specific NixOS activation...1635machine # [ 55.517422] systemd[587]: Starting Run user-specific NixOS activation...1636machine # [ 55.551599] systemd[587]: Finished Run user-specific NixOS activation.1637machine # [ 55.556537] systemd[1]: Stopped target Reactivate sysinit units.1638machine # [ 55.559655] systemd[1]: Stopping Reactivate sysinit units...1639machine # [ 55.562857] systemd[1]: Reached target Reactivate sysinit units.1640machine # [ 55.563832] systemd[1]: Reached target Local File Systems.1641machine # [ 55.565723] systemd[1]: Reached target Remote File Systems.1642machine # [ 55.568595] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1643machine # [ 55.575234] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1644machine # [ 55.579344] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1645machine # [ 55.588798] systemd[1]: Starting Create SUID/SGID Wrappers...1646machine # [ 55.589833] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1647machine # [ 55.591223] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671648machine # [ 55.600549] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1649machine # [ 55.602310] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1650machine # [ 55.604604] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1651machine # [ 55.784675] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1652machine # [ 55.788506] systemd[1]: Finished Create SUID/SGID Wrappers.1653machine # [ 55.793429] systemd[1]: Starting -.service...1654machine # [ 55.798668] systemd[1]: Starting linger-users.service...1655machine # [ 55.844586] systemd[1]: Finished -.service.1656machine # [ 55.866764] systemd[1]: linger-users.service: Deactivated successfully.1657machine # [ 55.869420] systemd[1]: Finished linger-users.service.1658machine # [ 56.128648] nixos[3165]: finished switching to system configuration /nix/store/b8jx62l33rxa430h0hirgbk5mywq1qap-nixos-system-machine-test1659machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&1, in 2.00 seconds)1660machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&11661machine # [ 56.386880] nixos[3307]: switching to system configuration /nix/store/3jpvcvsgbc7dfw859a4y9yfh4fpcq7i0-nixos-system-machine-test1662machine # [ 56.392853] systemd[1]: Stopped target Remote File Systems.1663machine # [ 56.393811] systemd[1]: Stopped target Local File Systems.1664machine # [ 56.734912] systemd[1]: Reload requested from client PID 3307 ('.switch-to-conf') (unit backdoor.service)...1665machine # [ 56.737472] systemd[1]: Reloading...1666machine # [ 57.102285] systemd[1]: Reloading finished in 361 ms.1667machine # [ 57.191026] systemd[587]: Reexecution requested from client PID 3375 ('switch-to-confi')...1668machine # [ 57.195659] systemd[587]: Reexecuting.1669machine # [ 57.255954] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1670machine # [ 57.372631] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1671machine # [ 57.380125] systemd[587]: Reload requested from client PID 3375 ('switch-to-confi')...1672machine # [ 57.381463] systemd[587]: Reloading...1673machine # [ 57.497149] systemd[587]: Reloading finished in 112 ms.1674machine # [ 57.512290] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1675machine # [ 57.523965] systemd[587]: Stopped Run user-specific NixOS activation.1676machine # [ 57.527176] systemd[587]: Stopping Run user-specific NixOS activation...1677machine # [ 57.530007] systemd[587]: Starting Run user-specific NixOS activation...1678machine # [ 57.567568] systemd[587]: Finished Run user-specific NixOS activation.1679machine # [ 57.572662] systemd[1]: Stopped target Reactivate sysinit units.1680machine # [ 57.576474] systemd[1]: Stopping Reactivate sysinit units...1681machine # [ 57.577472] systemd[1]: Reached target Reactivate sysinit units.1682machine # [ 57.581418] systemd[1]: Reloading -.service...1683machine # [ 57.611723] systemd[1]: Reloaded -.service.1684machine # [ 57.614987] systemd[1]: Reached target Remote File Systems.1685machine # [ 57.622203] systemd[1]: Reached target Local File Systems.1686machine # [ 57.625986] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1687machine # [ 57.631547] systemd[1]: Starting Create SUID/SGID Wrappers...1688machine # [ 57.636769] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1689machine # [ 57.638225] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671690machine # [ 57.650210] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1691machine # [ 57.652425] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1692machine # [ 57.653865] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1693machine # [ 57.655206] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1694machine # [ 57.656638] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1695machine # [ 57.832956] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1696machine # [ 57.834947] systemd[1]: Finished Create SUID/SGID Wrappers.1697machine # [ 57.845182] systemd[1]: Starting linger-users.service...1698machine # [ 57.889593] systemd[1]: linger-users.service: Deactivated successfully.1699machine # [ 57.892463] systemd[1]: Finished linger-users.service.1700machine # [ 58.149083] nixos[3307]: finished switching to system configuration /nix/store/3jpvcvsgbc7dfw859a4y9yfh4fpcq7i0-nixos-system-machine-test1701machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&1, in 2.02 seconds)1702machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&11703machine # [ 58.916317] nixos[3449]: switching to system configuration /nix/store/hh3xhd73ababvnvnc98r5f8wyv4w5ajf-nixos-system-machine-test1704machine # [ 58.919316] systemd[1]: Stopped target Remote File Systems.1705machine # [ 58.921653] systemd[1]: Stopped target Local File Systems.1706machine # [ 58.923532] systemd[1]: -.service: Deactivated successfully.1707machine # [ 58.925107] systemd[1]: Stopped -.service.1708machine # [ 59.272708] systemd[1]: Reload requested from client PID 3449 ('.switch-to-conf') (unit backdoor.service)...1709machine # [ 59.275015] systemd[1]: Reloading...1710machine # [ 59.666310] systemd[1]: Reloading finished in 389 ms.1711machine # [ 59.765944] systemd[587]: Reexecution requested from client PID 3518 ('switch-to-confi')...1712machine # [ 59.767438] systemd[587]: Reexecuting.1713machine # [ 59.827711] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1714machine # [ 59.949338] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1715machine # [ 59.956633] systemd[587]: Reload requested from client PID 3518 ('switch-to-confi')...1716machine # [ 59.960937] systemd[587]: Reloading...1717machine # [ 60.068045] systemd[587]: Reloading finished in 108 ms.1718machine # [ 60.084191] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1719machine # [ 60.094447] systemd[587]: Stopped Run user-specific NixOS activation.1720machine # [ 60.097159] systemd[587]: Stopping Run user-specific NixOS activation...1721machine # [ 60.099368] systemd[587]: Starting Run user-specific NixOS activation...1722machine # [ 60.132289] systemd[587]: Finished Run user-specific NixOS activation.1723machine # [ 60.137018] systemd[1]: Stopped target Reactivate sysinit units.1724machine # [ 60.138015] systemd[1]: Stopping Reactivate sysinit units...1725machine # [ 60.140915] systemd[1]: Reached target Reactivate sysinit units.1726machine # [ 60.142452] systemd[1]: Reached target Local File Systems.1727machine # [ 60.146422] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1728machine # [ 60.157126] systemd[1]: Starting Create SUID/SGID Wrappers...1729machine # [ 60.158075] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1730machine # [ 60.159468] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671731machine # [ 60.172965] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1732machine # [ 60.174904] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1733machine # [ 60.176282] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1734machine # [ 60.177543] systemd[1]: Reached target Remote File Systems.1735machine # [ 60.178350] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1736machine # [ 60.179676] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1737machine # [ 60.337459] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1738machine # [ 60.342894] systemd[1]: Finished Create SUID/SGID Wrappers.1739machine # [ 60.346466] systemd[1]: Starting linger-users.service...1740machine # [ 60.350099] systemd[1]: Starting required-service.service...1741machine # [ 60.353809] systemd[1]: Starting test-service.service...1742machine # [ 60.408793] systemd[1]: Finished required-service.service.1743machine # [ 60.412596] systemd[1]: Finished test-service.service.1744machine # [ 60.430911] systemd[1]: linger-users.service: Deactivated successfully.1745machine # [ 60.432960] systemd[1]: Finished linger-users.service.1746machine # [ 60.690880] nixos[3449]: finished switching to system configuration /nix/store/hh3xhd73ababvnvnc98r5f8wyv4w5ajf-nixos-system-machine-test1747machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&1, in 2.54 seconds)1748machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&11749machine # [ 60.992751] nixos[3593]: switching to system configuration /nix/store/0yanpi5qgzs12i61yp0z31cx5cnm7b9g-nixos-system-machine-test1750machine # [ 60.995563] systemd[1]: Stopped target Local File Systems.1751machine # [ 60.998902] systemd[1]: Stopped target Remote File Systems.1752machine # [ 61.001318] systemd[1]: required-service.service: Deactivated successfully.1753machine # [ 61.002601] systemd[1]: Stopped required-service.service.1754machine # [ 61.003473] systemd[1]: test-service.service: Deactivated successfully.1755machine # [ 61.007042] systemd[1]: Stopped test-service.service.1756machine # [ 61.322479] systemd[1]: Reload requested from client PID 3593 ('.switch-to-conf') (unit backdoor.service)...1757machine # [ 61.324717] systemd[1]: Reloading...1758machine # [ 61.679692] systemd[1]: Reloading finished in 353 ms.1759machine # [ 61.770658] systemd[587]: Reexecution requested from client PID 3662 ('switch-to-confi')...1760machine # [ 61.774825] systemd[587]: Reexecuting.1761machine # [ 61.836269] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1762machine # [ 61.955291] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1763machine # [ 61.962842] systemd[587]: Reload requested from client PID 3662 ('switch-to-confi')...1764machine # [ 61.966821] systemd[587]: Reloading...1765machine # [ 62.075621] systemd[587]: Reloading finished in 110 ms.1766machine # [ 62.092258] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1767machine # [ 62.104241] systemd[587]: Stopped Run user-specific NixOS activation.1768machine # [ 62.105415] systemd[587]: Stopping Run user-specific NixOS activation...1769machine # [ 62.108947] systemd[587]: Starting Run user-specific NixOS activation...1770machine # [ 62.141824] systemd[587]: Finished Run user-specific NixOS activation.1771machine # [ 62.148640] systemd[1]: Stopped target Reactivate sysinit units.1772machine # [ 62.150786] systemd[1]: Stopping Reactivate sysinit units...1773machine # [ 62.151747] systemd[1]: Reached target Reactivate sysinit units.1774machine # [ 62.155899] systemd[1]: Reached target Local File Systems.1775machine # [ 62.161050] systemd[1]: Reached target Remote File Systems.1776machine # [ 62.167731] systemd[1]: Starting linger-users.service...1777machine # [ 62.172876] systemd[1]: Starting required-service.service...1778machine # [ 62.185649] systemd[1]: Starting test-service.service...1779machine # [ 62.186751] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1780machine # [ 62.197846] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1781machine # [ 62.209404] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1782machine # [ 62.211183] systemd[1]: Starting Create SUID/SGID Wrappers...1783machine # [ 62.224517] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1784machine # [ 62.230736] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671785machine # [ 62.246935] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1786machine # [ 62.256779] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1787machine # [ 62.265739] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1788machine # [ 62.280236] systemd[1]: Finished test-service.service.1789machine # [ 62.281230] systemd[1]: Finished required-service.service.1790machine # [ 62.318703] systemd[1]: linger-users.service: Deactivated successfully.1791machine # [ 62.323968] systemd[1]: Finished linger-users.service.1792machine # [ 62.470379] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1793machine # [ 62.474367] systemd[1]: Finished Create SUID/SGID Wrappers.1794machine # [ 62.728683] nixos[3593]: finished switching to system configuration /nix/store/0yanpi5qgzs12i61yp0z31cx5cnm7b9g-nixos-system-machine-test1795machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&1, in 2.04 seconds)1796machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&11797machine # [ 62.981586] nixos[3737]: switching to system configuration /nix/store/1hxkvh97p40vrdd67djlpclvpl0hzf95-nixos-system-machine-test1798machine # [ 62.984580] systemd[1]: required-service.service: Deactivated successfully.1799machine # [ 62.989472] systemd[1]: Stopped required-service.service.1800machine # [ 62.990366] systemd[1]: test-service.service: Deactivated successfully.1801machine # [ 62.991419] systemd[1]: Stopped test-service.service.1802machine # [ 62.992208] systemd[1]: Stopped target Remote File Systems.1803machine # [ 62.993005] systemd[1]: Stopped target Local File Systems.1804machine # [ 63.296148] systemd[1]: Reload requested from client PID 3737 ('.switch-to-conf') (unit backdoor.service)...1805machine # [ 63.298196] systemd[1]: Reloading...1806machine # [ 63.669933] systemd[1]: Reloading finished in 369 ms.1807machine # [ 63.759201] systemd[587]: Reexecution requested from client PID 3806 ('switch-to-confi')...1808machine # [ 63.761814] systemd[587]: Reexecuting.1809machine # [ 63.819684] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1810machine # [ 63.934329] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1811machine # [ 63.942273] systemd[587]: Reload requested from client PID 3806 ('switch-to-confi')...1812machine # [ 63.943936] systemd[587]: Reloading...1813machine # [ 64.054160] systemd[587]: Reloading finished in 107 ms.1814machine # [ 64.068202] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1815machine # [ 64.078261] systemd[587]: Stopped Run user-specific NixOS activation.1816machine # [ 64.080966] systemd[587]: Stopping Run user-specific NixOS activation...1817machine # [ 64.083244] systemd[587]: Starting Run user-specific NixOS activation...1818machine # [ 64.116461] systemd[587]: Finished Run user-specific NixOS activation.1819machine # [ 64.121456] systemd[1]: Stopped target Reactivate sysinit units.1820machine # [ 64.123571] systemd[1]: Stopping Reactivate sysinit units...1821machine # [ 64.129098] systemd[1]: Reached target Reactivate sysinit units.1822machine # [ 64.130070] systemd[1]: Reached target Local File Systems.1823machine # [ 64.130900] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1824machine # [ 64.135047] systemd[1]: Starting Create SUID/SGID Wrappers...1825machine # [ 64.135990] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1826machine # [ 64.144960] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671827machine # [ 64.147384] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1828machine # [ 64.154664] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1829machine # [ 64.159137] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1830machine # [ 64.164297] systemd[1]: Reached target Remote File Systems.1831machine # [ 64.165245] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1832machine # [ 64.166885] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1833machine # [ 64.325148] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1834machine # [ 64.329933] systemd[1]: Finished Create SUID/SGID Wrappers.1835machine # [ 64.332449] systemd[1]: Starting linger-users.service...1836machine # [ 64.336332] systemd[1]: Starting required-service.service...1837machine # [ 64.342081] systemd[1]: Starting test-service.service...1838machine # [ 64.395947] systemd[1]: Finished required-service.service.1839machine # [ 64.400428] systemd[1]: Finished test-service.service.1840machine # [ 64.419850] systemd[1]: linger-users.service: Deactivated successfully.1841machine # [ 64.423338] systemd[1]: Finished linger-users.service.1842machine # [ 64.679265] nixos[3737]: finished switching to system configuration /nix/store/1hxkvh97p40vrdd67djlpclvpl0hzf95-nixos-system-machine-test1843machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&1, in 1.95 seconds)1844machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&11845machine # [ 64.929821] nixos[3881]: switching to system configuration /nix/store/mwibm7vrm5hdmangd32vbi3803ns33lz-nixos-system-machine-test1846machine # [ 64.932851] systemd[1]: Stopped target Remote File Systems.1847machine # [ 64.937298] systemd[1]: required-service.service: Deactivated successfully.1848machine # [ 64.938443] systemd[1]: Stopped required-service.service.1849machine # [ 64.939283] systemd[1]: test-service.service: Deactivated successfully.1850machine # [ 64.941651] systemd[1]: Stopped test-service.service.1851machine # [ 64.942600] systemd[1]: Stopped target Local File Systems.1852machine # [ 65.259880] systemd[1]: Reload requested from client PID 3881 ('.switch-to-conf') (unit backdoor.service)...1853machine # [ 65.262161] systemd[1]: Reloading...1854machine # [ 65.609694] systemd[1]: Reloading finished in 345 ms.1855machine # [ 65.697516] systemd[587]: Reexecution requested from client PID 3950 ('switch-to-confi')...1856machine # [ 65.702312] systemd[587]: Reexecuting.1857machine # [ 65.760089] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1858machine # [ 65.878938] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1859machine # [ 65.885956] systemd[587]: Reload requested from client PID 3950 ('switch-to-confi')...1860machine # [ 65.889008] systemd[587]: Reloading...1861machine # [ 66.002218] systemd[587]: Reloading finished in 112 ms.1862machine # [ 66.017681] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1863machine # [ 66.026616] systemd[587]: Stopped Run user-specific NixOS activation.1864machine # [ 66.029050] systemd[587]: Stopping Run user-specific NixOS activation...1865machine # [ 66.031274] systemd[587]: Starting Run user-specific NixOS activation...1866machine # [ 66.065196] systemd[587]: Finished Run user-specific NixOS activation.1867machine # [ 66.069413] systemd[1]: Stopped target Reactivate sysinit units.1868machine # [ 66.073872] systemd[1]: Stopping Reactivate sysinit units...1869machine # [ 66.074851] systemd[1]: Reached target Reactivate sysinit units.1870machine # [ 66.076977] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1871machine # [ 66.081044] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1872machine # [ 66.082514] systemd[1]: Reached target Remote File Systems.1873machine # [ 66.086034] systemd[1]: Created slice Slice /system/instantiated.1874machine # [ 66.091626] systemd[1]: Starting instantiated@one.service...1875machine # [ 66.095659] systemd[1]: Starting instantiated@two.service...1876machine # [ 66.099816] systemd[1]: Starting linger-users.service...1877machine # [ 66.112406] systemd[1]: Reached target Local File Systems.1878machine # [ 66.122524] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1879machine # [ 66.131501] systemd[1]: Starting Create SUID/SGID Wrappers...1880machine # [ 66.136242] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1881machine # [ 66.137634] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671882machine # [ 66.139939] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1883machine # [ 66.153951] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1884machine # [ 66.155435] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1885machine # [ 66.194638] systemd[1]: Finished instantiated@one.service.1886machine # [ 66.195609] systemd[1]: Finished instantiated@two.service.1887machine # [ 66.239606] systemd[1]: linger-users.service: Deactivated successfully.1888machine # [ 66.242340] systemd[1]: Finished linger-users.service.1889machine # [ 66.372658] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1890machine # [ 66.374614] systemd[1]: Finished Create SUID/SGID Wrappers.1891machine # [ 66.633648] nixos[3881]: finished switching to system configuration /nix/store/mwibm7vrm5hdmangd32vbi3803ns33lz-nixos-system-machine-test1892machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&1, in 1.95 seconds)1893machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&11894machine # [ 66.880369] nixos[4025]: switching to system configuration /nix/store/m5a8jaiyy83wz0mxzvh5pbg1qdyqrq3j-nixos-system-machine-test1895machine # [ 66.883464] systemd[1]: instantiated@one.service: Deactivated successfully.1896machine # [ 66.887029] systemd[1]: Stopped instantiated@one.service.1897machine # [ 66.887940] systemd[1]: Stopped target Local File Systems.1898machine # [ 66.889394] systemd[1]: instantiated@two.service: Deactivated successfully.1899machine # [ 66.890492] systemd[1]: Stopped instantiated@two.service.1900machine # [ 66.891288] systemd[1]: Stopped target Remote File Systems.1901machine # [ 67.195645] systemd[1]: Reload requested from client PID 4025 ('.switch-to-conf') (unit backdoor.service)...1902machine # [ 67.197898] systemd[1]: Reloading...1903machine # [ 67.545829] systemd[1]: Reloading finished in 346 ms.1904machine # [ 67.636378] systemd[587]: Reexecution requested from client PID 4094 ('switch-to-confi')...1905machine # [ 67.637873] systemd[587]: Reexecuting.1906machine # [ 67.691300] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1907machine # [ 67.806493] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1908machine # [ 67.813692] systemd[587]: Reload requested from client PID 4094 ('switch-to-confi')...1909machine # [ 67.816545] systemd[587]: Reloading...1910machine # [ 67.925645] systemd[587]: Reloading finished in 108 ms.1911machine # [ 67.941766] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1912machine # [ 67.952245] systemd[587]: Stopped Run user-specific NixOS activation.1913machine # [ 67.954587] systemd[587]: Stopping Run user-specific NixOS activation...1914machine # [ 67.956781] systemd[587]: Starting Run user-specific NixOS activation...1915machine # [ 67.983738] systemd[587]: Finished Run user-specific NixOS activation.1916machine # [ 67.987879] systemd[1]: Stopped target Reactivate sysinit units.1917machine # [ 67.990346] systemd[1]: Stopping Reactivate sysinit units...1918machine # [ 67.991902] systemd[1]: Reached target Reactivate sysinit units.1919machine # [ 67.996406] systemd[1]: Reached target Remote File Systems.1920machine # [ 67.999813] systemd[1]: Starting instantiated@one.service...1921machine # [ 68.004419] systemd[1]: Starting instantiated@two.service...1922machine # [ 68.019289] systemd[1]: Starting linger-users.service...1923machine # [ 68.021287] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1924machine # [ 68.022693] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1925machine # [ 68.032609] systemd[1]: Reached target Local File Systems.1926machine # [ 68.034912] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1927machine # [ 68.046088] systemd[1]: Starting Create SUID/SGID Wrappers...1928machine # [ 68.047203] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1929machine # [ 68.065165] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671930machine # [ 68.067670] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1931machine # [ 68.086672] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1932machine # [ 68.093841] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1933machine # [ 68.107889] systemd[1]: Finished instantiated@one.service.1934machine # [ 68.110821] systemd[1]: Finished instantiated@two.service.1935machine # [ 68.146016] systemd[1]: linger-users.service: Deactivated successfully.1936machine # [ 68.148704] systemd[1]: Finished linger-users.service.1937machine # [ 68.289517] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1938machine # [ 68.292575] systemd[1]: Finished Create SUID/SGID Wrappers.1939machine # [ 68.548745] nixos[4025]: finished switching to system configuration /nix/store/m5a8jaiyy83wz0mxzvh5pbg1qdyqrq3j-nixos-system-machine-test1940machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&1, in 1.92 seconds)1941(finished: subtest: services, in 45.37 seconds)1942subtest: failing units1943machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&11944machine # [ 68.764200] nixos[4169]: switching to system configuration /nix/store/rcd74gbcbvclm8kbhf20l2fxwyrds99l-nixos-system-machine-test1945machine # [ 68.766921] systemd[1]: Stopped target Remote File Systems.1946machine # [ 68.771410] systemd[1]: instantiated@one.service: Deactivated successfully.1947machine # [ 68.774051] systemd[1]: Stopped instantiated@one.service.1948machine # [ 68.775013] systemd[1]: instantiated@two.service: Deactivated successfully.1949machine # [ 68.775995] systemd[1]: Stopped instantiated@two.service.1950machine # [ 68.777429] systemd[1]: Stopped target Local File Systems.1951machine # [ 69.045977] systemd[1]: Reload requested from client PID 4169 ('.switch-to-conf') (unit backdoor.service)...1952machine # [ 69.048256] systemd[1]: Reloading...1953machine # [ 69.371492] systemd[1]: Reloading finished in 321 ms.1954machine # [ 69.462016] systemd[587]: Reexecution requested from client PID 4238 ('switch-to-confi')...1955machine # [ 69.465937] systemd[587]: Reexecuting.1956machine # [ 69.523907] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1957machine # [ 69.639918] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1958machine # [ 69.647882] systemd[587]: Reload requested from client PID 4238 ('switch-to-confi')...1959machine # [ 69.651260] systemd[587]: Reloading...1960machine # [ 69.757302] systemd[587]: Reloading finished in 107 ms.1961machine # [ 69.768183] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1962machine # [ 69.778357] systemd[587]: Stopped Run user-specific NixOS activation.1963machine # [ 69.780909] systemd[587]: Stopping Run user-specific NixOS activation...1964machine # [ 69.783014] systemd[587]: Starting Run user-specific NixOS activation...1965machine # [ 69.815510] systemd[587]: Finished Run user-specific NixOS activation.1966machine # [ 69.819521] systemd[1]: Stopped target Reactivate sysinit units.1967machine # [ 69.825232] systemd[1]: Stopping Reactivate sysinit units...1968machine # [ 69.826199] systemd[1]: Reached target Reactivate sysinit units.1969machine # [ 69.827126] systemd[1]: Reached target Local File Systems.1970machine # [ 69.827937] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1971machine # [ 69.833261] systemd[1]: Starting Create SUID/SGID Wrappers...1972machine # [ 69.834220] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1973machine # [ 69.835553] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671974machine # [ 69.853787] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1975machine # [ 69.855688] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1976machine # [ 69.857287] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1977machine # [ 69.858616] systemd[1]: Reached target Remote File Systems.1978machine # [ 69.859462] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1979machine # [ 69.861917] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1980machine # [ 70.020778] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1981machine # [ 70.022752] systemd[1]: Finished Create SUID/SGID Wrappers.1982machine # [ 70.027807] systemd[1]: Starting linger-users.service...1983machine # [ 70.032619] systemd[1]: Starting test.service...1984machine # [ 70.071406] systemd[1]: Finished test.service.1985machine # [ 70.092362] systemd[1]: linger-users.service: Deactivated successfully.1986machine # [ 70.094189] systemd[1]: Finished linger-users.service.1987machine # [ 70.351320] nixos[4169]: finished switching to system configuration /nix/store/rcd74gbcbvclm8kbhf20l2fxwyrds99l-nixos-system-machine-test1988machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 1.80 seconds)1989machine: must fail: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&11990machine # [ 70.594961] nixos[4311]: switching to system configuration /nix/store/izb8ngcyd91nccz1sxw01y0vbvzxgp80-nixos-system-machine-test1991machine # [ 70.598227] systemd[1]: Stopped target Local File Systems.1992machine # [ 70.600683] systemd[1]: Stopped target Remote File Systems.1993machine # [ 70.602540] systemd[1]: test.service: Deactivated successfully.1994machine # [ 70.603492] systemd[1]: Stopped test.service.1995machine # [ 70.893310] systemd[1]: Reload requested from client PID 4311 ('.switch-to-conf') (unit backdoor.service)...1996machine # [ 70.895473] systemd[1]: Reloading...1997machine # [ 71.215690] systemd[1]: Reloading finished in 318 ms.1998machine # [ 71.308605] systemd[587]: Reexecution requested from client PID 4380 ('switch-to-confi')...1999machine # [ 71.311304] systemd[587]: Reexecuting.2000machine # [ 71.363577] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2001machine # [ 71.472183] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2002machine # [ 71.479045] systemd[587]: Reload requested from client PID 4380 ('switch-to-confi')...2003machine # [ 71.482803] systemd[587]: Reloading...2004machine # [ 71.586855] systemd[587]: Reloading finished in 105 ms.2005machine # [ 71.604185] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2006machine # [ 71.613730] systemd[587]: Stopped Run user-specific NixOS activation.2007machine # [ 71.616435] systemd[587]: Stopping Run user-specific NixOS activation...2008machine # [ 71.618440] systemd[587]: Starting Run user-specific NixOS activation...2009machine # [ 71.647854] systemd[587]: Finished Run user-specific NixOS activation.2010machine # [ 71.652788] systemd[1]: Stopped target Reactivate sysinit units.2011machine # [ 71.653809] systemd[1]: Stopping Reactivate sysinit units...2012machine # [ 71.654755] systemd[1]: Reached target Reactivate sysinit units.2013machine # [ 71.657573] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2014machine # [ 71.661941] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2015machine # [ 71.667786] systemd[1]: Reached target Local File Systems.2016machine # [ 71.671521] systemd[1]: Reached target Remote File Systems.2017machine # [ 71.680555] systemd[1]: Starting linger-users.service...2018machine # [ 71.681412] systemd[1]: Starting test.service...2019machine # [ 71.694294] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2020machine # [ 71.703847] systemd[1]: Starting Create SUID/SGID Wrappers...2021machine # [ 71.705024] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2022machine # [ 71.706288] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672023machine # [ 71.714396] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2024machine # [ 71.722565] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2025machine # [ 71.729974] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2026machine # [ 71.749202] systemd[1]: test.service: Main process exited, code=exited, status=1/FAILURE2027machine # [ 71.750786] systemd[1]: test.service: Failed with result 'exit-code'.2028machine # [ 71.757836] systemd[1]: Failed to start test.service.2029machine # [ 71.786140] systemd[1]: linger-users.service: Deactivated successfully.2030machine # [ 71.791102] systemd[1]: Finished linger-users.service.2031machine # [ 71.923218] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2032machine # [ 71.926396] systemd[1]: Finished Create SUID/SGID Wrappers.2033machine # [ 72.208101] nixos[4311]: switching to system configuration /nix/store/izb8ngcyd91nccz1sxw01y0vbvzxgp80-nixos-system-machine-test failed (status 4)2034machine: (finished: must fail: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&1, in 1.86 seconds)2035machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&12036machine # [ 72.450352] nixos[4454]: switching to system configuration /nix/store/8ddzficlbrfrizda1h2zml5h898a4grh-nixos-system-machine-test2037machine # [ 72.455946] systemd[1]: Stopped target Local File Systems.2038machine # [ 72.458709] systemd[1]: Stopped target Remote File Systems.2039machine # [ 72.744769] systemd[1]: Reload requested from client PID 4454 ('.switch-to-conf') (unit backdoor.service)...2040machine # [ 72.746806] systemd[1]: Reloading...2041machine # [ 73.073375] systemd[1]: Reloading finished in 325 ms.2042machine # [ 73.157476] systemd[587]: Reexecution requested from client PID 4523 ('switch-to-confi')...2043machine # [ 73.161792] systemd[587]: Reexecuting.2044machine # [ 73.207237] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2045machine # [ 73.317449] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2046machine # [ 73.324572] systemd[587]: Reload requested from client PID 4523 ('switch-to-confi')...2047machine # [ 73.325959] systemd[587]: Reloading...2048machine # [ 73.434176] systemd[587]: Reloading finished in 105 ms.2049machine # [ 73.449187] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2050machine # [ 73.458440] systemd[587]: Stopped Run user-specific NixOS activation.2051machine # [ 73.461119] systemd[587]: Stopping Run user-specific NixOS activation...2052machine # [ 73.463637] systemd[587]: Starting Run user-specific NixOS activation...2053machine # [ 73.498139] systemd[587]: Finished Run user-specific NixOS activation.2054machine # [ 73.502928] systemd[1]: Stopped target Reactivate sysinit units.2055machine # [ 73.505255] systemd[1]: Stopping Reactivate sysinit units...2056machine # [ 73.506244] systemd[1]: Reached target Reactivate sysinit units.2057machine # [ 73.508807] systemd[1]: Reached target Remote File Systems.2058machine # [ 73.512204] systemd[1]: Reached target Local File Systems.2059machine # [ 73.518313] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2060machine # [ 73.526153] systemd[1]: Starting Create SUID/SGID Wrappers...2061machine # [ 73.527300] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2062machine # [ 73.535808] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672063machine # [ 73.543360] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2064machine # [ 73.545295] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2065machine # [ 73.546687] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2066machine # [ 73.548430] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2067machine # [ 73.549855] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2068machine # [ 73.726296] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2069machine # [ 73.728638] systemd[1]: Finished Create SUID/SGID Wrappers.2070machine # [ 73.734359] systemd[1]: Started autorestart.service.2071machine # [ 73.740466] systemd[1]: Starting linger-users.service...2072machine # [ 73.782119] systemd[1]: autorestart.service: Deactivated successfully.2073machine # [ 73.804158] systemd[1]: linger-users.service: Deactivated successfully.2074machine # [ 73.807189] systemd[1]: Finished linger-users.service.2075machine # [ 74.064496] nixos[4454]: finished switching to system configuration /nix/store/8ddzficlbrfrizda1h2zml5h898a4grh-nixos-system-machine-test2076machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 1.85 seconds)2077machine # [ 74.106832] systemd[1]: Stopped autorestart.service.2078machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&12079machine # [ 74.303637] nixos[4601]: switching to system configuration /nix/store/8ddzficlbrfrizda1h2zml5h898a4grh-nixos-system-machine-test2080machine # [ 74.306216] systemd[1]: Stopped target Local File Systems.2081machine # [ 74.309002] systemd[1]: Stopped target Remote File Systems.2082machine # [ 74.585405] systemd[1]: Reload requested from client PID 4601 ('.switch-to-conf') (unit backdoor.service)...2083machine # [ 74.587452] systemd[1]: Reloading...2084machine # [ 74.910986] systemd[1]: Reloading finished in 321 ms.2085machine # [ 74.998220] systemd[587]: Reexecution requested from client PID 4670 ('switch-to-confi')...2086machine # [ 75.003075] systemd[587]: Reexecuting.2087machine # [ 75.051654] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2088machine # [ 75.162793] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2089machine # [ 75.169696] systemd[587]: Reload requested from client PID 4670 ('switch-to-confi')...2090machine # [ 75.172356] systemd[587]: Reloading...2091machine # [ 75.281431] systemd[587]: Reloading finished in 108 ms.2092machine # [ 75.293685] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2093machine # [ 75.302274] systemd[587]: Stopped Run user-specific NixOS activation.2094machine # [ 75.304875] systemd[587]: Stopping Run user-specific NixOS activation...2095machine # [ 75.307128] systemd[587]: Starting Run user-specific NixOS activation...2096machine # [ 75.340127] systemd[587]: Finished Run user-specific NixOS activation.2097machine # [ 75.344167] systemd[1]: Stopped target Reactivate sysinit units.2098machine # [ 75.346354] systemd[1]: Stopping Reactivate sysinit units...2099machine # [ 75.347300] systemd[1]: Reached target Reactivate sysinit units.2100machine # [ 75.353987] systemd[1]: Reached target Remote File Systems.2101machine # [ 75.358629] systemd[1]: Started autorestart.service.2102machine # [ 75.365279] systemd[1]: Starting linger-users.service...2103machine # [ 75.366176] systemd[1]: Reached target Local File Systems.2104machine # [ 75.379539] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2105machine # [ 75.387761] systemd[1]: Starting Create SUID/SGID Wrappers...2106machine # [ 75.392990] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2107machine # [ 75.394335] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672108machine # [ 75.404277] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2109machine # [ 75.406023] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2110machine # [ 75.417239] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2111machine # [ 75.424333] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2112machine # [ 75.429857] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2113machine # [ 75.439895] systemd[1]: autorestart.service: Deactivated successfully.2114machine # [ 75.473960] systemd[1]: linger-users.service: Deactivated successfully.2115machine # [ 75.477835] systemd[1]: Finished linger-users.service.2116machine # [ 75.603578] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2117machine # [ 75.606947] systemd[1]: Finished Create SUID/SGID Wrappers.2118machine # [ 75.864256] nixos[4601]: finished switching to system configuration /nix/store/8ddzficlbrfrizda1h2zml5h898a4grh-nixos-system-machine-test2119machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 1.75 seconds)2120machine # [ 75.905051] systemd[1]: Stopped autorestart.service.2121machine: must fail: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&12122machine # [ 76.123261] nixos[4748]: switching to system configuration /nix/store/vbs6waxcs99gnmckxbhr60xj8pk128a0-nixos-system-machine-test2123machine # [ 76.126109] systemd[1]: Stopped target Remote File Systems.2124machine # [ 76.128579] systemd[1]: Stopped target Local File Systems.2125machine # [ 76.413979] systemd[1]: Reload requested from client PID 4748 ('.switch-to-conf') (unit backdoor.service)...2126machine # [ 76.416429] systemd[1]: Reloading...2127machine # [ 76.739524] systemd[1]: Reloading finished in 321 ms.2128machine # [ 76.833414] systemd[587]: Reexecution requested from client PID 4817 ('switch-to-confi')...2129machine # [ 76.837462] systemd[587]: Reexecuting.2130machine # [ 76.895652] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2131machine # [ 77.010851] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2132machine # [ 77.018057] systemd[587]: Reload requested from client PID 4817 ('switch-to-confi')...2133machine # [ 77.020431] systemd[587]: Reloading...2134machine # [ 77.130144] systemd[587]: Reloading finished in 107 ms.2135machine # [ 77.144174] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2136machine # [ 77.153244] systemd[587]: Stopped Run user-specific NixOS activation.2137machine # [ 77.155833] systemd[587]: Stopping Run user-specific NixOS activation...2138machine # [ 77.158146] systemd[587]: Starting Run user-specific NixOS activation...2139machine # [ 77.192999] systemd[587]: Finished Run user-specific NixOS activation.2140machine # [ 77.197003] systemd[1]: Stopped target Reactivate sysinit units.2141machine # [ 77.199071] systemd[1]: Stopping Reactivate sysinit units...2142machine # [ 77.199963] systemd[1]: Reached target Reactivate sysinit units.2143machine # [ 77.204952] systemd[1]: Reached target Remote File Systems.2144machine # [ 77.208168] systemd[1]: Started autorestart.service.2145machine # [ 77.212319] systemd[1]: Starting linger-users.service...2146machine # [ 77.221000] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2147machine # [ 77.222599] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2148machine # [ 77.235274] systemd[1]: Reached target Local File Systems.2149machine # [ 77.242257] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2150machine # [ 77.243922] systemd[1]: Starting Create SUID/SGID Wrappers...2151machine # [ 77.252932] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2152machine # [ 77.254488] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672153machine # [ 77.268948] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2154machine # [ 77.270870] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2155machine # [ 77.282991] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2156machine # [ 77.296259] systemd[1]: autorestart.service: Main process exited, code=exited, status=1/FAILURE2157machine # [ 77.297729] systemd[1]: autorestart.service: Failed with result 'exit-code'.2158machine # [ 77.333776] systemd[1]: linger-users.service: Deactivated successfully.2159machine # [ 77.336597] systemd[1]: Finished linger-users.service.2160machine # [ 77.464808] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2161machine # [ 77.467932] systemd[1]: Finished Create SUID/SGID Wrappers.2162machine # [ 77.747192] nixos[4748]: switching to system configuration /nix/store/vbs6waxcs99gnmckxbhr60xj8pk128a0-nixos-system-machine-test failed (status 4)2163machine: (finished: must fail: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&1, in 1.84 seconds)2164(finished: subtest: failing units, in 9.20 seconds)2165subtest: unit file parser2166machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&12167machine # [ 77.956356] nixos[4891]: switching to system configuration /nix/store/g4z5yys9wjkvy6hdkvy00nrgjm02yvnf-nixos-system-machine-test2168machine # [ 77.959506] systemd[1]: Stopped target Local File Systems.2169machine # [ 77.961548] systemd[1]: Stopped autorestart.service.2170machine # [ 77.964367] systemd[1]: Stopped target Remote File Systems.2171machine # [ 78.235923] systemd[1]: Reload requested from client PID 4891 ('.switch-to-conf') (unit backdoor.service)...2172machine # [ 78.238194] systemd[1]: Reloading...2173machine # [ 78.553222] systemd[1]: Reloading finished in 313 ms.2174machine # [ 78.643042] systemd[587]: Reexecution requested from client PID 4960 ('switch-to-confi')...2175machine # [ 78.645955] systemd[587]: Reexecuting.2176machine # [ 78.699724] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2177machine # [ 78.809867] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2178machine # [ 78.816485] systemd[587]: Reload requested from client PID 4960 ('switch-to-confi')...2179machine # [ 78.819001] systemd[587]: Reloading...2180machine # [ 78.925041] systemd[587]: Reloading finished in 105 ms.2181machine # [ 78.940173] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2182machine # [ 78.949624] systemd[587]: Stopped Run user-specific NixOS activation.2183machine # [ 78.952096] systemd[587]: Stopping Run user-specific NixOS activation...2184machine # [ 78.954375] systemd[587]: Starting Run user-specific NixOS activation...2185machine # [ 78.987301] systemd[587]: Finished Run user-specific NixOS activation.2186machine # [ 78.991796] systemd[1]: Stopped target Reactivate sysinit units.2187machine # [ 78.993285] systemd[1]: Stopping Reactivate sysinit units...2188machine # [ 78.994113] systemd[1]: Reached target Reactivate sysinit units.2189machine # [ 78.999393] systemd[1]: Reached target Local File Systems.2190machine # [ 79.001643] systemd[1]: Reached target Remote File Systems.2191machine # [ 79.005862] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2192machine # [ 79.013336] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2193machine # [ 79.014814] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2194machine # [ 79.023430] systemd[1]: Starting Create SUID/SGID Wrappers...2195machine # [ 79.025972] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2196machine # [ 79.027368] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672197machine # [ 79.029888] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2198machine # [ 79.031597] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2199machine # [ 79.032979] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2200machine # [ 79.186525] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2201machine # [ 79.189652] systemd[1]: Finished Create SUID/SGID Wrappers.2202machine # [ 79.195510] systemd[1]: Starting linger-users.service...2203machine # [ 79.198636] systemd[1]: Starting test.service...2204machine # [ 79.235117] systemd[1]: Finished test.service.2205machine # [ 79.256386] systemd[1]: linger-users.service: Deactivated successfully.2206machine # [ 79.259732] systemd[1]: Finished linger-users.service.2207machine # [ 79.514619] nixos[4891]: finished switching to system configuration /nix/store/g4z5yys9wjkvy6hdkvy00nrgjm02yvnf-nixos-system-machine-test2208machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 1.77 seconds)2209machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&12210machine # [ 79.745589] nixos[5033]: switching to system configuration /nix/store/mhsjz9vsv5c7phfvd5q3n0zgb9lp39h1-nixos-system-machine-test2211machine # [ 79.749409] systemd[1]: Stopped target Remote File Systems.2212machine # [ 79.751705] systemd[1]: Stopped target Local File Systems.2213machine # [ 80.034860] systemd[1]: Reload requested from client PID 5033 ('.switch-to-conf') (unit backdoor.service)...2214machine # [ 80.037025] systemd[1]: Reloading...2215machine # [ 80.357704] systemd[1]: Reloading finished in 317 ms.2216machine # [ 80.448563] systemd[587]: Reexecution requested from client PID 5102 ('switch-to-confi')...2217machine # [ 80.451771] systemd[587]: Reexecuting.2218machine # [ 80.511454] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2219machine # [ 80.628797] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2220machine # [ 80.635974] systemd[587]: Reload requested from client PID 5102 ('switch-to-confi')...2221machine # [ 80.637666] systemd[587]: Reloading...2222machine # [ 80.743490] systemd[587]: Reloading finished in 104 ms.2223machine # [ 80.760179] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2224machine # [ 80.769925] systemd[587]: Stopped Run user-specific NixOS activation.2225machine # [ 80.772448] systemd[587]: Stopping Run user-specific NixOS activation...2226machine # [ 80.774565] systemd[587]: Starting Run user-specific NixOS activation...2227machine # [ 80.808000] systemd[587]: Finished Run user-specific NixOS activation.2228machine # [ 80.812101] systemd[1]: Stopped target Reactivate sysinit units.2229machine # [ 80.816731] systemd[1]: Stopping Reactivate sysinit units...2230machine # [ 80.817677] systemd[1]: Reached target Reactivate sysinit units.2231machine # [ 80.818612] systemd[1]: test.service: Deactivated successfully.2232machine # [ 80.819476] systemd[1]: Stopped test.service.2233machine # [ 80.821146] systemd[1]: Stopping test.service...2234machine # [ 80.822892] systemd[1]: Starting test.service...2235machine # [ 80.849043] systemd[1]: Finished test.service.2236machine # [ 80.858095] systemd[1]: Reached target Local File Systems.2237machine # [ 80.863760] systemd[1]: Reached target Remote File Systems.2238machine # [ 80.867618] systemd[1]: Starting linger-users.service...2239machine # [ 80.872530] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2240machine # [ 80.874039] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2241machine # [ 80.881907] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2242machine # [ 80.887193] systemd[1]: Starting Create SUID/SGID Wrappers...2243machine # [ 80.892491] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2244machine # [ 80.893929] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672245machine # [ 80.903918] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2246machine # [ 80.906786] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2247machine # [ 80.911333] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2248machine # [ 80.952927] systemd[1]: linger-users.service: Deactivated successfully.2249machine # [ 80.957922] systemd[1]: Finished linger-users.service.2250machine # [ 81.090836] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2251machine # [ 81.093995] systemd[1]: Finished Create SUID/SGID Wrappers.2252machine # [ 81.349673] nixos[5033]: finished switching to system configuration /nix/store/mhsjz9vsv5c7phfvd5q3n0zgb9lp39h1-nixos-system-machine-test2253machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&1, in 1.84 seconds)2254machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&12255machine # [ 81.582467] nixos[5175]: switching to system configuration /nix/store/cr9q9w43x7v4sfbl8dw1ffw6rm70chrx-nixos-system-machine-test2256machine # [ 81.585211] systemd[1]: Stopped target Remote File Systems.2257machine # [ 81.587703] systemd[1]: Stopped target Local File Systems.2258machine # [ 81.870104] systemd[1]: Reload requested from client PID 5175 ('.switch-to-conf') (unit backdoor.service)...2259machine # [ 81.872366] systemd[1]: Reloading...2260machine # [ 82.189307] systemd[1]: Reloading finished in 315 ms.2261machine # [ 82.278333] systemd[587]: Reexecution requested from client PID 5244 ('switch-to-confi')...2262machine # [ 82.282145] systemd[587]: Reexecuting.2263machine # [ 82.339453] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2264machine # [ 82.455356] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2265machine # [ 82.462582] systemd[587]: Reload requested from client PID 5244 ('switch-to-confi')...2266machine # [ 82.466483] systemd[587]: Reloading...2267machine # [ 82.572514] systemd[587]: Reloading finished in 107 ms.2268machine # [ 82.588187] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2269machine # [ 82.598092] systemd[587]: Stopped Run user-specific NixOS activation.2270machine # [ 82.600586] systemd[587]: Stopping Run user-specific NixOS activation...2271machine # [ 82.602721] systemd[587]: Starting Run user-specific NixOS activation...2272machine # [ 82.635422] systemd[587]: Finished Run user-specific NixOS activation.2273machine # [ 82.639733] systemd[1]: Stopped target Reactivate sysinit units.2274machine # [ 82.644702] systemd[1]: Stopping Reactivate sysinit units...2275machine # [ 82.645583] systemd[1]: Reached target Reactivate sysinit units.2276machine # [ 82.646541] systemd[1]: test.service: Deactivated successfully.2277machine # [ 82.647377] systemd[1]: Stopped test.service.2278machine # [ 82.648329] systemd[1]: Stopping test.service...2279machine # [ 82.650506] systemd[1]: Starting test.service...2280machine # [ 82.673342] systemd[1]: Finished test.service.2281machine # [ 82.676975] systemd[1]: Reached target Remote File Systems.2282machine # [ 82.680393] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2283machine # [ 82.684591] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2284machine # [ 82.688501] systemd[1]: Starting linger-users.service...2285machine # [ 82.699563] systemd[1]: Reached target Local File Systems.2286machine # [ 82.704381] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2287machine # [ 82.713284] systemd[1]: Starting Create SUID/SGID Wrappers...2288machine # [ 82.714286] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2289machine # [ 82.715599] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672290machine # [ 82.723457] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2291machine # [ 82.728721] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2292machine # [ 82.735289] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2293machine # [ 82.780755] systemd[1]: linger-users.service: Deactivated successfully.2294machine # [ 82.785655] systemd[1]: Finished linger-users.service.2295machine # [ 82.910090] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2296machine # [ 82.913525] systemd[1]: Finished Create SUID/SGID Wrappers.2297machine # [ 83.167425] nixos[5175]: finished switching to system configuration /nix/store/cr9q9w43x7v4sfbl8dw1ffw6rm70chrx-nixos-system-machine-test2298machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&1, in 1.82 seconds)2299machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&12300machine # [ 83.377769] nixos[5317]: switching to system configuration /nix/store/g4z5yys9wjkvy6hdkvy00nrgjm02yvnf-nixos-system-machine-test2301machine # [ 83.380387] systemd[1]: Stopped target Local File Systems.2302machine # [ 83.383392] systemd[1]: Stopped target Remote File Systems.2303machine # [ 83.649557] systemd[1]: Reload requested from client PID 5317 ('.switch-to-conf') (unit backdoor.service)...2304machine # [ 83.651710] systemd[1]: Reloading...2305machine # [ 83.957835] systemd[1]: Reloading finished in 304 ms.2306machine # [ 84.040434] systemd[587]: Reexecution requested from client PID 5386 ('switch-to-confi')...2307machine # [ 84.043941] systemd[587]: Reexecuting.2308machine # [ 84.099598] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2309machine # [ 84.210918] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2310machine # [ 84.218226] systemd[587]: Reload requested from client PID 5386 ('switch-to-confi')...2311machine # [ 84.219596] systemd[587]: Reloading...2312machine # [ 84.322470] systemd[587]: Reloading finished in 101 ms.2313machine # [ 84.336156] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2314machine # [ 84.346758] systemd[587]: Stopped Run user-specific NixOS activation.2315machine # [ 84.347810] systemd[587]: Stopping Run user-specific NixOS activation...2316machine # [ 84.350904] systemd[587]: Starting Run user-specific NixOS activation...2317machine # [ 84.374466] systemd[587]: Finished Run user-specific NixOS activation.2318machine # [ 84.378441] systemd[1]: Stopped target Reactivate sysinit units.2319machine # [ 84.379827] systemd[1]: Stopping Reactivate sysinit units...2320machine # [ 84.381345] systemd[1]: Reached target Reactivate sysinit units.2321machine # [ 84.384490] systemd[1]: test.service: Deactivated successfully.2322machine # [ 84.385407] systemd[1]: Stopped test.service.2323machine # [ 84.386053] systemd[1]: Stopping test.service...2324machine # [ 84.388863] systemd[1]: Starting test.service...2325machine # [ 84.412573] systemd[1]: Finished test.service.2326machine # [ 84.415438] systemd[1]: Reached target Remote File Systems.2327machine # [ 84.419068] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2328machine # [ 84.422630] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2329machine # [ 84.428825] systemd[1]: Starting linger-users.service...2330machine # [ 84.432254] systemd[1]: Reached target Local File Systems.2331machine # [ 84.442957] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2332machine # [ 84.452240] systemd[1]: Starting Create SUID/SGID Wrappers...2333machine # [ 84.453164] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2334machine # [ 84.454541] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672335machine # [ 84.461380] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2336machine # [ 84.463173] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2337machine # [ 84.471778] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2338machine # [ 84.516904] systemd[1]: linger-users.service: Deactivated successfully.2339machine # [ 84.520116] systemd[1]: Finished linger-users.service.2340machine # [ 84.644968] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2341machine # [ 84.647968] systemd[1]: Finished Create SUID/SGID Wrappers.2342machine # [ 84.903625] nixos[5317]: finished switching to system configuration /nix/store/g4z5yys9wjkvy6hdkvy00nrgjm02yvnf-nixos-system-machine-test2343machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 1.74 seconds)2344machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&12345machine # [ 85.126732] nixos[5459]: switching to system configuration /nix/store/aa4rzc55mmhbghrr837lx8k55a6nqvpm-nixos-system-machine-test2346machine # [ 85.129473] systemd[1]: Stopped target Remote File Systems.2347machine # [ 85.131762] systemd[1]: Stopped target Local File Systems.2348machine # [ 85.403776] systemd[1]: Reload requested from client PID 5459 ('.switch-to-conf') (unit backdoor.service)...2349machine # [ 85.406036] systemd[1]: Reloading...2350machine # [ 85.720889] systemd[1]: Reloading finished in 313 ms.2351machine # [ 85.810854] systemd[587]: Reexecution requested from client PID 5528 ('switch-to-confi')...2352machine # [ 85.812907] systemd[587]: Reexecuting.2353machine # [ 85.859034] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2354machine # [ 85.969245] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2355machine # [ 85.976275] systemd[587]: Reload requested from client PID 5528 ('switch-to-confi')...2356machine # [ 85.977608] systemd[587]: Reloading...2357machine # [ 86.081489] systemd[587]: Reloading finished in 102 ms.2358machine # [ 86.097567] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2359machine # [ 86.106073] systemd[587]: Stopped Run user-specific NixOS activation.2360machine # [ 86.108505] systemd[587]: Stopping Run user-specific NixOS activation...2361machine # [ 86.110685] systemd[587]: Starting Run user-specific NixOS activation...2362machine # [ 86.143017] systemd[587]: Finished Run user-specific NixOS activation.2363machine # [ 86.147212] systemd[1]: Stopped target Reactivate sysinit units.2364machine # [ 86.148920] systemd[1]: Stopping Reactivate sysinit units...2365machine # [ 86.149768] systemd[1]: Reached target Reactivate sysinit units.2366machine # [ 86.152638] systemd[1]: Reached target Local File Systems.2367machine # [ 86.156394] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2368machine # [ 86.160077] systemd[1]: Starting Create SUID/SGID Wrappers...2369machine # [ 86.160959] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2370machine # [ 86.162209] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672371machine # [ 86.176870] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2372machine # [ 86.178668] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2373machine # [ 86.179957] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2374machine # [ 86.184694] systemd[1]: Reached target Remote File Systems.2375machine # [ 86.185578] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2376machine # [ 86.186946] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2377machine # [ 86.339667] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2378machine # [ 86.344817] systemd[1]: Finished Create SUID/SGID Wrappers.2379machine # [ 86.346887] systemd[1]: Starting linger-users.service...2380machine # [ 86.388419] systemd[1]: linger-users.service: Deactivated successfully.2381machine # [ 86.391092] systemd[1]: Finished linger-users.service.2382machine # [ 86.646626] nixos[5459]: finished switching to system configuration /nix/store/aa4rzc55mmhbghrr837lx8k55a6nqvpm-nixos-system-machine-test2383machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&1, in 1.74 seconds)2384machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&12385machine # [ 86.872896] nixos[5599]: switching to system configuration /nix/store/073xg983sr5mr5jrb1fvyykwsairqk4b-nixos-system-machine-test2386machine # [ 86.876685] systemd[1]: Stopped target Remote File Systems.2387machine # [ 86.878992] systemd[1]: Stopped target Local File Systems.2388machine # [ 87.153963] systemd[1]: Reload requested from client PID 5599 ('.switch-to-conf') (unit backdoor.service)...2389machine # [ 87.155908] systemd[1]: Reloading...2390machine # [ 87.471046] systemd[1]: Reloading finished in 313 ms.2391machine # [ 87.560672] systemd[587]: Reexecution requested from client PID 5668 ('switch-to-confi')...2392machine # [ 87.564906] systemd[587]: Reexecuting.2393machine # [ 87.623389] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2394machine # [ 87.736145] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2395machine # [ 87.742729] systemd[587]: Reload requested from client PID 5668 ('switch-to-confi')...2396machine # [ 87.746319] systemd[587]: Reloading...2397machine # [ 87.850157] systemd[587]: Reloading finished in 104 ms.2398machine # [ 87.868165] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2399machine # [ 87.877720] systemd[587]: Stopped Run user-specific NixOS activation.2400machine # [ 87.880182] systemd[587]: Stopping Run user-specific NixOS activation...2401machine # [ 87.882219] systemd[587]: Starting Run user-specific NixOS activation...2402machine # [ 87.914498] systemd[587]: Finished Run user-specific NixOS activation.2403machine # [ 87.918216] systemd[1]: Stopped target Reactivate sysinit units.2404machine # [ 87.920608] systemd[1]: Stopping Reactivate sysinit units...2405machine # [ 87.921512] systemd[1]: Reached target Reactivate sysinit units.2406machine # [ 87.924784] systemd[1]: test.service: Deactivated successfully.2407machine # [ 87.925700] systemd[1]: Stopped test.service.2408machine # [ 87.926433] systemd[1]: Stopping test.service...2409machine # [ 87.928294] systemd[1]: Starting test.service...2410machine # [ 87.952139] systemd[1]: Finished test.service.2411machine # [ 87.955732] systemd[1]: Reached target Remote File Systems.2412machine # [ 87.959834] systemd[1]: Starting linger-users.service...2413machine # [ 87.964831] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2414machine # [ 87.966217] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2415machine # [ 87.978577] systemd[1]: Reached target Local File Systems.2416machine # [ 87.982899] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2417machine # [ 87.992788] systemd[1]: Starting Create SUID/SGID Wrappers...2418machine # [ 87.993665] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2419machine # [ 87.994967] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672420machine # [ 88.006866] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2421machine # [ 88.009314] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2422machine # [ 88.011081] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2423machine # [ 88.045634] systemd[1]: linger-users.service: Deactivated successfully.2424machine # [ 88.048917] systemd[1]: Finished linger-users.service.2425machine # [ 88.178025] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2426machine # [ 88.181500] systemd[1]: Finished Create SUID/SGID Wrappers.2427machine # [ 88.435305] nixos[5599]: finished switching to system configuration /nix/store/073xg983sr5mr5jrb1fvyykwsairqk4b-nixos-system-machine-test2428machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&1, in 1.79 seconds)2429machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&12430machine # [ 88.661972] nixos[5741]: switching to system configuration /nix/store/0pncfahdv1mk7nxxhjw013pj5rf63w5a-nixos-system-machine-test2431machine # [ 88.664969] systemd[1]: Stopped target Remote File Systems.2432machine # [ 88.667751] systemd[1]: Stopped target Local File Systems.2433machine # [ 88.943716] systemd[1]: Reload requested from client PID 5741 ('.switch-to-conf') (unit backdoor.service)...2434machine # [ 88.945940] systemd[1]: Reloading...2435machine # [ 89.282564] systemd[1]: Reloading finished in 334 ms.2436machine # [ 89.364431] systemd[587]: Reexecution requested from client PID 5810 ('switch-to-confi')...2437machine # [ 89.367955] systemd[587]: Reexecuting.2438machine # [ 89.415042] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2439machine # [ 89.521960] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2440machine # [ 89.528227] systemd[587]: Reload requested from client PID 5810 ('switch-to-confi')...2441machine # [ 89.531979] systemd[587]: Reloading...2442machine # [ 89.635062] systemd[587]: Reloading finished in 104 ms.2443machine # [ 89.652186] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2444machine # [ 89.661437] systemd[587]: Stopped Run user-specific NixOS activation.2445machine # [ 89.663904] systemd[587]: Stopping Run user-specific NixOS activation...2446machine # [ 89.666046] systemd[587]: Starting Run user-specific NixOS activation...2447machine # [ 89.699043] systemd[587]: Finished Run user-specific NixOS activation.2448machine # [ 89.702691] systemd[1]: Stopped target Reactivate sysinit units.2449machine # [ 89.704630] systemd[1]: Stopping Reactivate sysinit units...2450machine # [ 89.705532] systemd[1]: Reached target Reactivate sysinit units.2451machine # [ 89.708414] systemd[1]: test.service: Deactivated successfully.2452machine # [ 89.709331] systemd[1]: Stopped test.service.2453machine # [ 89.709981] systemd[1]: Stopping test.service...2454machine # [ 89.712955] systemd[1]: Starting test.service...2455machine # [ 89.736967] systemd[1]: Finished test.service.2456machine # [ 89.740966] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2457machine # [ 89.747270] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2458machine # [ 89.748914] systemd[1]: Reached target Remote File Systems.2459machine # [ 89.750527] systemd[1]: Starting linger-users.service...2460machine # [ 89.762156] systemd[1]: Reached target Local File Systems.2461machine # [ 89.766224] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2462machine # [ 89.777132] systemd[1]: Starting Create SUID/SGID Wrappers...2463machine # [ 89.778029] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2464machine # [ 89.779296] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672465machine # [ 89.790644] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2466machine # [ 89.794154] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2467machine # [ 89.795610] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2468machine # [ 89.834922] systemd[1]: linger-users.service: Deactivated successfully.2469machine # [ 89.838073] systemd[1]: Finished linger-users.service.2470machine # [ 89.975800] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2471machine # [ 89.979680] systemd[1]: Finished Create SUID/SGID Wrappers.2472machine # [ 90.233332] nixos[5741]: finished switching to system configuration /nix/store/0pncfahdv1mk7nxxhjw013pj5rf63w5a-nixos-system-machine-test2473machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&1, in 1.80 seconds)2474machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&12475machine # [ 90.459935] nixos[5883]: switching to system configuration /nix/store/1p1d4pvjrc3lk0154ismsln4fs2jjgl0-nixos-system-machine-test2476machine # [ 90.463156] systemd[1]: Stopped target Remote File Systems.2477machine # [ 90.464870] systemd[1]: Stopped target Local File Systems.2478machine # [ 90.745588] systemd[1]: Reload requested from client PID 5883 ('.switch-to-conf') (unit backdoor.service)...2479machine # [ 90.747911] systemd[1]: Reloading...2480machine # [ 91.079384] systemd[1]: Reloading finished in 330 ms.2481machine # [ 91.165470] systemd[587]: Reexecution requested from client PID 5952 ('switch-to-confi')...2482machine # [ 91.169753] systemd[587]: Reexecuting.2483machine # [ 91.227573] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2484machine # [ 91.341845] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2485machine # [ 91.349169] systemd[587]: Reload requested from client PID 5952 ('switch-to-confi')...2486machine # [ 91.350676] systemd[587]: Reloading...2487machine # [ 91.455252] systemd[587]: Reloading finished in 103 ms.2488machine # [ 91.472161] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2489machine # [ 91.482441] systemd[587]: Stopped Run user-specific NixOS activation.2490machine # [ 91.484785] systemd[587]: Stopping Run user-specific NixOS activation...2491machine # [ 91.486918] systemd[587]: Starting Run user-specific NixOS activation...2492machine # [ 91.518121] systemd[587]: Finished Run user-specific NixOS activation.2493machine # [ 91.522111] systemd[1]: Stopped target Reactivate sysinit units.2494machine # [ 91.526688] systemd[1]: Stopping Reactivate sysinit units...2495machine # [ 91.527552] systemd[1]: Reached target Reactivate sysinit units.2496machine # [ 91.529566] systemd[1]: test.service: Deactivated successfully.2497machine # [ 91.530476] systemd[1]: Stopped test.service.2498machine # [ 91.531102] systemd[1]: Stopping test.service...2499machine # [ 91.531755] systemd[1]: Starting test.service...2500machine # [ 91.555831] systemd[1]: Finished test.service.2501machine # [ 91.560776] systemd[1]: Reached target Remote File Systems.2502machine # [ 91.563788] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2503machine # [ 91.568186] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2504machine # [ 91.569527] systemd[1]: Reached target Local File Systems.2505machine # [ 91.570350] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2506machine # [ 91.575012] systemd[1]: Starting Create SUID/SGID Wrappers...2507machine # [ 91.575881] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2508machine # [ 91.586946] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672509machine # [ 91.591482] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2510machine # [ 91.593385] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2511machine # [ 91.594697] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2512machine # [ 91.744469] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2513machine # [ 91.748575] systemd[1]: Finished Create SUID/SGID Wrappers.2514machine # [ 91.751506] systemd[1]: Starting linger-users.service...2515machine # [ 91.791924] systemd[1]: linger-users.service: Deactivated successfully.2516machine # [ 91.793981] systemd[1]: Finished linger-users.service.2517machine # [ 92.049945] nixos[5883]: finished switching to system configuration /nix/store/1p1d4pvjrc3lk0154ismsln4fs2jjgl0-nixos-system-machine-test2518machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&1, in 1.82 seconds)2519machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&12520machine # [ 92.259387] nixos[6025]: switching to system configuration /nix/store/g4z5yys9wjkvy6hdkvy00nrgjm02yvnf-nixos-system-machine-test2521machine # [ 92.261942] systemd[1]: Stopped target Remote File Systems.2522machine # [ 92.264259] systemd[1]: Stopped target Local File Systems.2523machine # [ 92.520203] systemd[1]: Reload requested from client PID 6025 ('.switch-to-conf') (unit backdoor.service)...2524machine # [ 92.521798] systemd[1]: Reloading...2525machine # [ 92.822309] systemd[1]: Reloading finished in 298 ms.2526machine # [ 92.908131] systemd[587]: Reexecution requested from client PID 6094 ('switch-to-confi')...2527machine # [ 92.912063] systemd[587]: Reexecuting.2528machine # [ 92.967204] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2529machine # [ 93.077197] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2530machine # [ 93.083491] systemd[587]: Reload requested from client PID 6094 ('switch-to-confi')...2531machine # [ 93.087156] systemd[587]: Reloading...2532machine # [ 93.189483] systemd[587]: Reloading finished in 103 ms.2533machine # [ 93.204159] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2534machine # [ 93.213503] systemd[587]: Stopped Run user-specific NixOS activation.2535machine # [ 93.215814] systemd[587]: Stopping Run user-specific NixOS activation...2536machine # [ 93.218042] systemd[587]: Starting Run user-specific NixOS activation...2537machine # [ 93.246595] systemd[587]: Finished Run user-specific NixOS activation.2538machine # [ 93.250203] systemd[1]: Stopped target Reactivate sysinit units.2539machine # [ 93.252525] systemd[1]: Stopping Reactivate sysinit units...2540machine # [ 93.253433] systemd[1]: Reached target Reactivate sysinit units.2541machine # [ 93.256757] systemd[1]: test.service: Deactivated successfully.2542machine # [ 93.257658] systemd[1]: Stopped test.service.2543machine # [ 93.258317] systemd[1]: Stopping test.service...2544machine # [ 93.260256] systemd[1]: Starting test.service...2545machine # [ 93.283680] systemd[1]: Finished test.service.2546machine # [ 93.286557] systemd[1]: Reached target Remote File Systems.2547machine # [ 93.290425] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2548machine # [ 93.294704] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2549machine # [ 93.296599] systemd[1]: Reached target Local File Systems.2550machine # [ 93.298621] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2551machine # [ 93.306182] systemd[1]: Starting Create SUID/SGID Wrappers...2552machine # [ 93.307052] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2553machine # [ 93.313432] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672554machine # [ 93.315694] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2555machine # [ 93.320339] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2556machine # [ 93.321656] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2557machine # [ 93.473785] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2558machine # [ 93.478013] systemd[1]: Finished Create SUID/SGID Wrappers.2559machine # [ 93.480355] systemd[1]: Starting linger-users.service...2560machine # [ 93.519017] systemd[1]: linger-users.service: Deactivated successfully.2561machine # [ 93.521996] systemd[1]: Finished linger-users.service.2562machine # [ 93.775921] nixos[6025]: finished switching to system configuration /nix/store/g4z5yys9wjkvy6hdkvy00nrgjm02yvnf-nixos-system-machine-test2563machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 1.73 seconds)2564machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&12565machine # [ 93.995998] nixos[6167]: switching to system configuration /nix/store/5yx3wfb1rd5vfr6107xf8b284a9l1cal-nixos-system-machine-test2566machine # [ 93.999048] systemd[1]: Stopped target Local File Systems.2567machine # [ 94.001197] systemd[1]: Stopped target Remote File Systems.2568machine # [ 94.272605] systemd[1]: Reload requested from client PID 6167 ('.switch-to-conf') (unit backdoor.service)...2569machine # [ 94.274606] systemd[1]: Reloading...2570machine # [ 94.586191] systemd[1]: Reloading finished in 310 ms.2571machine # [ 94.674244] systemd[587]: Reexecution requested from client PID 6236 ('switch-to-confi')...2572machine # [ 94.675681] systemd[587]: Reexecuting.2573machine # [ 94.735497] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2574machine # [ 94.847369] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2575machine # [ 94.854057] systemd[587]: Reload requested from client PID 6236 ('switch-to-confi')...2576machine # [ 94.857778] systemd[587]: Reloading...2577machine # [ 94.961214] systemd[587]: Reloading finished in 103 ms.2578machine # [ 94.976174] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2579machine # [ 94.985668] systemd[587]: Stopped Run user-specific NixOS activation.2580machine # [ 94.987968] systemd[587]: Stopping Run user-specific NixOS activation...2581machine # [ 94.990313] systemd[587]: Starting Run user-specific NixOS activation...2582machine # [ 95.023352] systemd[587]: Finished Run user-specific NixOS activation.2583machine # [ 95.027660] systemd[1]: Stopped target Reactivate sysinit units.2584machine # [ 95.032545] systemd[1]: Stopping Reactivate sysinit units...2585machine # [ 95.033466] systemd[1]: Reached target Reactivate sysinit units.2586machine # [ 95.035738] systemd[1]: Reloading test.service...2587machine # [ 95.062171] systemd[1]: Reloaded test.service.2588machine # [ 95.066807] systemd[1]: Reached target Remote File Systems.2589machine # [ 95.071834] systemd[1]: Starting linger-users.service...2590machine # [ 95.078847] systemd[1]: Reached target Local File Systems.2591machine # [ 95.079756] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2592machine # [ 95.096277] systemd[1]: Starting Create SUID/SGID Wrappers...2593machine # [ 95.097256] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2594machine # [ 95.098630] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672595machine # [ 95.108344] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2596machine # [ 95.110068] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2597machine # [ 95.111469] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2598machine # [ 95.112847] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2599machine # [ 95.114207] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2600machine # [ 95.170361] systemd[1]: linger-users.service: Deactivated successfully.2601machine # [ 95.175369] systemd[1]: Finished linger-users.service.2602machine # [ 95.299013] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2603machine # [ 95.301786] systemd[1]: Finished Create SUID/SGID Wrappers.2604machine # [ 95.558077] nixos[6167]: finished switching to system configuration /nix/store/5yx3wfb1rd5vfr6107xf8b284a9l1cal-nixos-system-machine-test2605machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 1.78 seconds)2606machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&12607machine # [ 95.783253] nixos[6309]: switching to system configuration /nix/store/0gngmrrrdpinb7sh5k2vs91g078h3d0n-nixos-system-machine-test2608machine # [ 95.785791] systemd[1]: Stopped target Local File Systems.2609machine # [ 95.788208] systemd[1]: Stopped target Remote File Systems.2610machine # [ 96.057115] systemd[1]: Reload requested from client PID 6309 ('.switch-to-conf') (unit backdoor.service)...2611machine # [ 96.059124] systemd[1]: Reloading...2612machine # [ 96.365966] systemd[1]: Reloading finished in 305 ms.2613machine # [ 96.452434] systemd[587]: Reexecution requested from client PID 6378 ('switch-to-confi')...2614machine # [ 96.456777] systemd[587]: Reexecuting.2615machine # [ 96.515321] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2616machine # [ 96.627611] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2617machine # [ 96.634492] systemd[587]: Reload requested from client PID 6378 ('switch-to-confi')...2618machine # [ 96.636815] systemd[587]: Reloading...2619machine # [ 96.738731] systemd[587]: Reloading finished in 100 ms.2620machine # [ 96.756164] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2621machine # [ 96.766370] systemd[587]: Stopped Run user-specific NixOS activation.2622machine # [ 96.768717] systemd[587]: Stopping Run user-specific NixOS activation...2623machine # [ 96.770754] systemd[587]: Starting Run user-specific NixOS activation...2624machine # [ 96.802732] systemd[587]: Finished Run user-specific NixOS activation.2625machine # [ 96.805989] systemd[1]: Stopped target Reactivate sysinit units.2626machine # [ 96.807872] systemd[1]: Stopping Reactivate sysinit units...2627machine # [ 96.811136] systemd[1]: Reached target Reactivate sysinit units.2628machine # [ 96.813278] systemd[1]: Reloading test.service...2629machine # [ 96.835677] systemd[1]: Reloaded test.service.2630machine # [ 96.840812] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2631machine # [ 96.845117] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2632machine # [ 96.846550] systemd[1]: Reached target Remote File Systems.2633machine # [ 96.848081] systemd[1]: Reached target Local File Systems.2634machine # [ 96.851608] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2635machine # [ 96.860192] systemd[1]: Starting Create SUID/SGID Wrappers...2636machine # [ 96.861099] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2637machine # [ 96.862415] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672638machine # [ 96.869900] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2639machine # [ 96.871629] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2640machine # [ 96.873065] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2641machine # [ 97.020351] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2642machine # [ 97.024417] systemd[1]: Finished Create SUID/SGID Wrappers.2643machine # [ 97.026394] systemd[1]: Starting linger-users.service...2644machine # [ 97.063048] systemd[1]: linger-users.service: Deactivated successfully.2645machine # [ 97.066172] systemd[1]: Finished linger-users.service.2646machine # [ 97.319928] nixos[6309]: finished switching to system configuration /nix/store/0gngmrrrdpinb7sh5k2vs91g078h3d0n-nixos-system-machine-test2647machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&1, in 1.76 seconds)2648machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&12649machine # [ 97.538670] nixos[6451]: switching to system configuration /nix/store/r5qp6l52kw6jg6yrrnskgjjjsv9qv6c4-nixos-system-machine-test2650machine # [ 97.541164] systemd[1]: Stopped target Remote File Systems.2651machine # [ 97.543429] systemd[1]: Stopped target Local File Systems.2652machine # [ 97.812397] systemd[1]: Reload requested from client PID 6451 ('.switch-to-conf') (unit backdoor.service)...2653machine # [ 97.814464] systemd[1]: Reloading...2654machine # [ 98.120400] systemd[1]: Reloading finished in 304 ms.2655machine # [ 98.201657] systemd[587]: Reexecution requested from client PID 6520 ('switch-to-confi')...2656machine # [ 98.206372] systemd[587]: Reexecuting.2657machine # [ 98.263304] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2658machine # [ 98.373328] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2659machine # [ 98.379786] systemd[587]: Reload requested from client PID 6520 ('switch-to-confi')...2660machine # [ 98.382290] systemd[587]: Reloading...2661machine # [ 98.485331] systemd[587]: Reloading finished in 102 ms.2662machine # [ 98.500164] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2663machine # [ 98.509633] systemd[587]: Stopped Run user-specific NixOS activation.2664machine # [ 98.511987] systemd[587]: Stopping Run user-specific NixOS activation...2665machine # [ 98.514043] systemd[587]: Starting Run user-specific NixOS activation...2666machine # [ 98.542129] systemd[587]: Finished Run user-specific NixOS activation.2667machine # [ 98.546048] systemd[1]: Stopped target Reactivate sysinit units.2668machine # [ 98.547047] systemd[1]: Stopping Reactivate sysinit units...2669machine # [ 98.547846] systemd[1]: Reached target Reactivate sysinit units.2670machine # [ 98.552362] systemd[1]: test.service: Deactivated successfully.2671machine # [ 98.553302] systemd[1]: Stopped test.service.2672machine # [ 98.553947] systemd[1]: Stopping test.service...2673machine # [ 98.555831] systemd[1]: Starting test.service...2674machine # [ 98.580346] systemd[1]: Finished test.service.2675machine # [ 98.586032] systemd[1]: Reached target Local File Systems.2676machine # [ 98.589378] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2677machine # [ 98.591719] systemd[1]: Starting Create SUID/SGID Wrappers...2678machine # [ 98.596318] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2679machine # [ 98.597643] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672680machine # [ 98.599940] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2681machine # [ 98.613560] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2682machine # [ 98.614977] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2683machine # [ 98.618667] systemd[1]: Reached target Remote File Systems.2684machine # [ 98.620351] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2685machine # [ 98.623369] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2686machine # [ 98.768250] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2687machine # [ 98.770095] systemd[1]: Finished Create SUID/SGID Wrappers.2688machine # [ 98.774794] systemd[1]: Starting linger-users.service...2689machine # [ 98.817078] systemd[1]: linger-users.service: Deactivated successfully.2690machine # [ 98.819625] systemd[1]: Finished linger-users.service.2691machine # [ 99.075170] nixos[6451]: finished switching to system configuration /nix/store/r5qp6l52kw6jg6yrrnskgjjjsv9qv6c4-nixos-system-machine-test2692machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&1, in 1.76 seconds)2693machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&12694machine # [ 99.278744] nixos[6593]: switching to system configuration /nix/store/073xg983sr5mr5jrb1fvyykwsairqk4b-nixos-system-machine-test2695machine # [ 99.283852] systemd[1]: Stopped target Remote File Systems.2696machine # [ 99.286261] systemd[1]: Stopped target Local File Systems.2697machine # [ 99.538711] systemd[1]: Reload requested from client PID 6593 ('.switch-to-conf') (unit backdoor.service)...2698machine # [ 99.540743] systemd[1]: Reloading...2699machine # [ 99.836238] systemd[1]: Reloading finished in 294 ms.2700machine # [ 99.920395] systemd[587]: Reexecution requested from client PID 6662 ('switch-to-confi')...2701machine # [ 99.923166] systemd[587]: Reexecuting.2702machine # [ 99.979541] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2703machine # [ 100.089240] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2704machine # [ 100.095634] systemd[587]: Reload requested from client PID 6662 ('switch-to-confi')...2705machine # [ 100.099284] systemd[587]: Reloading...2706machine # [ 100.201601] systemd[587]: Reloading finished in 103 ms.2707machine # [ 100.216146] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2708machine # [ 100.225241] systemd[587]: Stopped Run user-specific NixOS activation.2709machine # [ 100.227335] systemd[587]: Stopping Run user-specific NixOS activation...2710machine # [ 100.229286] systemd[587]: Starting Run user-specific NixOS activation...2711machine # [ 100.257483] systemd[587]: Finished Run user-specific NixOS activation.2712machine # [ 100.261086] systemd[1]: Stopped target Reactivate sysinit units.2713machine # [ 100.263019] systemd[1]: Stopping Reactivate sysinit units...2714machine # [ 100.263897] systemd[1]: Reached target Reactivate sysinit units.2715machine # [ 100.270603] systemd[1]: Reached target Local File Systems.2716machine # [ 100.273589] systemd[1]: Reached target Remote File Systems.2717machine # [ 100.275612] systemd[1]: Starting linger-users.service...2718machine # [ 100.281068] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2719machine # [ 100.296789] systemd[1]: Starting Create SUID/SGID Wrappers...2720machine # [ 100.297700] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2721machine # [ 100.298969] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672722machine # [ 100.301813] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2723machine # [ 100.303469] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2724machine # [ 100.304843] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2725machine # [ 100.306057] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2726machine # [ 100.307369] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2727machine # [ 100.359071] systemd[1]: linger-users.service: Deactivated successfully.2728machine # [ 100.362586] systemd[1]: Finished linger-users.service.2729machine # [ 100.481120] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2730machine # [ 100.484522] systemd[1]: Finished Create SUID/SGID Wrappers.2731machine # [ 100.738695] nixos[6593]: finished switching to system configuration /nix/store/073xg983sr5mr5jrb1fvyykwsairqk4b-nixos-system-machine-test2732machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&1, in 1.66 seconds)2733(finished: subtest: unit file parser, in 22.99 seconds)2734subtest: restart and reload by activation script2735machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&12736machine # [ 100.943782] nixos[6733]: switching to system configuration /nix/store/d6fzs56x0w5gcby739vjjaddab4izqsp-nixos-system-machine-test2737machine # [ 100.948845] systemd[1]: Stopped target Local File Systems.2738machine # [ 100.949708] systemd[1]: Stopped target Remote File Systems.2739machine # [ 101.199102] systemd[1]: Reload requested from client PID 6733 ('.switch-to-conf') (unit backdoor.service)...2740machine # [ 101.201238] systemd[1]: Reloading...2741machine # [ 101.495804] systemd[1]: Reloading finished in 292 ms.2742machine # [ 101.583049] systemd[587]: Reexecution requested from client PID 6802 ('switch-to-confi')...2743machine # [ 101.585546] systemd[587]: Reexecuting.2744machine # [ 101.647473] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2745machine # [ 101.759174] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2746machine # [ 101.765546] systemd[587]: Reload requested from client PID 6802 ('switch-to-confi')...2747machine # [ 101.767847] systemd[587]: Reloading...2748machine # [ 101.869273] systemd[587]: Reloading finished in 100 ms.2749machine # [ 101.884140] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2750machine # [ 101.893872] systemd[587]: Stopped Run user-specific NixOS activation.2751machine # [ 101.896068] systemd[587]: Stopping Run user-specific NixOS activation...2752machine # [ 101.898022] systemd[587]: Starting Run user-specific NixOS activation...2753machine # [ 101.925105] systemd[587]: Finished Run user-specific NixOS activation.2754machine # [ 101.928585] systemd[1]: Stopped target Reactivate sysinit units.2755machine # [ 101.930467] systemd[1]: Stopping Reactivate sysinit units...2756machine # [ 101.931309] systemd[1]: Reached target Reactivate sysinit units.2757machine # [ 101.935310] systemd[1]: Reached target Remote File Systems.2758machine # [ 101.938579] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2759machine # [ 101.939906] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2760machine # [ 101.944976] systemd[1]: Reached target Local File Systems.2761machine # [ 101.945840] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2762machine # [ 101.950449] systemd[1]: Starting Create SUID/SGID Wrappers...2763machine # [ 101.951332] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2764machine # [ 101.959829] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672765machine # [ 101.963738] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2766machine # [ 101.965598] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2767machine # [ 101.966942] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2768machine # [ 102.115985] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2769machine # [ 102.118702] systemd[1]: Finished Create SUID/SGID Wrappers.2770machine # [ 102.122682] systemd[1]: Starting linger-users.service...2771machine # [ 102.157938] systemd[1]: linger-users.service: Deactivated successfully.2772machine # [ 102.160899] systemd[1]: Finished linger-users.service.2773machine # [ 102.414799] nixos[6733]: finished switching to system configuration /nix/store/d6fzs56x0w5gcby739vjjaddab4izqsp-nixos-system-machine-test2774machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 1.68 seconds)2775machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&12776machine # [ 102.635197] nixos[6873]: switching to system configuration /nix/store/an390vh09m6hwcmhiqvf7pxzzky7x2ax-nixos-system-machine-test2777machine # [ 102.637952] systemd[1]: test.service: Deactivated successfully.2778machine # [ 102.641561] systemd[1]: Stopped test.service.2779machine # [ 102.642871] systemd[1]: Stopped target Local File Systems.2780machine # [ 102.643675] systemd[1]: Stopped target Remote File Systems.2781machine # [ 102.916808] systemd[1]: Reload requested from client PID 6873 ('.switch-to-conf') (unit backdoor.service)...2782machine # [ 102.919068] systemd[1]: Reloading...2783machine # [ 103.231028] systemd[1]: Reloading finished in 310 ms.2784machine # [ 103.317778] systemd[587]: Reexecution requested from client PID 6944 ('switch-to-confi')...2785machine # [ 103.319242] systemd[587]: Reexecuting.2786machine # [ 103.379266] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2787machine # [ 103.491691] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2788machine # [ 103.498243] systemd[587]: Reload requested from client PID 6944 ('switch-to-confi')...2789machine # [ 103.500737] systemd[587]: Reloading...2790machine # [ 103.605972] systemd[587]: Reloading finished in 104 ms.2791machine # [ 103.620212] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2792machine # [ 103.630064] systemd[587]: Stopped Run user-specific NixOS activation.2793machine # [ 103.632735] systemd[587]: Stopping Run user-specific NixOS activation...2794machine # [ 103.635164] systemd[587]: Starting Run user-specific NixOS activation...2795machine # [ 103.667675] systemd[587]: Finished Run user-specific NixOS activation.2796machine # [ 103.672412] systemd[1]: Stopped target Reactivate sysinit units.2797machine # [ 103.673359] systemd[1]: Stopping Reactivate sysinit units...2798machine # [ 103.674204] systemd[1]: Reached target Reactivate sysinit units.2799machine # [ 103.681286] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart-by-as.2800machine # [ 103.685182] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service...2801machine # [ 103.695906] systemd[1]: Starting simple-service.service...2802machine # [ 103.706976] systemd[1]: Starting reload-triggers-and-restart-by-as.service...2803machine # [ 103.713632] systemd[1]: Created slice Slice /system/templated-reload-triggers.2804machine # [ 103.714698] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart.2805machine # [ 103.715828] systemd[1]: Reached target Remote File Systems.2806machine # [ 103.721190] systemd[1]: Starting linger-users.service...2807machine # [ 103.736238] systemd[1]: Starting reload-triggers-and-restart.service...2808machine # [ 103.737273] systemd[1]: Starting reload-triggers.service...2809machine # [ 103.738083] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service...2810machine # [ 103.739230] systemd[1]: Starting templated-reload-triggers@instance.service...2811machine # [ 103.787195] systemd[1]: Created slice Slice /system/templated-simple-service.2812machine # [ 103.806022] systemd[1]: Starting templated-simple-service@instance.service...2813machine # [ 103.807182] systemd[1]: Created slice Slice /system/templated-simple-restart-service.2814machine # [ 103.840344] systemd[1]: Starting templated-simple-restart-service@instance.service...2815machine # [ 103.853141] systemd[1]: Reached target Local File Systems.2816machine # [ 103.878496] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2817machine # [ 103.909861] systemd[1]: Starting Create SUID/SGID Wrappers...2818machine # [ 103.910930] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2819machine # [ 103.914110] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672820machine # [ 103.917188] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2821machine # [ 103.918958] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2822machine # [ 103.922945] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2823machine # [ 103.926931] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2824machine # [ 103.931292] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2825machine # [ 103.944679] systemd[1]: Created slice Slice /system/templated-simple-reload-service.2826machine # [ 103.945778] systemd[1]: Finished simple-service.service.2827machine # [ 103.946556] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service.2828machine # [ 103.947718] systemd[1]: Created slice Slice /system/templated-no-restart-service.2829machine # [ 103.980793] systemd[1]: Finished reload-triggers-and-restart-by-as.service.2830machine # [ 103.982052] systemd[1]: Finished reload-triggers-and-restart.service.2831machine # [ 103.983027] systemd[1]: Finished reload-triggers.service.2832machine # [ 103.983779] systemd[1]: Finished templated-reload-triggers@instance.service.2833machine # [ 103.997066] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service.2834machine # [ 103.998342] systemd[1]: Finished templated-simple-service@instance.service.2835machine # [ 103.999320] systemd[1]: Finished templated-simple-restart-service@instance.service.2836machine # [ 104.017506] systemd[1]: linger-users.service: Deactivated successfully.2837machine # [ 104.020227] systemd[1]: Finished linger-users.service.2838machine # [ 104.145341] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2839machine # [ 104.149833] systemd[1]: Finished Create SUID/SGID Wrappers.2840machine # [ 104.152175] systemd[1]: Starting no-restart-service.service...2841machine # [ 104.156511] systemd[1]: Starting simple-reload-service.service...2842machine # [ 104.160311] systemd[1]: Starting simple-restart-service.service...2843machine # [ 104.175127] systemd[1]: Starting templated-no-restart-service@instance.service...2844machine # [ 104.182710] systemd[1]: Starting templated-simple-reload-service@instance.service...2845machine # [ 104.239393] systemd[1]: Finished simple-reload-service.service.2846machine # [ 104.242880] systemd[1]: Finished simple-restart-service.service.2847machine # [ 104.245847] systemd[1]: Finished templated-no-restart-service@instance.service.2848machine # [ 104.247001] systemd[1]: Finished templated-simple-reload-service@instance.service.2849machine # [ 104.251003] systemd[1]: Finished no-restart-service.service.2850machine # [ 104.508426] nixos[6873]: finished switching to system configuration /nix/store/an390vh09m6hwcmhiqvf7pxzzky7x2ax-nixos-system-machine-test2851machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 2.09 seconds)2852machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&12853machine # [ 104.747924] nixos[7043]: switching to system configuration /nix/store/an390vh09m6hwcmhiqvf7pxzzky7x2ax-nixos-system-machine-test2854machine # [ 104.750941] systemd[1]: Stopped target Local File Systems.2855machine # [ 104.753979] systemd[1]: Stopped target Remote File Systems.2856machine # [ 105.032659] systemd[1]: Reload requested from client PID 7043 ('.switch-to-conf') (unit backdoor.service)...2857machine # [ 105.034854] systemd[1]: Reloading...2858machine # [ 105.346013] systemd[1]: Reloading finished in 310 ms.2859machine # [ 105.432411] systemd[587]: Reexecution requested from client PID 7114 ('switch-to-confi')...2860machine # [ 105.435490] systemd[587]: Reexecuting.2861machine # [ 105.495198] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2862machine # [ 105.602998] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2863machine # [ 105.609266] systemd[587]: Reload requested from client PID 7114 ('switch-to-confi')...2864machine # [ 105.611450] systemd[587]: Reloading...2865machine # [ 105.713052] systemd[587]: Reloading finished in 100 ms.2866machine # [ 105.728146] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2867machine # [ 105.737316] systemd[587]: Stopped Run user-specific NixOS activation.2868machine # [ 105.739462] systemd[587]: Stopping Run user-specific NixOS activation...2869machine # [ 105.741301] systemd[587]: Starting Run user-specific NixOS activation...2870machine # [ 105.769711] systemd[587]: Finished Run user-specific NixOS activation.2871machine # [ 105.773624] systemd[1]: Stopped target Reactivate sysinit units.2872machine # [ 105.776693] systemd[1]: Stopping Reactivate sysinit units...2873machine # [ 105.777558] systemd[1]: Reached target Reactivate sysinit units.2874machine # [ 105.782038] systemd[1]: Reloading reload-triggers-and-restart.service...2875machine # [ 105.792874] systemd[1]: Reloading reload-triggers.service...2876machine # [ 105.793771] systemd[1]: Reloading simple-reload-service.service...2877machine # [ 105.808462] systemd[1]: Reloading templated-reload-triggers-and-restart@instance.service...2878machine # [ 105.814296] systemd[1]: Reloading templated-reload-triggers@instance.service...2879machine # [ 105.824766] systemd[1]: Reloading templated-simple-reload-service@instance.service...2880machine # [ 105.881794] systemd[1]: Reloaded reload-triggers.service.2881machine # [ 105.889403] systemd[1]: Reloaded simple-reload-service.service.2882machine # [ 105.890396] systemd[1]: Reloaded templated-reload-triggers-and-restart@instance.service.2883machine # [ 105.891473] systemd[1]: Reloaded templated-reload-triggers@instance.service.2884machine # [ 105.896352] systemd[1]: Reloaded reload-triggers-and-restart.service.2885machine # [ 105.901899] systemd[1]: Reloaded templated-simple-reload-service@instance.service.2886machine # [ 105.904350] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully.2887machine # [ 105.905541] systemd[1]: Stopped reload-triggers-and-restart-by-as.service.2888machine # [ 105.906579] systemd[1]: Stopping reload-triggers-and-restart-by-as.service...2889machine # [ 105.916813] systemd[1]: Starting reload-triggers-and-restart-by-as.service...2890machine # [ 105.917850] systemd[1]: simple-restart-service.service: Deactivated successfully.2891machine # [ 105.918965] systemd[1]: Stopped simple-restart-service.service.2892machine # [ 105.919789] systemd[1]: Stopping simple-restart-service.service...2893machine # [ 105.928496] systemd[1]: Starting simple-restart-service.service...2894machine # [ 105.929396] systemd[1]: simple-service.service: Deactivated successfully.2895machine # [ 105.930388] systemd[1]: Stopped simple-service.service.2896machine # [ 105.931138] systemd[1]: Stopping simple-service.service...2897machine # [ 105.931915] systemd[1]: Starting simple-service.service...2898machine # [ 105.952218] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully.2899machine # [ 105.953717] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service.2900machine # [ 105.954894] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service...2901machine # [ 105.972474] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service...2902machine # [ 105.973733] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully.2903machine # [ 105.992654] systemd[1]: Stopped templated-simple-restart-service@instance.service.2904machine # [ 106.003246] systemd[1]: Stopping templated-simple-restart-service@instance.service...2905machine # [ 106.011168] systemd[1]: Starting templated-simple-restart-service@instance.service...2906machine # [ 106.014231] systemd[1]: templated-simple-service@instance.service: Deactivated successfully.2907machine # [ 106.015523] systemd[1]: Stopped templated-simple-service@instance.service.2908machine # [ 106.023249] systemd[1]: Stopping templated-simple-service@instance.service...2909machine # [ 106.027682] systemd[1]: Starting templated-simple-service@instance.service...2910machine # [ 106.032875] systemd[1]: Finished simple-restart-service.service.2911machine # [ 106.033751] systemd[1]: Finished reload-triggers-and-restart-by-as.service.2912machine # [ 106.034717] systemd[1]: Finished templated-simple-restart-service@instance.service.2913machine # [ 106.035753] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service.2914machine # [ 106.041525] systemd[1]: Finished simple-service.service.2915machine # [ 106.045734] systemd[1]: Finished templated-simple-service@instance.service.2916machine # [ 106.048661] systemd[1]: Reached target Local File Systems.2917machine # [ 106.051013] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2918machine # [ 106.057009] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2919machine # [ 106.058364] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2920machine # [ 106.062243] systemd[1]: Starting Create SUID/SGID Wrappers...2921machine # [ 106.063144] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2922machine # [ 106.072457] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672923machine # [ 106.074750] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2924machine # [ 106.079113] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2925machine # [ 106.082309] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2926machine # [ 106.083544] systemd[1]: Reached target Remote File Systems.2927machine # [ 106.224083] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2928machine # [ 106.228539] systemd[1]: Finished Create SUID/SGID Wrappers.2929machine # [ 106.230915] systemd[1]: Starting linger-users.service...2930machine # [ 106.270715] systemd[1]: linger-users.service: Deactivated successfully.2931machine # [ 106.273679] systemd[1]: Finished linger-users.service.2932machine # [ 106.527963] nixos[7043]: finished switching to system configuration /nix/store/an390vh09m6hwcmhiqvf7pxzzky7x2ax-nixos-system-machine-test2933machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 2.02 seconds)2934machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&12935machine # [ 106.790312] nixos[7209]: switching to system configuration /nix/store/wnnxgcsn4f80pidnb5xznhfc4nzzcmj0-nixos-system-machine-test2936machine # [ 106.793020] systemd[1]: Stopped target Remote File Systems.2937machine # [ 106.795259] systemd[1]: Stopped target Local File Systems.2938machine # [ 107.079945] systemd[1]: Reload requested from client PID 7209 ('.switch-to-conf') (unit backdoor.service)...2939machine # [ 107.082210] systemd[1]: Reloading...2940machine # [ 107.401365] systemd[1]: Reloading finished in 318 ms.2941machine # [ 107.487139] systemd[587]: Reexecution requested from client PID 7280 ('switch-to-confi')...2942machine # [ 107.489718] systemd[587]: Reexecuting.2943machine # [ 107.547024] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2944machine # [ 107.659047] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2945machine # [ 107.665825] systemd[587]: Reload requested from client PID 7280 ('switch-to-confi')...2946machine # [ 107.667145] systemd[587]: Reloading...2947machine # [ 107.767401] systemd[587]: Reloading finished in 98 ms.2948machine # [ 107.784138] systemd[587]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2949machine # [ 107.793685] systemd[587]: Stopped Run user-specific NixOS activation.2950machine # [ 107.795904] systemd[587]: Stopping Run user-specific NixOS activation...2951machine # [ 107.798172] systemd[587]: Starting Run user-specific NixOS activation...2952machine # [ 107.824992] systemd[587]: Finished Run user-specific NixOS activation.2953machine # [ 107.828757] systemd[1]: Stopped target Reactivate sysinit units.2954machine # [ 107.833564] systemd[1]: Stopping Reactivate sysinit units...2955machine # [ 107.834438] systemd[1]: Reached target Reactivate sysinit units.2956machine # [ 107.836295] systemd[1]: Reloading reload-triggers.service...2957machine # [ 107.844396] systemd[1]: Reloading simple-reload-service.service...2958machine # [ 107.854604] systemd[1]: Reloading templated-reload-triggers@instance.service...2959machine # [ 107.860566] systemd[1]: Reloading templated-simple-reload-service@instance.service...2960machine # [ 107.909064] systemd[1]: Reloaded simple-reload-service.service.2961machine # [ 107.913281] systemd[1]: Reloaded templated-reload-triggers@instance.service.2962machine # [ 107.915745] systemd[1]: Reloaded templated-simple-reload-service@instance.service.2963machine # [ 107.919908] systemd[1]: Reloaded reload-triggers.service.2964machine # [ 107.922862] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully.2965machine # [ 107.926189] systemd[1]: Stopped reload-triggers-and-restart-by-as.service.2966machine # [ 107.927680] systemd[1]: Stopping reload-triggers-and-restart-by-as.service...2967machine # [ 107.930402] systemd[1]: Starting reload-triggers-and-restart-by-as.service...2968machine # [ 107.940907] systemd[1]: reload-triggers-and-restart.service: Deactivated successfully.2969machine # [ 107.942051] systemd[1]: Stopped reload-triggers-and-restart.service.2970machine # [ 107.943016] systemd[1]: Stopping reload-triggers-and-restart.service...2971machine # [ 107.943960] systemd[1]: Starting reload-triggers-and-restart.service...2972machine # [ 107.952695] systemd[1]: simple-restart-service.service: Deactivated successfully.2973machine # [ 107.953754] systemd[1]: Stopped simple-restart-service.service.2974machine # [ 107.954603] systemd[1]: Stopping simple-restart-service.service...2975machine # [ 107.955453] systemd[1]: Starting simple-restart-service.service...2976machine # [ 107.992633] systemd[1]: simple-service.service: Deactivated successfully.2977machine # [ 107.993653] systemd[1]: Stopped simple-service.service.2978machine # [ 108.010173] systemd[1]: Stopping simple-service.service...2979machine # [ 108.015540] systemd[1]: Starting simple-service.service...2980machine # [ 108.026675] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully.2981machine # [ 108.046967] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service.2982machine # [ 108.057746] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service...2983machine # [ 108.068151] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service...2984machine # [ 108.079498] systemd[1]: templated-reload-triggers-and-restart@instance.service: Deactivated successfully.2985machine # [ 108.089615] systemd[1]: Stopped templated-reload-triggers-and-restart@instance.service.2986machine # [ 108.090814] systemd[1]: Stopping templated-reload-triggers-and-restart@instance.service...2987machine # [ 108.091934] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service...2988machine # [ 108.096561] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully.2989machine # [ 108.097841] systemd[1]: Stopped templated-simple-restart-service@instance.service.2990machine # [ 108.098934] systemd[1]: Stopping templated-simple-restart-service@instance.service...2991machine # [ 108.099990] systemd[1]: Starting templated-simple-restart-service@instance.service...2992machine # [ 108.102401] systemd[1]: templated-simple-service@instance.service: Deactivated successfully.2993machine # [ 108.103543] systemd[1]: Stopped templated-simple-service@instance.service.2994machine # [ 108.118230] systemd[1]: Stopping templated-simple-service@instance.service...2995machine # [ 108.119278] systemd[1]: Starting templated-simple-service@instance.service...2996machine # [ 108.127253] systemd[1]: Finished simple-restart-service.service.2997machine # [ 108.128432] systemd[1]: Finished reload-triggers-and-restart.service.2998machine # [ 108.130155] systemd[1]: Finished reload-triggers-and-restart-by-as.service.2999machine # [ 108.131124] systemd[1]: Finished simple-service.service.3000machine # [ 108.131882] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service.3001machine # [ 108.133199] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service.3002machine # [ 108.134346] systemd[1]: Finished templated-simple-restart-service@instance.service.3003machine # [ 108.135378] systemd[1]: Finished templated-simple-service@instance.service.3004machine # [ 108.136681] systemd[1]: Reached target Local File Systems.3005machine # [ 108.137495] systemd[1]: Reached target Remote File Systems.3006machine # [ 108.139006] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore3007machine # [ 108.141535] systemd[1]: Starting Create SUID/SGID Wrappers...3008machine # [ 108.143140] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.3009machine # [ 108.145121] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc673010machine # [ 108.149411] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore3011machine # [ 108.151126] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os3012machine # [ 108.154997] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os3013machine # [ 108.156422] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os3014machine # [ 108.157723] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki3015machine # [ 108.292872] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.3016machine # [ 108.294724] systemd[1]: Finished Create SUID/SGID Wrappers.3017machine # [ 108.299246] systemd[1]: Starting linger-users.service...3018machine # [ 108.339602] systemd[1]: linger-users.service: Deactivated successfully.3019machine # [ 108.342848] systemd[1]: Finished linger-users.service.3020machine # [ 108.597778] nixos[7209]: finished switching to system configuration /nix/store/wnnxgcsn4f80pidnb5xznhfc4nzzcmj0-nixos-system-machine-test3021machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&1, in 2.07 seconds)3022machine: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration dry-activate 2>&13023machine: (finished: must succeed: /nix/store/akvr53m0hrpqz5ghxvx1y4qmb0ngp26k-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration dry-activate 2>&1, in 0.33 seconds)3024(finished: subtest: restart and reload by activation script, in 8.19 seconds)3025(finished: run the VM test script, in 109.66 seconds)3026test script finished in 109.69s3027cleanup3028kill QemuMachine (pid 45)3029machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lq2fsnmg0q3y0vlxn91w8s8wvj1m6fld-python3-3.14.8/bin/python3.14)3030machine # [2026-10-20T03:09:56Z INFO virtiofsd] Client disconnected, shutting down3031machine # [2026-10-20T03:09:56Z INFO virtiofsd] Client disconnected, shutting down3032machine # [2026-10-20T03:09:56Z INFO virtiofsd] Client disconnected, shutting down3033(finished: cleanup, in 0.16 seconds)3034additionally exposed symbols:3035 machine, other,3036 vlan1,3037 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