vm-test-run-switch-test-units
checks.aarch64-linux.switchTest-units
· 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: QEMU running (pid 45)11machine # Disk image does not exist, creating the virtualisation disk image...12machine # Formatting '/build/vm-state-machine/tmp.Iu9Fnzv2p3', fmt=raw size=107374182413machine # mke2fs 1.47.4 (6-Mar-2025)14machine # Discarding device blocks: 0/262144 done15machine # Creating filesystem with 262144 4k blocks and 65536 inodes16machine # Filesystem UUID: cc22c44e-967a-456d-ab1e-dde31b8c1f4d17machine # 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:07Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28machine # [2026-10-20T03:08:07Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29machine # [2026-10-20T03:08:07Z INFO virtiofsd] Waiting for vhost-user socket connection...30machine # [2026-10-20T03:08:07Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31machine # [2026-10-20T03:08:07Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32machine # [2026-10-20T03:08:07Z INFO virtiofsd] Waiting for vhost-user socket connection...33machine # [2026-10-20T03:08:07Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34machine # [2026-10-20T03:08:07Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35machine # [2026-10-20T03:08:07Z INFO virtiofsd] Waiting for vhost-user socket connection...36machine # [2026-10-20T03:08:07Z INFO virtiofsd] Client connected, servicing requests37machine # [2026-10-20T03:08:07Z INFO virtiofsd] Client connected, servicing requests38machine # [2026-10-20T03:08:07Z 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/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/init regInfo=/nix/.ro-store/mk2gwa83b15rsyy49c00k6ag53g1jbmq-closure-info/registration console=ttyAMA0,115200n8 console=tty074machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/mk2gwa83b15rsyy49c00k6ag53g1jbmq-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 @0x0000000045100000111machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045110000112machine # [ 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.000034] arm-pv: using stolen time PV118machine # [ 0.000424] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)119machine # [ 0.000646] Console: colour dummy device 80x25120machine # [ 0.000655] printk: legacy console [tty0] enabled121machine # [ 0.000946] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)122machine # [ 0.000954] pid_max: default: 32768 minimum: 301123machine # [ 0.001039] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima124machine # [ 0.001183] landlock: Up and running.125machine # [ 0.001187] Yama: becoming mindful.126machine # [ 0.001660] LSM support for eBPF active127machine # [ 0.001781] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)128machine # [ 0.001802] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)129machine # [ 0.004055] rcu: Hierarchical SRCU implementation.130machine # [ 0.004062] rcu: Max phase no-delay instances is 1000.131machine # [ 0.005340] fsl-mc MSI: its@8080000 domain created132machine # [ 0.005466] EFI services will not be available.133machine # [ 0.005585] smp: Bringing up secondary CPUs ...134machine # [ 0.005596] smp: Brought up 1 node, 1 CPU135machine # [ 0.005599] SMP: Total of 1 processors activated.136machine # [ 0.005602] CPU: All CPU(s) started at EL1137machine # [ 0.005612] CPU features: detected: Branch Target Identification138machine # [ 0.005617] CPU features: detected: ARMv8.4 Translation Table Level139machine # [ 0.005620] CPU features: detected: Instruction cache invalidation not required for I/D coherence140machine # [ 0.005623] CPU features: detected: Data cache clean to the PoU not required for I/D coherence141machine # [ 0.005633] CPU features: detected: Common not Private translations142machine # [ 0.005637] CPU features: detected: CRC32 instructions143machine # [ 0.005640] CPU features: detected: Data cache clean to Point of Deep Persistence144machine # [ 0.005643] CPU features: detected: Data cache clean to Point of Persistence145machine # [ 0.005646] CPU features: detected: Data independent timing control (DIT)146machine # [ 0.005649] CPU features: detected: E0PD147machine # [ 0.005652] CPU features: detected: Enhanced Counter Virtualization148machine # [ 0.005655] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)149machine # [ 0.005658] CPU features: detected: Enhanced Virtualization Traps150machine # [ 0.005661] CPU features: detected: Fine Grained Traps151machine # [ 0.005664] CPU features: detected: Generic authentication (architected QARMA5 algorithm)152machine # [ 0.005668] CPU features: detected: RCpc load-acquire (LDAPR)153machine # [ 0.005671] CPU features: detected: LSE atomic instructions154machine # [ 0.005674] CPU features: detected: Privileged Access Never155machine # [ 0.005677] CPU features: detected: PMUv3156machine # [ 0.005679] CPU features: detected: RAS Extension Support157machine # [ 0.005682] CPU features: detected: RASv1p1 Extension Support158machine # [ 0.005685] CPU features: detected: Random Number Generator159machine # [ 0.005690] CPU features: detected: Speculation barrier (SB)160machine # [ 0.005693] CPU features: detected: Stage-2 Force Write-Back161machine # [ 0.005696] CPU features: detected: TLB range maintenance instructions162machine # [ 0.005700] CPU features: detected: Speculative Store Bypassing Safe (SSBS)163machine # [ 0.005738] alternatives: applying system-wide alternatives164machine # [ 0.008838] CPU features: detected: BBM Level 2 without TLB conflict abort165machine # [ 0.009015] Memory: 886668K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 120600K reserved, 32768K cma-reserved)166machine # [ 0.009393] devtmpfs: initialized167machine # [ 0.011209] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)168machine # [ 0.011233] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).169machine # [ 0.011454] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL170machine # [ 0.011458] 0 pages in range for non-PLT usage171machine # [ 0.011460] 506368 pages in range for PLT usage172machine # [ 0.011585] pinctrl core: initialized pinctrl subsystem173machine # [ 0.012494] DMI not present or invalid.174machine # [ 0.015745] NET: Registered PF_NETLINK/PF_ROUTE protocol family175machine # [ 0.018141] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations176machine # [ 0.018301] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations177machine # [ 0.018460] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations178machine # [ 0.018484] audit: initializing netlink subsys (disabled)179machine # [ 0.019156] thermal_sys: Registered thermal governor 'fair_share'180machine # [ 0.019158] thermal_sys: Registered thermal governor 'bang_bang'181machine # [ 0.019162] thermal_sys: Registered thermal governor 'step_wise'182machine # [ 0.019165] thermal_sys: Registered thermal governor 'user_space'183machine # [ 0.019168] thermal_sys: Registered thermal governor 'power_allocator'184machine # [ 0.019205] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1185machine # [ 0.019214] cpuidle: using governor ladder186machine # [ 0.019220] cpuidle: using governor menu187machine # [ 0.019415] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.188machine # [ 0.019433] ASID allocator initialised with 65536 entries189machine # [ 0.020735] Serial: AMBA PL011 UART driver190machine # [ 0.026555] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1191machine # [ 0.026746] printk: console [ttyAMA0] enabled192machine # [ 0.157558] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages193machine # [ 0.157568] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page194machine # [ 0.157571] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages195machine # [ 0.157573] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page196machine # [ 0.157576] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages197machine # [ 0.157579] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page198machine # [ 0.157581] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages199machine # [ 0.157584] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page200machine # [ 0.158885] fbcon: Taking over console201machine # [ 0.158895] ACPI: Interpreter disabled.202machine # [ 0.159879] iommu: Default domain type: Translated203machine # [ 0.159883] iommu: DMA domain TLB invalidation policy: strict mode204machine # [ 0.160281] SCSI subsystem initialized205machine # [ 0.170934] usbcore: registered new interface driver usbfs206machine # [ 0.170965] usbcore: registered new interface driver hub207machine # [ 0.170981] usbcore: registered new device driver usb208machine # [ 0.171310] pps_core: LinuxPPS API ver. 1 registered209machine # [ 0.171314] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>210machine # [ 0.171323] PTP clock support registered211machine # [ 0.171375] EDAC MC: Ver: 3.0.0212machine # [ 0.173592] scmi_core: SCMI protocol bus registered213machine # [ 0.179459] FPGA manager framework214machine # [ 0.180024] vgaarb: loaded215machine # [ 0.180292] clocksource: Switched to clocksource arch_sys_counter216machine # [ 0.188736] VFS: Disk quotas dquot_6.6.0217machine # [ 0.188770] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)218machine # [ 0.191021] netfs: FS-Cache loaded219machine # [ 0.191164] pnp: PnP ACPI: disabled220machine # [ 0.197276] NET: Registered PF_INET protocol family221machine # [ 0.197430] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)222machine # [ 0.229554] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)223machine # [ 0.229599] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)224machine # [ 0.229627] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)225machine # [ 0.229670] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)226machine # [ 0.229743] TCP: Hash tables configured (established 8192 bind 8192)227machine # [ 0.229835] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)228machine # [ 0.229869] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)229machine # [ 0.229919] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)230machine # [ 0.230009] NET: Registered PF_UNIX/PF_LOCAL protocol family231machine # [ 0.230027] NET: Registered PF_XDP protocol family232machine # [ 0.230045] PCI: CLS 0 bytes, default 64233machine # [ 0.230319] Trying to unpack rootfs image as initramfs...234machine # [ 0.242764] kvm [1]: HYP mode not available235machine # [ 0.381291] Initialise system trusted keyrings236machine # [ 0.381327] Key type blacklist registered237machine # [ 0.381462] workingset: timestamp_bits=42 max_order=18 bucket_order=0238machine # [ 0.381836] squashfs: version 4.0 (2009/01/31) Phillip Lougher239machine # [ 0.381885] 9p: Installing v9fs 9p2000 file system support240machine # [ 0.381996] integrity: Platform Keyring initialized241machine # [ 0.382004] integrity: Machine keyring initialized242machine # [ 0.406775] Key type asymmetric registered243machine # [ 0.406793] Asymmetric key parser 'x509' registered244machine # [ 0.406885] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)245machine # [ 0.416374] io scheduler mq-deadline registered246machine # [ 0.416393] io scheduler kyber registered247machine # [ 0.422152] pl061_gpio 9030000.pl061: PL061 GPIO chip registered248machine # [ 0.422748] ledtrig-cpu: registered to indicate activity on CPUs249machine # [ 0.423129] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:250machine # [ 0.423144] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000251machine # [ 0.423157] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000252machine # [ 0.423164] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000253machine # [ 0.423183] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits254machine # [ 0.423205] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]255machine # [ 0.423292] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00256machine # [ 0.423299] pci_bus 0000:00: root bus resource [bus 00-ff]257machine # [ 0.423303] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]258machine # [ 0.423307] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]259machine # [ 0.423310] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]260machine # [ 0.423376] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint261machine # [ 0.423847] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint262machine # [ 0.424040] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]263machine # [ 0.424053] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]264machine # [ 0.424079] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]265machine # [ 0.424092] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]266machine # [ 0.448820] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint267machine # [ 0.449029] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]268machine # [ 0.449043] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]269machine # [ 0.449069] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]270machine # [ 0.449554] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint271machine # [ 0.449747] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]272machine # [ 0.449759] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]273machine # [ 0.449786] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]274machine # [ 0.450251] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint275machine # [ 0.450451] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]276machine # [ 0.450463] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]277machine # [ 0.450490] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]278machine # [ 0.450502] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]279machine # [ 0.450975] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint280machine # [ 0.451163] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.451189] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.451644] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint283machine # [ 0.451832] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]284machine # [ 0.451857] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]285machine # [ 0.468585] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint286machine # [ 0.468781] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]287machine # [ 0.469046] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint288machine # [ 0.469241] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]289machine # [ 0.469267] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]290machine # [ 0.469741] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint291machine # [ 0.469932] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]292machine # [ 0.469959] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]293machine # [ 0.470427] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint294machine # [ 0.470621] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]295machine # [ 0.470647] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]296machine # [ 0.471110] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint297machine # [ 0.471402] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]298machine # [ 0.471418] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]299machine # [ 0.471444] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]300machine # [ 0.471922] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint301machine # [ 0.472106] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]302machine # [ 0.472117] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]303machine # [ 0.472143] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]304machine # [ 0.497918] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned305machine # [ 0.497935] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned306machine # [ 0.497938] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned307machine # [ 0.497991] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned308machine # [ 0.498038] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned309machine # [ 0.498085] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned310machine # [ 0.498130] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned311machine # [ 0.498182] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned312machine # [ 0.498228] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned313machine # [ 0.498272] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned314machine # [ 0.498319] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned315machine # [ 0.498363] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned316machine # [ 0.498478] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned317machine # [ 0.498522] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned318machine # [ 0.498540] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned319machine # [ 0.498557] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned320machine # [ 0.498575] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned321machine # [ 0.498593] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned322machine # [ 0.498617] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned323machine # [ 0.498635] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned324machine # [ 0.498655] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned325machine # [ 0.498673] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned326machine # [ 0.498691] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned327machine # [ 0.498710] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned328machine # [ 0.498729] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned329machine # [ 0.498746] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned330machine # [ 0.498764] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned331machine # [ 0.498784] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned332machine # [ 0.498803] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned333machine # [ 0.498822] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned334machine # [ 0.498840] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned335machine # [ 0.498863] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]336machine # [ 0.498869] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]337machine # [ 0.498872] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]338machine # [ 0.499723] pci 0000:00:07.0: enabling device (0000 -> 0002)339machine # [ 0.552559] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)340machine # [ 0.554799] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)341machine # [ 0.572587] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)342machine # [ 0.574772] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)343machine # [ 0.578536] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)344machine # [ 0.581459] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)345machine # [ 0.584004] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)346machine # [ 0.593875] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)347machine # [ 0.605119] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)348machine # [ 0.607215] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)349machine # [ 0.611581] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)350machine # [ 0.625285] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled351machine # [ 0.630829] msm_serial: driver initialized352machine # [ 0.631052] SuperH (H)SCI(F) driver initialized353machine # [ 0.631114] STM32 USART driver initialized354machine # [ 0.670310] loop: module loaded355machine # [ 0.670519] virtio_blk virtio2: 1/0/0 default/read/poll queues356machine # [ 0.671418] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)357machine # [ 0.677108] megasas: 07.734.00.00-rc1358machine # [ 0.677856] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]359machine # [ 0.679921] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000360machine # [ 0.679945] Intel/Sharp Extended Query Table at 0x0031361machine # [ 0.693555] Using buffer write method362machine # [ 0.693615] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]363machine # [ 0.695417] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000364machine # [ 0.695439] Intel/Sharp Extended Query Table at 0x0031365machine # [ 0.700845] Using buffer write method366machine # [ 0.700867] Concatenating MTD devices:367machine # [ 0.700869] (0): "0.flash"368machine # [ 0.700872] (1): "0.flash"369machine # [ 0.700874] into device "0.flash"370machine # [ 1.002394] Freeing initrd memory: 26060K371machine # [ 1.009045] tun: Universal TUN/TAP device driver, 1.6372machine # [ 1.013333] thunder_xcv, ver 1.0373machine # [ 1.013367] thunder_bgx, ver 1.0374machine # [ 1.013386] nicpf, ver 1.0375machine # [ 1.013990] e1000: Intel(R) PRO/1000 Network Driver376machine # [ 1.013994] e1000: Copyright (c) 1999-2006 Intel Corporation.377machine # [ 1.014021] e1000e: Intel(R) PRO/1000 Network Driver378machine # [ 1.014027] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.379machine # [ 1.014052] igb: Intel(R) Gigabit Ethernet Network Driver380machine # [ 1.014056] igb: Copyright (c) 2007-2014 Intel Corporation.381machine # [ 1.014077] igbvf: Intel(R) Gigabit Virtual Function Network Driver382machine # [ 1.014081] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.383machine # [ 1.014219] sky2: driver version 1.30384machine # [ 1.015896] usbcore: registered new interface driver usb-storage385machine # [ 1.016033] usbcore: registered new interface driver usbserial_generic386machine # [ 1.016046] usbserial: USB Serial support registered for generic387machine # [ 1.027151] ehci-pci 0000:00:07.0: EHCI Host Controller388machine # [ 1.027173] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1389machine # [ 1.027362] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000390machine # [ 1.027888] hv_vmbus: registering driver hyperv_keyboard391machine # [ 1.031823] rtc-pl031 9010000.pl031: registered as rtc0392machine # [ 1.031843] rtc-pl031 9010000.pl031: setting system clock to 2026-10-20T03:08:09 UTC (1792465689)393machine # [ 1.032162] i2c_dev: i2c /dev entries driver394machine # [ 1.038062] sdhci: Secure Digital Host Controller Interface driver395machine # [ 1.038069] sdhci: Copyright(c) Pierre Ossman396machine # [ 1.038362] Synopsys Designware Multimedia Card Interface Driver397machine # [ 1.038759] sdhci-pltfm: SDHCI platform and OF driver helper398machine # [ 1.043318] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00399machine # [ 1.043665] hub 1-0:1.0: USB hub found400machine # [ 1.043690] hub 1-0:1.0: 6 ports detected401machine # [ 1.044189] hid: raw HID events driver (C) Jiri Kosina402machine # [ 1.046879] usbcore: registered new interface driver usbhid403machine # [ 1.046883] usbhid: USB HID core driver404machine # [ 1.048150] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available405machine # [ 1.052213] drop_monitor: Initializing network drop monitor service406machine # [ 1.053221] NET: Registered PF_INET6 protocol family407machine # [ 1.053807] Segment Routing with IPv6408machine # [ 1.053819] In-situ OAM (IOAM) with IPv6409machine # [ 1.053848] NET: Registered PF_PACKET protocol family410machine # [ 1.053905] 9pnet: Installing 9P2000 support411machine # [ 1.053949] Key type dns_resolver registered412machine # [ 1.063784] registered taskstats version 1413machine # [ 1.063939] Loading compiled-in X.509 certificates414machine # [ 1.073190] Demotion targets for Node 0: null415machine # [ 1.073310] Key type .fscrypt registered416machine # [ 1.073314] Key type fscrypt-provisioning registered417machine # [ 1.073414] ima: No TPM chip found, activating TPM-bypass!418machine # [ 1.073436] ima: Allocated hash algorithm: sha1419machine # [ 1.073457] ima: No architecture policies found420machine # [ 1.074140] input: gpio-keys as /devices/platform/gpio-keys/input/input0421machine # [ 1.097552] clk: Disabling unused clocks422machine # [ 1.097571] PM: genpd: Disabling unused power domains423machine # [ 1.103459] Freeing unused kernel memory: 7808K424machine # [ 1.103672] Run /init as init process425machine # [ 1.121150] fuse: module verification failed: signature and/or required key missing - tainting kernel426machine # [ 1.123755] fuse: init (API version 7.45)427machine # [ 1.128584] virtiofs virtio6: discovered new tag: nix-store428machine # [ 1.129481] virtiofs virtio6: virtio_fs_setup_dax: No cache capability429machine # [ 1.146152] virtiofs virtio7: discovered new tag: shared430machine # [ 1.147000] virtiofs virtio7: virtio_fs_setup_dax: No cache capability431machine # [ 1.150058] virtiofs virtio8: discovered new tag: xchg432machine # [ 1.150828] virtiofs virtio8: virtio_fs_setup_dax: No cache capability433machine # [ 1.163074] systemd[1]: Successfully made /usr/ read-only.434machine # [ 1.163536] 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.163551] systemd[1]: Detected virtualization qemu.436machine # [ 1.163572] systemd[1]: Detected architecture arm64.437machine # [ 1.163578] systemd[1]: Running in initrd.438machine # [ 1.163869] systemd[1]: Initializing machine ID from random generator.439machine # [ 1.163962] systemd[1]: Hostname set to <machine>.440machine # [ 1.265927] systemd[1]: bpf-restrict-fs: LSM BPF program attached441machine # [ 1.292435] usb 1-1: new high-speed USB device number 2 using ehci-pci442machine # [ 1.325300] systemd[1]: Queued start job for default target Initrd Default Target.443machine # [ 1.331486] systemd[1]: Created slice Slice /system/modprobe.444machine # [ 1.331687] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.445machine # [ 1.331716] systemd[1]: Expecting device /dev/disk/by-label/nixos...446machine # [ 1.331738] systemd[1]: Reached target Path Units.447machine # [ 1.331752] systemd[1]: Reached target Slice Units.448machine # [ 1.331767] systemd[1]: Reached target Swaps.449machine # [ 1.331780] systemd[1]: Reached target Timer Units.450machine # [ 1.331912] systemd[1]: Listening on D-Bus System Message Bus Socket.451machine # [ 1.332053] systemd[1]: Listening on Journal Socket (/dev/log).452machine # [ 1.332201] systemd[1]: Listening on Journal Sockets.453machine # [ 1.332273] systemd[1]: Listening on udev Kernel Socket.454machine # [ 1.340625] systemd[1]: Listening on udev Varlink Socket.455machine # [ 1.340655] systemd[1]: Reached target Socket Units.456machine # [ 1.342135] systemd[1]: Starting Create List of Static Device Nodes...457machine # [ 1.342194] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs458machine # [ 1.353895] systemd[1]: Mounting Kernel Configuration File System...459machine # [ 1.370837] systemd[1]: Starting Journal Service...460machine # [ 1.380533] systemd[1]: Starting Load Kernel Modules...461machine # [ 1.380648] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os462machine # [ 1.386635] systemd[1]: Starting Coldplug All udev Devices...463machine # [ 1.396820] systemd[1]: Finished Create List of Static Device Nodes.464machine # [ 1.432525] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...465machine # [ 1.433291] systemd[1]: Mounted Kernel Configuration File System.466machine # [ 1.441687] systemd-journald[71]: Collecting audit messages is disabled.467machine # [ 1.470949] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.468machine # [ 1.475497] 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/input1469machine # [ 1.486373] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev470machine # [ 1.490354] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.471machine # [ 1.492076] systemd[1]: Starting Create Static Device Nodes in /dev...472machine # [ 1.501947] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0473machine # [ 1.502202] [drm] features: -virgl +edid -resource_blob -host_visible474machine # [ 1.502208] [drm] features: -context_init475machine # [ 1.502966] [drm] number of scanouts: 1476machine # [ 1.502981] [drm] number of cap sets: 0477machine # [ 1.524694] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic478machine # [ 1.524712] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0479machine # [ 1.560841] Console: switching to colour frame buffer device 160x50480machine # [ 1.567264] systemd[1]: Finished Create Static Device Nodes in /dev.481machine # [ 1.567487] systemd[1]: Reached target Preparation for Local File Systems.482machine # [ 1.567508] systemd[1]: Reached target Local File Systems.483machine # [ 1.568557] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0484machine # [ 1.576968] systemd[1]: Starting Rule-based Manager for Device Events and Files...485machine # [ 1.589036] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device486machine # [ 1.613022] systemd[1]: Finished Load Kernel Modules.487machine # [ 1.617615] systemd[1]: Starting Apply Kernel Variables...488machine # [ 1.618318] systemd-modules-load[72]: Inserted module 'dm_mod'489machine # [ 1.620235] systemd-modules-load[72]: Module 'virtio_balloon' is built in490machine # [ 1.640619] systemd[1]: Started Journal Service.491machine # [ 1.632532] systemd-modules-load[72]: Module 'virtio_console' is built in492machine # [ 1.636339] systemd-modules-load[72]: Inserted module 'virtio_gpu'493machine # [ 1.652481] systemd-modules-load[72]: Module 'virtio_rng' is built in494machine # [ 1.653739] systemd[1]: Starting Create System Files and Directories...495machine # [ 1.670747] systemd[1]: Finished Apply Kernel Variables.496machine # [ 1.688413] usb 1-2: new high-speed USB device number 3 using ehci-pci497machine # [ 1.680999] systemd-udevd[78]: Using default interface naming scheme 'v261'.498machine # [ 1.730374] systemd[1]: Finished Create System Files and Directories.499machine # [ 1.733064] systemd[1]: Started Rule-based Manager for Device Events and Files.500machine # [ 1.822706] systemd[1]: Starting Virtual Console Setup...501machine # [ 1.844148] 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.847376] 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.913736] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.504machine # [ 1.920243] systemd[1]: Finished Virtual Console Setup.505machine # [ 2.576275] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.506machine # [ 2.625384] systemd[1]: Finished Coldplug All udev Devices.507machine # [ 2.627272] systemd[1]: Reached target System Initialization.508machine # [ 2.629045] systemd[1]: Reached target Basic System.509machine # [ 2.630863] (udev-worker)[90]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.510machine # [ 2.637137] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.511machine # [ 2.668765] systemd[1]: Found device /dev/disk/by-label/nixos.512machine # [ 2.670842] systemd[1]: Reached target Initrd Root Device.513machine # [ 2.674172] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...514machine # [ 2.720454] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks515machine # [ 2.727414] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.516machine # [ 2.733022] systemd[1]: Mounting /sysroot...517machine # [ 2.783326] EXT4-fs (vda): mounted filesystem cc22c44e-967a-456d-ab1e-dde31b8c1f4d r/w with ordered data mode. Quota mode: none.518machine # [ 2.774565] systemd[1]: Mounted /sysroot.519machine # [ 2.777984] systemd[1]: Reached target Initrd Root File System.520machine # [ 2.783770] systemd[1]: Starting Mountpoints Configured in the Real Root...521machine # [ 2.817766] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.522machine # [ 2.826361] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...523machine # [ 2.832139] systemd[1]: Reloading...524machine # [ 3.023332] systemd[1]: Reloading finished in 198 ms.525machine # [ 3.060649] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...526machine # [ 3.065908] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...527machine # [ 3.074185] systemd[1]: initrd-parse-etc.service: Deactivated successfully.528machine # [ 3.077296] systemd[1]: Finished Mountpoints Configured in the Real Root.529machine # [ 3.079354] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.530machine # [ 3.383732] systemd[1]: Mounting /sysroot/nix/.ro-store...531machine # [ 3.395142] systemd[1]: Mounting /sysroot/nix/.rw-store...532machine # [ 3.409569] systemd[1]: Mounting /sysroot/run...533machine # [ 3.429241] systemd[1]: Mounting /sysroot/tmp/shared...534machine # [ 3.459909] systemd[1]: Mounting /sysroot/tmp/xchg...535machine # [ 3.513068] systemd[1]: Mounted /sysroot/nix/.ro-store.536machine # [ 3.523671] systemd[1]: Mounted /sysroot/nix/.rw-store.537machine # [ 3.526946] systemd[1]: Mounted /sysroot/run.538machine # [ 3.546576] systemd[1]: Starting rw-sysroot-nix-store.service...539machine # [ 3.550167] systemd[1]: Mounted /sysroot/tmp/shared.540machine # [ 3.563984] systemd[1]: Mounted /sysroot/tmp/xchg.541machine # [ 3.582904] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.542machine # [ 3.584717] systemd[1]: Finished rw-sysroot-nix-store.service.543machine # [ 3.948955] (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.955172] (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 # [ 3.987897] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.546machine # [ 3.990701] systemd[1]: Stopped Virtual Console Setup.547machine # [ 3.993477] systemd[1]: Stopping Virtual Console Setup...548machine # [ 3.996221] systemd[1]: Starting Virtual Console Setup...549machine # [ 4.024691] systemd-vconsole-setup[150]: Configuration of first virtual console was skipped, ignoring remaining ones.550machine # [ 4.028350] systemd[1]: Finished Virtual Console Setup.551machine # [ 4.382315] systemd[1]: Mounting /sysroot/nix/store...552machine # [ 4.428613] systemd[1]: Mounted /sysroot/nix/store.553machine # [ 4.431019] systemd[1]: Reached target Initrd File Systems.554machine # [ 4.435354] systemd[1]: Starting Find NixOS closure...555machine # [ 4.444455] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...556machine # [ 4.492563] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.557machine # [ 4.497951] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.558machine # [ 4.509413] systemd[1]: Finished Find NixOS closure.559machine # [ 4.512253] systemd[1]: Reached target Initrd Default Target.560machine # [ 4.516309] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...561machine # [ 4.545818] systemd[1]: Stopped target Initrd Default Target.562machine # [ 4.548422] systemd[1]: Stopped target Basic System.563machine # [ 4.552636] systemd[1]: Stopped target Initrd Root Device.564machine # [ 4.553758] systemd[1]: Stopped target Path Units.565machine # [ 4.555560] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.566machine # [ 4.560475] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.567machine # [ 4.561808] systemd[1]: Stopped target Slice Units.568machine # [ 4.562650] systemd[1]: Stopped target Socket Units.569machine # [ 4.563505] systemd[1]: Stopped target System Initialization.570machine # [ 4.569338] systemd[1]: Stopped target Swaps.571machine # [ 4.573213] systemd[1]: Stopped target Timer Units.572machine # [ 4.575036] systemd[1]: dbus.socket: Deactivated successfully.573machine # [ 4.581187] systemd[1]: Closed D-Bus System Message Bus Socket.574machine # [ 4.583325] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.575machine # [ 4.593096] systemd[1]: Stopped Find NixOS closure.576machine # [ 4.594032] systemd[1]: Starting rw-sysroot-nix-store.service...577machine # [ 4.595114] systemd[1]: systemd-sysctl.service: Deactivated successfully.578machine # [ 4.603723] systemd[1]: Stopped Apply Kernel Variables.579machine # [ 4.606199] systemd[1]: systemd-modules-load.service: Deactivated successfully.580machine # [ 4.609124] systemd[1]: Stopped Load Kernel Modules.581machine # [ 4.611176] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.582machine # [ 4.616551] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.583machine # [ 4.617841] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.584machine # [ 4.618917] systemd[1]: Stopped Create System Files and Directories.585machine # [ 4.619836] systemd[1]: Stopped target Local File Systems.586machine # [ 4.624578] systemd[1]: Stopped target Preparation for Local File Systems.587machine # [ 4.626663] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.588machine # [ 4.630438] systemd[1]: Stopped Coldplug All udev Devices.589machine # [ 4.631392] systemd[1]: Stopping Rule-based Manager for Device Events and Files...590machine # [ 4.632985] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.591machine # [ 4.634110] systemd[1]: Stopped Virtual Console Setup.592machine # [ 4.634884] systemd[1]: initrd-cleanup.service: Deactivated successfully.593machine # [ 4.635866] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.594machine # [ 4.637045] systemd[1]: systemd-udevd.service: Deactivated successfully.595machine # [ 4.638011] systemd[1]: Stopped Rule-based Manager for Device Events and Files.596machine # [ 4.639046] systemd[1]: systemd-udevd.service: Consumed 1.346s CPU time over 3.048s wall clock time, 25.9M memory peak.597machine # [ 4.644307] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.598machine # [ 4.645549] systemd[1]: Finished rw-sysroot-nix-store.service.599machine # [ 4.647986] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully.600machine # [ 4.652239] systemd[1]: Closed udev Varlink Socket.601machine # [ 4.653182] systemd[1]: Starting Cleanup udev Database...602machine # [ 4.656201] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.603machine # [ 4.657570] systemd[1]: Stopped Create Static Device Nodes in /dev.604machine # [ 4.660144] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.605machine # [ 4.664119] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.606machine # [ 4.665340] systemd[1]: kmod-static-nodes.service: Deactivated successfully.607machine # [ 4.666347] systemd[1]: Stopped Create List of Static Device Nodes.608machine # [ 4.697254] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.609machine # [ 4.700582] systemd[1]: Finished Cleanup udev Database.610machine # [ 4.701939] systemd[1]: Reached target Switch Root.611machine # [ 4.703267] systemd[1]: Starting NixOS Activation...612machine # [ 4.798670] initrd-nixos-activation-start[173]: booting system configuration /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test613machine # [ 4.835464] initrd-nixos-activation-start[173]: running activation script...614machine # [ 5.060593] initrd-nixos-activation-start[196]: setting up /etc...615machine # [ 5.181890] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.616machine # [ 5.185203] systemd[1]: Finished NixOS Activation.617machine # [ 5.186566] systemd[1]: Starting Switch Root...618machine # [ 5.205615] systemd[1]: Switching root.619machine # [ 5.389449] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).620machine # [ 5.481647] NET: Registered PF_VSOCK protocol family621machine # [ 5.555544] 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.564001] systemd[1]: Detected virtualization qemu.623machine # [ 5.564065] systemd[1]: Detected architecture arm64.624machine # [ 5.564172] systemd[1]: Detected first boot.625machine # [ 5.568875] systemd[1]: Initializing machine ID from random generator.626machine # [ 5.703405] systemd[1]: bpf-restrict-fs: LSM BPF program attached627machine # [ 5.865602] systemd[1]: Applying preset policy.628machine # [ 6.087832] systemd[1]: Populated /etc with preset unit settings.629machine # [ 6.304243] systemd[1]: initrd-switch-root.service: Deactivated successfully.630machine # [ 6.306444] systemd[1]: Stopped initrd-switch-root.service.631machine # [ 6.310240] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.632machine # [ 6.312222] systemd[1]: Created slice Slice /system/getty.633machine # [ 6.315946] systemd[1]: Created slice User and Session Slice.634machine # [ 6.317555] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.635machine # [ 6.318205] systemd[1]: Started Forward Password Requests to Wall Directory Watch.636machine # [ 6.318768] systemd[1]: Expecting device /dev/hvc0...637machine # [ 6.319339] systemd[1]: Expecting device /dev/ttyAMA0...638machine # [ 6.319923] systemd[1]: Reached target Local Encrypted Volumes.639machine # [ 6.325354] systemd[1]: Stopped target initrd-fs.target.640machine # [ 6.325814] systemd[1]: Stopped target initrd-root-fs.target.641machine # [ 6.326507] systemd[1]: Stopped target initrd-switch-root.target.642machine # [ 6.327124] systemd[1]: Reached target Virtual Machines and Containers.643machine # [ 6.327707] systemd[1]: Reached target Path Units.644machine # [ 6.328270] systemd[1]: Reached target Remote File Systems.645machine # [ 6.333950] systemd[1]: Reached target Slice Units.646machine # [ 6.334378] systemd[1]: Reached target Swaps.647machine # [ 6.337787] systemd[1]: Listening on Query the User Interactively for a Password.648machine # [ 6.339794] systemd[1]: Listening on Process Core Dump Socket.649machine # [ 6.343647] systemd[1]: Listening on Credential Encryption/Decryption.650machine # [ 6.346517] systemd[1]: Listening on Factory Reset Management.651machine # [ 6.347069] systemd[1]: Listening on Hostname Service Socket.652machine # [ 6.352218] systemd[1]: Starting Journal Log Access Socket...653machine # [ 6.356069] systemd[1]: Listening on Journal Audit Socket.654machine # [ 6.360003] systemd[1]: Listening on Console Output Muting Service Socket.655machine # [ 6.362083] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.656machine # [ 6.363815] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os657machine # [ 6.368495] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki658machine # [ 6.373781] systemd[1]: Listening on Disk Repartitioning Service Socket.659machine # [ 6.374387] systemd[1]: Listening on udev Varlink Socket.660machine # [ 6.380203] systemd[1]: Mounting Huge Pages File System...661machine # [ 6.389516] systemd[1]: Mounting POSIX Message Queue File System...662machine # [ 6.394496] systemd[1]: Mounting Kernel Debug File System...663machine # [ 6.416604] systemd[1]: Mounting Kernel Trace File System...664machine # [ 6.433113] systemd[1]: Starting Create List of Static Device Nodes...665machine # [ 6.434694] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs666machine # [ 6.457357] systemd[1]: Mounting Kernel Configuration File System...667machine # [ 6.458015] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm668machine # [ 6.470938] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore669machine # [ 6.471584] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse670machine # [ 6.499283] systemd[1]: Mounting FUSE Control File System...671machine # [ 6.500186] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67672machine # [ 6.555197] systemd[1]: Starting Journal Service...673machine # [ 6.574652] systemd[1]: Starting Load Kernel Modules...674machine # [ 6.588772] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...675machine # [ 6.615352] systemd[1]: Starting Remount Root and Kernel File Systems...676machine # [ 6.615818] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os677machine # [ 6.639128] systemd-journald[267]: Collecting audit messages is enabled.678machine # [ 6.638494] systemd[1]: Queued start job for default target Multi-User System.679machine # [ 6.643151] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0.680machine # [ 6.649883] systemd[1]: Unnecessary job was removed for /sys/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0.681machine # [ 6.651885] systemd[1]: systemd-journald.service: Deactivated successfully.682machine # [ 6.672089] systemd[1]: Starting Coldplug All udev Devices...683machine # [ 6.676201] systemd[1]: Started Journal Service.684machine # [ 6.692206] systemd[1]: Listening on Journal Log Access Socket.685machine # [ 6.696578] systemd[1]: Mounted Huge Pages File System.686machine # [ 6.697571] systemd[1]: Mounted POSIX Message Queue File System.687machine # [ 6.698531] systemd[1]: Mounted Kernel Debug File System.688machine # [ 6.699859] systemd[1]: Mounted Kernel Trace File System.689machine # [ 6.710940] systemd[1]: Finished Create List of Static Device Nodes.690machine # [ 6.719510] systemd[1]: Mounted Kernel Configuration File System.691machine # [ 6.743021] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...692machine # [ 6.759164] systemd-modules-load[268]: Module 'atkbd' is built in693machine # [ 6.762395] systemd-modules-load[268]: Module 'loop' is built in694machine # [ 6.772292] systemd[1]: Finished Load Kernel Modules.695machine # [ 6.808533] EXT4-fs (vda): re-mounted cc22c44e-967a-456d-ab1e-dde31b8c1f4d.696machine # [ 6.800836] systemd-oomd[270]: No swap; memory pressure usage will be degraded697machine # [ 6.810914] systemd[1]: Starting Firewall...698machine # [ 6.820411] systemd[1]: Starting Apply Kernel Variables...699machine # [ 6.821456] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.700machine # [ 6.855349] systemd[1]: Finished Remount Root and Kernel File Systems.701machine # [ 6.871550] systemd[1]: Listening on Disk Image Download Service Socket.702machine # [ 6.894278] systemd[1]: Starting Flush Journal to Persistent Storage...703machine # [ 6.898184] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore704machine # [ 6.908548] systemd[1]: Starting Load/Save OS Random Seed...705machine # [ 6.909595] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os706machine # [ 6.910922] systemd[1]: Mounted FUSE Control File System.707machine # [ 6.980266] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.708machine # [ 6.996910] systemd[1]: Starting Create Static Device Nodes in /dev...709machine # [ 7.033171] systemd-journald[267]: Received client request to flush runtime journal.710machine # [ 7.100240] systemd[1]: Finished Apply Kernel Variables.711machine # [ 7.101339] systemd[1]: Finished Load/Save OS Random Seed.712machine # [ 7.110531] systemd[1]: Reached target First Boot Complete.713machine # [ 7.111562] systemd[1]: Finished Flush Journal to Persistent Storage.714machine # [ 7.254361] systemd[1]: Finished Create Static Device Nodes in /dev.715machine # [ 7.255569] systemd[1]: Reached target Preparation for Local File Systems.716machine # [ 7.264534] systemd[1]: Starting Rule-based Manager for Device Events and Files...717machine # [ 7.300388] systemd[1]: Mounting /run/wrappers...718machine # [ 7.396885] systemd[1]: Mounted /run/wrappers.719machine # [ 7.397780] systemd[1]: Reached target Local File Systems.720machine # [ 7.404174] systemd[1]: Listening on Boot Loader Control Service Socket.721machine # [ 7.408177] systemd[1]: Starting Create SUID/SGID Wrappers...722machine # [ 7.409361] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.723machine # [ 7.421285] systemd[1]: Starting Save Transient machine-id to Disk...724machine # [ 7.425733] systemd-udevd[304]: Using default interface naming scheme 'v261'.725machine # [ 7.436234] systemd[1]: Starting Create System Files and Directories...726machine # [ 7.611402] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.727machine # [ 7.629940] systemd[1]: Finished Save Transient machine-id to Disk.728machine # [ 7.649832] systemd[1]: Started Rule-based Manager for Device Events and Files.729machine # [ 7.673165] systemd[1]: Finished Create System Files and Directories.730machine # [ 7.688986] systemd[1]: Starting Rebuild Journal Catalog...731machine # [ 7.704175] systemd[1]: Starting Record System Boot/Shutdown in UTMP...732machine # [ 7.831930] systemd[1]: Finished Record System Boot/Shutdown in UTMP.733machine # [ 7.882330] systemd[1]: Finished Rebuild Journal Catalog.734machine # [ 7.890703] systemd[1]: Starting Update is Completed...735machine # [ 7.968625] systemd[1]: Finished Update is Completed.736machine # [ 8.247016] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs737machine # [ 8.322184] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse738machine # [ 8.400146] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.739machine # [ 8.402916] systemd[1]: Finished Create SUID/SGID Wrappers.740machine # [ 8.596373] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.741machine # [ 8.627062] systemd[1]: Finished Coldplug All udev Devices.742machine # [ 8.630565] systemd[1]: Reached target System Initialization.743machine # [ 8.631512] systemd[1]: Started Discard unused filesystem blocks once a week.744machine # [ 8.637946] systemd[1]: Started Daily Cleanup of Temporary Directories.745machine # [ 8.639007] systemd[1]: Reached target Timer Units.746machine # [ 8.639821] systemd[1]: Listening on D-Bus System Message Bus Socket.747machine # [ 8.648541] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.748machine # [ 8.650189] systemd[1]: Reached target Socket Units.749machine # [ 8.651016] systemd[1]: Reached target Basic System.750machine # [ 8.651827] systemd[1]: Starting Import lastlog data into lastlog2 database...751machine # [ 8.664530] systemd[1]: Starting Name Service Cache Daemon (nsncd)...752machine # [ 8.665615] systemd[1]: Starting Post-Boot Actions...753machine # [ 8.688834] systemd[1]: Started Reset console on configuration changes.754machine # [ 8.741077] systemd[1]: Starting resolvconf update...755machine # [ 8.792228] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.756machine # [ 8.805785] systemd[1]: Started backdoor.service.757machine # [ 8.832268] systemd[1]: Starting D-Bus System Message Bus...758machine # [ 8.888084] nsncd[425]: Oct 20 03:08:17.368 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"759machine # [ 8.901622] systemd[1]: Finished Post-Boot Actions.760machine # [ 8.902531] systemd[1]: Started Name Service Cache Daemon (nsncd).761machine # [ 8.935048] systemd[1]: Reached target Host and Network Name Lookups.762machine # [ 8.944514] systemd[1]: Reached target User and Group Name Lookups.763machine # [ 8.980168] systemd[1]: Starting User Login Management...764machine # connecting to host...765machine # [ 9.049814] (udev-worker)[400]: Network interface NamePolicy= disabled on kernel command line.766machine # [ 9.059408] (udev-worker)[337]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.767machine # [ 9.071572] (udev-worker)[337]: Network interface NamePolicy= disabled on kernel command line.768machine # [ 9.102800] systemd[1]: Finished Import lastlog data into lastlog2 database.769machine: Guest shell says: b'Spawning backdoor root shell...\n'770machine: connected to guest root shell771machine: (connecting took 9.38 seconds)772machine: (finished: waiting for the VM to finish booting, in 9.89 seconds)773machine # [ 9.181272] dbus-broker-launch[435]: Looking up NSS user entry for 'systemd-timesync'...774machine # [ 9.193086] dbus-broker-launch[435]: NSS returned no entry for 'systemd-timesync'775machine # [ 9.194296] dbus-broker-launch[435]: Invalid user-name in /nix/store/lwvd8zypc7d831h4k85sqslcsqwvyagn-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"776machine # [ 9.240081] systemd[1]: Started D-Bus System Message Bus.777machine # [ 9.293978] systemd-logind[453]: New seat seat0.778machine # [ 9.310565] dbus-broker-launch[435]: Ready779machine # [ 9.397543] systemd[1]: Started User Login Management.780machine # [ 9.439634] systemd[1]: Stopped target Host and Network Name Lookups.781machine # [ 9.445834] systemd[1]: Stopping Host and Network Name Lookups...782machine # [ 9.446816] systemd[1]: Stopped target User and Group Name Lookups.783machine # [ 9.447731] systemd[1]: Stopping User and Group Name Lookups...784machine # [ 9.466961] systemd[1]: Starting linger-users.service...785machine # [ 9.467932] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...786machine # [ 9.477507] systemd[1]: nscd.service: Deactivated successfully.787machine # [ 9.478445] systemd[1]: Stopped Name Service Cache Daemon (nsncd).788machine # [ 9.540146] systemd[1]: Starting Name Service Cache Daemon (nsncd)...789machine # [ 9.636523] systemd[1]: Finished resolvconf update.790machine # [ 9.654556] systemd[1]: Starting DHCP Client...791machine # [ 9.661562] systemd[1]: Condition check resulted in Virtio network device being skipped.792machine # [ 9.663277] nsncd[544]: Oct 20 03:08:18.145 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"793machine # [ 9.680426] systemd[1]: Condition check resulted in Virtio network device being skipped.794machine # [ 9.681684] systemd[1]: Started Name Service Cache Daemon (nsncd).795machine # [ 9.682574] systemd[1]: Reached target Host and Network Name Lookups.796machine # [ 9.683549] systemd[1]: Reached target User and Group Name Lookups.797machine # [ 9.749562] systemd[1]: Created slice Slice /user/1001.798machine # [ 9.750565] systemd[1]: Starting User Runtime Directory /run/user/1001...799machine # [ 9.797942] systemd[1]: linger-users.service: Deactivated successfully.800machine # [ 9.818584] systemd[1]: Finished linger-users.service.801machine # [ 9.819570] systemd-logind[453]: Watching system buttons on /dev/input/event0 (gpio-keys)802machine # [ 9.885837] systemd[1]: Finished User Runtime Directory /run/user/1001.803machine # [ 9.886926] systemd[1]: Starting User Manager for UID 1001...804machine # [ 10.004850] mousedev: PS/2 mouse device common for all mice805machine # [ 10.030891] (systemd)[582]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)806machine # [ 10.075905] systemd-logind[453]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.807machine # [ 10.106144] systemd-logind[453]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)808machine # [ 10.142916] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy809machine # [ 10.454580] systemd[582]: Queued start job for default target Main User Target.810machine # [ 10.464167] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy811machine # [ 10.466053] systemd[582]: Created slice User Application Slice.812machine # [ 10.467003] systemd[582]: Started Daily Cleanup of User's Temporary Directories.813machine # [ 10.509733] systemd[582]: Reached target Paths.814machine # [ 10.510630] systemd[582]: Reached target Timers.815machine # [ 10.537129] systemd[1]: Finished Firewall.816machine # [ 10.543775] systemd[1]: Reached target Preparation for Network.817machine # [ 10.550770] systemd[582]: Starting D-Bus User Message Bus Socket...818machine # [ 10.551783] systemd[582]: Starting Create User Files and Directories...819machine # [ 10.580877] systemd[1]: Starting Address configuration of eth1...820machine # [ 10.582014] systemd[1]: Starting Extra networking commands....821machine # [ 10.583029] systemd[582]: Finished Create User Files and Directories.822machine # [ 10.614576] systemd[582]: Listening on D-Bus User Message Bus Socket.823machine # [ 10.615711] systemd[582]: Reached target Sockets.824machine # [ 10.629069] systemd[582]: Reached target Basic System.825machine # [ 10.633198] systemd[1]: Started User Manager for UID 1001.826machine # [ 10.634189] systemd[582]: Starting Run user-specific NixOS activation...827machine # [ 10.652168] systemd[582]: Finished Run user-specific NixOS activation.828machine # [ 10.653364] systemd[582]: Reached target Main User Target.829machine # [ 10.654186] systemd[582]: Startup finished in 520ms.830machine # [ 10.747843] dhcpcd[589]: dhcpcd-10.3.2 starting831machine # [ 10.764664] dhcpcd[627]: dev: loaded udev832machine # [ 10.830725] 8021q: 802.1Q VLAN Support v1.8833machine # [ 10.939579] cfg80211: Loading compiled-in X.509 certificates for regulatory database834machine # [ 10.946244] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'835machine # [ 10.946724] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'836machine # [ 10.951201] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2837machine # [ 10.951525] cfg80211: failed to load regulatory.db838machine # [ 10.966219] 8021q: adding VLAN 0 to HW filter on device eth1839machine # [ 11.064640] network-addresses-eth1-start[601]: adding address 192.168.1.1/24... done840machine # [ 11.157587] network-addresses-eth1-start[601]: adding address 2001:db8:1::1/64... done841machine # [ 11.267593] systemd[1]: Finished Address configuration of eth1.842machine # [ 11.309355] dhcpcd[648]: /nix/store/48w99jvsb41hnyml943xqzrklzdb5fww-openresolv-3.17.4/sbin/.resolvconf-wrapped: line 1250: kill: (614) - Operation not permitted843machine # [ 11.311400] dhcpcd[648]: .resolvconf-wrapped: clearing stale lock pid 614844machine # [ 11.551399] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3845machine # [ 11.624886] 8021q: adding VLAN 0 to HW filter on device eth0846machine # [ 11.621236] dhcpcd[627]: eth0: waiting for carrier847machine # [ 11.622066] dhcpcd[627]: eth0: carrier acquired848machine # [ 11.752456] dhcpcd[627]: DUID 00:01:00:01:32:69:97:a4:52:54:00:12:34:56849machine # [ 11.753580] dhcpcd[627]: eth0: IAID 00:12:34:56850machine # [ 11.754483] dhcpcd[627]: eth0: adding address fe80::5054:ff:fe12:3456851machine # [ 11.903995] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.852machine # [ 11.930522] systemd[1]: Starting Virtual Console Setup...853machine # [ 11.948457] systemd[1]: Finished Extra networking commands..854machine # [ 11.956531] systemd[1]: Reached target Network.855machine # [ 11.957336] systemd[1]: Starting Permit User Sessions...856machine # [ 11.984972] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.857machine # [ 11.986164] systemd[1]: Stopped Virtual Console Setup.858machine # [ 11.991797] systemd[1]: Starting Virtual Console Setup...859machine # [ 11.996827] systemd-logind[453]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)860machine # [ 12.026914] systemd[1]: Finished Permit User Sessions.861machine # [ 12.039632] systemd[1]: Started Getty on tty1.862machine # [ 12.041590] systemd[1]: Reached target Login Prompts.863machine # [ 12.154643] systemd-vconsole-setup[707]: Configuration of first virtual console was skipped, ignoring remaining ones.864machine # [ 12.160389] systemd[1]: Finished Virtual Console Setup.865machine # [ 12.913164] dhcpcd[627]: eth0: soliciting a DHCP lease866machine # [ 12.916821] dhcpcd[627]: eth0: offered 10.0.2.15 from 10.0.2.2867machine # [ 12.924322] dhcpcd[627]: eth0: probing address 10.0.2.15/24868machine # [ 14.032716] dhcpcd[627]: eth0: soliciting an IPv6 router869machine # [ 14.034762] dhcpcd[627]: eth0: Router Advertisement from fe80::2870machine # [ 14.036431] dhcpcd[627]: eth0: adding address fec0::5054:ff:fe12:3456/64871machine # [ 14.037914] dhcpcd[627]: eth0: adding route to fec0::/64872machine # [ 14.039101] dhcpcd[627]: eth0: adding default route via fe80::2873machine # [ 18.371742] dhcpcd[627]: eth0: leased 10.0.2.15 for 86400 seconds874machine # [ 18.374690] dhcpcd[627]: eth0: adding route to 10.0.2.0/24875machine # [ 18.377805] dhcpcd[627]: eth0: adding default route via 10.0.2.2876machine # [ 18.499417] systemd[1]: Started DHCP Client.877machine # [ 18.502211] systemd[1]: Reached target Multi-User System.878machine # [ 18.503160] systemd[1]: Startup finished in 1.092s (kernel) + 4.352s (initrd) + 13.056s (userspace) = 18.501s.879machine: (finished: waiting for unit multi-user.target, in 19.47 seconds)880machine: must succeed: /nix/store/kabiwhgyb1j3wv90hzpkic8zvq2cw8s3-stderr-runner /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/bin/switch-to-configuration test881machine # Checking switch inhibitors... done882machine # [ 18.943139] nixos[835]: switching to system configuration /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test883machine # [ 18.946218] systemd[1]: Stopped target Local File Systems.884machine # activating the configuration...885machine # [ 18.949273] systemd[1]: Stopped target Remote File Systems.886machine # setting up /etc...887machine # [ 19.305940] systemd[1]: Reload requested from client PID 835 ('.switch-to-conf') (unit backdoor.service)...888machine # [ 19.308389] systemd[1]: Reloading...889machine # [ 19.669614] systemd[1]: Reloading finished in 359 ms.890machine # reloading user units for usertest...891machine # [ 19.725515] systemd[582]: Created slice User Core Session Slice.892machine # [ 19.729195] systemd[582]: Starting D-Bus User Message Bus...893machine # [ 19.762081] dbus-broker-launch[905]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/lwvd8zypc7d831h4k85sqslcsqwvyagn-system-path/share/dbus-1/services/org.freedesktop.import1.service'894machine # [ 19.765674] dbus-broker-launch[905]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/lwvd8zypc7d831h4k85sqslcsqwvyagn-system-path/share/dbus-1/services/org.freedesktop.machine1.service'895machine # [ 19.768953] dbus-broker-launch[905]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/lwvd8zypc7d831h4k85sqslcsqwvyagn-system-path/share/dbus-1/services/org.freedesktop.portable1.service'896machine # [ 19.772038] dbus-broker-launch[905]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/lwvd8zypc7d831h4k85sqslcsqwvyagn-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'897machine # [ 19.778270] dbus-broker-launch[905]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored898machine # [ 19.782486] dbus-broker-launch[905]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored899machine # [ 19.787109] systemd[582]: Started D-Bus User Message Bus.900machine # [ 19.789903] dbus-broker-launch[905]: Ready901machine # [ 19.832135] systemd[582]: Reexecution requested from client PID 904 ('switch-to-confi')...902machine # [ 19.833838] systemd[582]: Reexecuting.903machine # [ 19.897740] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy904machine # [ 20.022026] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy905machine # [ 20.030713] systemd[582]: Reload requested from client PID 904 ('switch-to-confi')...906machine # [ 20.033407] systemd[582]: Reloading...907machine # [ 20.157826] systemd[582]: Reloading finished in 123 ms.908machine # [ 20.172233] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy909machine # restarting the following user units: nixos-activation.service910machine # [ 20.186062] systemd[582]: Stopped Run user-specific NixOS activation.911machine # [ 20.189085] systemd[582]: Stopping Run user-specific NixOS activation...912machine # [ 20.191643] systemd[582]: Starting Run user-specific NixOS activation...913machine # [ 20.228861] systemd[582]: Finished Run user-specific NixOS activation.914machine # restarting sysinit-reactivation.target915machine # [ 20.242735] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....916machine # [ 20.340468] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..917machine # [ 20.345658] systemd[1]: Reached target Reactivate sysinit units.918machine # [ 20.350017] systemd[1]: Reached target Local File Systems.919machine # [ 20.354251] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore920machine # [ 20.358246] systemd[1]: Starting Create SUID/SGID Wrappers...921machine # [ 20.359255] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.922machine # [ 20.368858] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67923machine # [ 20.371285] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore924machine # [ 20.383498] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os925machine # [ 20.385172] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os926machine # [ 20.386490] systemd[1]: Reached target Remote File Systems.927machine # [ 20.387338] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os928machine # [ 20.389781] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki929machine # [ 20.579241] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.930machine # [ 20.583099] systemd[1]: Finished Create SUID/SGID Wrappers.931machine # [ 20.588200] systemd[1]: Starting linger-users.service...932machine # [ 20.635181] systemd[1]: linger-users.service: Deactivated successfully.933machine # [ 20.637691] systemd[1]: Finished linger-users.service.934machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service935machine # [ 20.900281] nixos[835]: finished switching to system configuration /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test936machine: (finished: must succeed: /nix/store/kabiwhgyb1j3wv90hzpkic8zvq2cw8s3-stderr-runner /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/bin/switch-to-configuration test, in 2.20 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.243443] nixos[982]: switching to system configuration /nix/store/vyc9823rbn3dgdj17a37652ajil02dnv-nixos-system-other-test943machine # [ 21.247304] systemd[1]: Stopped target Local File Systems.944machine # activating the configuration...945machine # [ 21.251270] systemd[1]: Stopped target Remote File Systems.946machine # removing user ‘usertest’947machine # setting up /etc...948machine # [ 21.602146] systemd[1]: Reload requested from client PID 982 ('.switch-to-conf') (unit backdoor.service)...949machine # [ 21.604612] systemd[1]: Reloading...950machine # [ 21.985813] systemd[1]: Reloading finished in 379 ms.951machine # reloading user units for usertest...952machine # [ 22.104711] systemd[582]: Reexecution requested from client PID 1054 ('switch-to-confi')...953machine # [ 22.109143] systemd[582]: Reexecuting.954machine # [ 22.163275] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy955machine # [ 22.287873] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy956machine # [ 22.295518] systemd[582]: Reload requested from client PID 1054 ('switch-to-confi')...957machine # [ 22.299961] systemd[582]: Reloading...958machine # [ 22.423213] systemd[582]: Reloading finished in 124 ms.959machine # [ 22.440352] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy960machine # restarting the following user units: nixos-activation.service961machine # [ 22.454575] systemd[582]: Stopped Run user-specific NixOS activation.962machine # [ 22.457435] systemd[582]: Stopping Run user-specific NixOS activation...963machine # [ 22.460176] systemd[582]: Starting Run user-specific NixOS activation...964machine # [ 22.497081] systemd[582]: Finished Run user-specific NixOS activation.965machine # restarting sysinit-reactivation.target966machine # [ 22.502919] systemd[1]: Stopped target Reactivate sysinit units.967machine # [ 22.505242] systemd[1]: Stopping Reactivate sysinit units...968machine # [ 22.506212] systemd[1]: Reached target Reactivate sysinit units.969machine # reloading the following units: dbus-broker.service970machine # [ 22.510293] systemd[1]: Reloading D-Bus System Message Bus...971machine # [ 22.511707] dbus-broker-launch[435]: Caught SIGHUP, trigger reload.972machine # [ 22.519266] dbus-broker-launch[435]: Looking up NSS user entry for 'systemd-timesync'...973machine # [ 22.522303] dbus-broker-launch[435]: NSS returned no entry for 'systemd-timesync'974machine # [ 22.523481] dbus-broker-launch[435]: Invalid user-name in /nix/store/dywaqxfqn4w86xba4frwwckp6r9njfan-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"975machine # [ 22.537585] systemd[1]: Reloaded D-Bus System Message Bus.976machine # restarting the following units: network-addresses-eth1.service977machine # [ 22.546636] systemd[1]: Stopping Address configuration of eth1...978machine # [ 22.584839] network-addresses-eth1-pre-stop[1066]: deleting address 192.168.1.1/24... done979machine # [ 22.593329] network-addresses-eth1-pre-stop[1066]: deleting address 2001:db8:1::1/64... done980machine # [ 22.602084] systemd[1]: network-addresses-eth1.service: Deactivated successfully.981machine # [ 22.604989] systemd[1]: Stopped Address configuration of eth1.982machine # [ 22.611159] systemd[1]: Starting Address configuration of eth1...983machine # [ 22.661456] network-addresses-eth1-start[1071]: adding address 192.168.1.2/24... done984machine # [ 22.669822] network-addresses-eth1-start[1071]: adding address 2001:db8:1::2/64... done985machine # [ 22.682191] systemd[1]: Finished Address configuration of eth1.986machine # [ 22.690823] systemd[1]: Reached target Remote File Systems.987machine # [ 22.691875] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os988machine # [ 22.701571] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki989machine # [ 22.703058] systemd[1]: Starting linger-users.service...990machine # [ 22.710936] systemd[1]: Reached target Local File Systems.991machine # [ 22.715057] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore992machine # [ 22.725376] systemd[1]: Starting Create SUID/SGID Wrappers...993machine # [ 22.726410] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.994machine # [ 22.738824] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67995machine # [ 22.745666] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore996machine # [ 22.750744] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os997machine # [ 22.754934] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os998machine # [ 22.781020] linger-users-start[1085]: id: ‘usertest’: no such user999machine # [ 22.785975] linger-users-start[1082]: Removing linger for missing user usertest1000machine # [ 22.794413] systemd[1]: linger-users.service: Deactivated successfully.1001machine # [ 22.798257] systemd[1]: Finished linger-users.service.1002machine # [ 23.006922] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1003machine # [ 23.009329] systemd[1]: Finished Create SUID/SGID Wrappers.1004machine # [ 23.267609] 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.34 seconds)1006machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/bin/switch-to-configuration test 2>&11007machine # [ 23.544509] nixos[1149]: switching to system configuration /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test1008machine # [ 23.547630] systemd[1]: Stopped target Local File Systems.1009machine # [ 23.550862] systemd[1]: Stopped target Remote File Systems.1010machine # [ 23.927336] systemd[1]: Reload requested from client PID 1149 ('.switch-to-conf') (unit backdoor.service)...1011machine # [ 23.929998] systemd[1]: Reloading...1012machine # [ 24.358082] systemd[1]: Reloading finished in 427 ms.1013machine # [ 24.507044] systemd[582]: Reexecution requested from client PID 1218 ('switch-to-confi')...1014machine # [ 24.510399] systemd[582]: Reexecuting.1015machine # [ 24.571753] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1016machine # [ 24.689608] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1017machine # [ 24.697491] systemd[582]: Reload requested from client PID 1218 ('switch-to-confi')...1018machine # [ 24.701001] systemd[582]: Reloading...1019machine # [ 24.815710] systemd[582]: Reloading finished in 113 ms.1020machine # [ 24.833735] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1021machine # [ 24.842778] systemd[582]: Stopped Run user-specific NixOS activation.1022machine # [ 24.845396] systemd[582]: Stopping Run user-specific NixOS activation...1023machine # [ 24.847785] systemd[582]: Starting Run user-specific NixOS activation...1024machine # [ 24.887108] systemd[582]: Finished Run user-specific NixOS activation.1025machine # [ 24.891541] systemd[1]: Stopped target Reactivate sysinit units.1026machine # [ 24.895780] systemd[1]: Stopping Reactivate sysinit units...1027machine # [ 24.897201] systemd[1]: Reached target Reactivate sysinit units.1028machine # [ 24.898141] systemd[1]: Reloading D-Bus System Message Bus...1029machine # [ 24.899330] dbus-broker-launch[435]: Caught SIGHUP, trigger reload.1030machine # [ 24.903948] dbus-broker-launch[435]: Looking up NSS user entry for 'systemd-timesync'...1031machine # [ 24.906559] dbus-broker-launch[435]: NSS returned no entry for 'systemd-timesync'1032machine # [ 24.907639] dbus-broker-launch[435]: Invalid user-name in /nix/store/lwvd8zypc7d831h4k85sqslcsqwvyagn-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1033machine # [ 24.928123] systemd[1]: Reloaded D-Bus System Message Bus.1034machine # [ 24.934799] systemd[1]: Stopping Address configuration of eth1...1035machine # [ 24.968983] network-addresses-eth1-pre-stop[1231]: deleting address 192.168.1.2/24... done1036machine # [ 24.976290] network-addresses-eth1-pre-stop[1231]: deleting address 2001:db8:1::2/64... done1037machine # [ 24.983677] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1038machine # [ 24.986421] systemd[1]: Stopped Address configuration of eth1.1039machine # [ 24.991592] systemd[1]: Starting Address configuration of eth1...1040machine # [ 25.039243] network-addresses-eth1-start[1236]: adding address 192.168.1.1/24... done1041machine # [ 25.049248] network-addresses-eth1-start[1236]: adding address 2001:db8:1::1/64... done1042machine # [ 25.059930] systemd[1]: Finished Address configuration of eth1.1043machine # [ 25.065122] systemd[1]: Reached target Remote File Systems.1044machine # [ 25.070292] systemd[1]: Starting linger-users.service...1045machine # [ 25.077166] systemd[1]: Reached target Local File Systems.1046machine # [ 25.078163] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1047machine # [ 25.082662] systemd[1]: Starting Create SUID/SGID Wrappers...1048machine # [ 25.083621] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1049machine # [ 25.100941] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671050machine # [ 25.103335] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1051machine # [ 25.112566] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1052machine # [ 25.114060] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1053machine # [ 25.115296] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1054machine # [ 25.119337] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1055machine # [ 25.167339] systemd[1]: linger-users.service: Deactivated successfully.1056machine # [ 25.171094] systemd[1]: Finished linger-users.service.1057machine # [ 25.359806] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1058machine # [ 25.363558] systemd[1]: Finished Create SUID/SGID Wrappers.1059machine # [ 25.620458] nixos[1149]: finished switching to system configuration /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test1060machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.35 seconds)1061subtest: socket-activated services1062machine: must fail: [ -S /run/test.sock ]1063machine: (finished: must fail: [ -S /run/test.sock ], in 0.02 seconds)1064machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&11065machine # [ 25.884453] nixos[1308]: switching to system configuration /nix/store/vhijavx2b2n5379flqv4n6aj5c3wd2fc-nixos-system-machine-test1066machine # [ 25.888858] systemd[1]: Stopped target Local File Systems.1067machine # [ 25.891781] systemd[1]: Stopped target Remote File Systems.1068machine # [ 26.221954] systemd[1]: Reload requested from client PID 1308 ('.switch-to-conf') (unit backdoor.service)...1069machine # [ 26.224666] systemd[1]: Reloading...1070machine # [ 26.616280] systemd[1]: Reloading finished in 390 ms.1071machine # [ 26.719107] systemd[582]: Reexecution requested from client PID 1377 ('switch-to-confi')...1072machine # [ 26.724350] systemd[582]: Reexecuting.1073machine # [ 26.784272] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1074machine # [ 26.906699] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1075machine # [ 26.915233] systemd[582]: Reload requested from client PID 1377 ('switch-to-confi')...1076machine # [ 26.919928] systemd[582]: Reloading...1077machine # [ 27.050804] systemd[582]: Reloading finished in 132 ms.1078machine # [ 27.064378] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1079machine # [ 27.076064] systemd[582]: Stopped Run user-specific NixOS activation.1080machine # [ 27.078802] systemd[582]: Stopping Run user-specific NixOS activation...1081machine # [ 27.081213] systemd[582]: Starting Run user-specific NixOS activation...1082machine # [ 27.113972] systemd[582]: Finished Run user-specific NixOS activation.1083machine # [ 27.118601] systemd[1]: Stopped target Reactivate sysinit units.1084machine # [ 27.122018] systemd[1]: Stopping Reactivate sysinit units...1085machine # [ 27.122936] systemd[1]: Reached target Reactivate sysinit units.1086machine # [ 27.126485] systemd[1]: Reached target Local File Systems.1087machine # [ 27.130181] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1088machine # [ 27.135923] systemd[1]: Starting Create SUID/SGID Wrappers...1089machine # [ 27.140480] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1090machine # [ 27.142069] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671091machine # [ 27.156765] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1092machine # [ 27.158592] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1093machine # [ 27.159935] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1094machine # [ 27.165526] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1095machine # [ 27.166981] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1096machine # [ 27.171047] systemd[1]: Reached target Remote File Systems.1097machine # [ 27.368432] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1098machine # [ 27.376358] systemd[1]: Finished Create SUID/SGID Wrappers.1099machine # [ 27.377423] systemd[1]: Listening on socket-activated.socket.1100machine # [ 27.380761] systemd[1]: Starting linger-users.service...1101machine # [ 27.441304] systemd[1]: linger-users.service: Deactivated successfully.1102machine # [ 27.445218] systemd[1]: Finished linger-users.service.1103machine # [ 27.701374] nixos[1308]: finished switching to system configuration /nix/store/vhijavx2b2n5379flqv4n6aj5c3wd2fc-nixos-system-machine-test1104machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&1, in 2.06 seconds)1105machine: must succeed: [ -S /run/test.sock ]1106machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1107machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/simple-socket-service-modified/bin/switch-to-configuration test 2>&11108machine # [ 28.012300] nixos[1452]: switching to system configuration /nix/store/nvb8mqsx964jwdjcjdvgna0jm5fd7y8m-nixos-system-machine-test1109machine # [ 28.015266] systemd[1]: Stopped target Remote File Systems.1110machine # [ 28.018266] systemd[1]: Stopped target Local File Systems.1111machine # [ 28.385431] systemd[1]: Reload requested from client PID 1452 ('.switch-to-conf') (unit backdoor.service)...1112machine # [ 28.387768] systemd[1]: Reloading...1113machine # [ 28.803537] systemd[1]: Reloading finished in 413 ms.1114machine # [ 28.897601] systemd[582]: Reexecution requested from client PID 1520 ('switch-to-confi')...1115machine # [ 28.901398] systemd[582]: Reexecuting.1116machine # [ 28.955939] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1117machine # [ 29.073397] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1118machine # [ 29.080742] systemd[582]: Reload requested from client PID 1520 ('switch-to-confi')...1119machine # [ 29.084721] systemd[582]: Reloading...1120machine # [ 29.198897] systemd[582]: Reloading finished in 115 ms.1121machine # [ 29.216260] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1122machine # [ 29.228085] systemd[582]: Stopped Run user-specific NixOS activation.1123machine # [ 29.229587] systemd[582]: Stopping Run user-specific NixOS activation...1124machine # [ 29.232970] systemd[582]: Starting Run user-specific NixOS activation...1125machine # [ 29.267814] systemd[582]: Finished Run user-specific NixOS activation.1126machine # [ 29.272805] systemd[1]: Stopped target Reactivate sysinit units.1127machine # [ 29.276874] systemd[1]: Stopping Reactivate sysinit units...1128machine # [ 29.277873] systemd[1]: Reached target Reactivate sysinit units.1129machine # [ 29.278746] systemd[1]: Reached target Remote File Systems.1130machine # [ 29.281978] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1131machine # [ 29.289357] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1132machine # [ 29.290940] systemd[1]: Reached target Local File Systems.1133machine # [ 29.291860] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1134machine # [ 29.308372] systemd[1]: Starting Create SUID/SGID Wrappers...1135machine # [ 29.309387] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1136machine # [ 29.310701] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671137machine # [ 29.315648] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1138machine # [ 29.319066] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1139machine # [ 29.322168] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1140machine # [ 29.531592] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1141machine # [ 29.537168] systemd[1]: Finished Create SUID/SGID Wrappers.1142machine # [ 29.541235] systemd[1]: Starting linger-users.service...1143machine # [ 29.590618] systemd[1]: linger-users.service: Deactivated successfully.1144machine # [ 29.593025] systemd[1]: Finished linger-users.service.1145machine # [ 29.850370] nixos[1452]: finished switching to system configuration /nix/store/nvb8mqsx964jwdjcjdvgna0jm5fd7y8m-nixos-system-machine-test1146machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/simple-socket-service-modified/bin/switch-to-configuration test 2>&1, in 2.14 seconds)1147machine: must succeed: [ -S /run/test.sock ]1148machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1149machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1150machine # [ 29.926040] systemd[1]: Started A socket-activated service.1151machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.69 seconds)1152machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&11153machine # [ 30.821580] nixos[1599]: switching to system configuration /nix/store/vhijavx2b2n5379flqv4n6aj5c3wd2fc-nixos-system-machine-test1154machine # [ 30.825304] systemd[1]: Stopped target Local File Systems.1155machine # [ 30.827530] systemd[1]: Stopped target Remote File Systems.1156machine # [ 31.226531] systemd[1]: Reload requested from client PID 1599 ('.switch-to-conf') (unit backdoor.service)...1157machine # [ 31.228956] systemd[1]: Reloading...1158machine # [ 31.617047] systemd[1]: Reloading finished in 386 ms.1159machine # [ 31.716547] systemd[582]: Reexecution requested from client PID 1670 ('switch-to-confi')...1160machine # [ 31.720979] systemd[582]: Reexecuting.1161machine # [ 31.780326] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1162machine # [ 31.899880] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1163machine # [ 31.907919] systemd[582]: Reload requested from client PID 1670 ('switch-to-confi')...1164machine # [ 31.911737] systemd[582]: Reloading...1165machine # [ 32.037486] systemd[582]: Reloading finished in 127 ms.1166machine # [ 32.054241] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1167machine # [ 32.065255] systemd[582]: Stopped Run user-specific NixOS activation.1168machine # [ 32.068272] systemd[582]: Stopping Run user-specific NixOS activation...1169machine # [ 32.071417] systemd[582]: Starting Run user-specific NixOS activation...1170machine # [ 32.111962] systemd[582]: Finished Run user-specific NixOS activation.1171machine # [ 32.118251] systemd[1]: Stopped target Reactivate sysinit units.1172machine # [ 32.126911] systemd[1]: Stopping Reactivate sysinit units...1173machine # [ 32.128515] systemd[1]: Reached target Reactivate sysinit units.1174machine # [ 32.131213] systemd[1]: Stopping A socket-activated service...1175machine # [ 32.133086] systemd[1]: socket-activated.service: Deactivated successfully.1176machine # [ 32.134334] systemd[1]: Stopped A socket-activated service.1177machine # [ 32.140601] systemd[1]: Started A socket-activated service.1178machine # [ 32.145950] systemd[1]: Reached target Local File Systems.1179machine # [ 32.160631] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1180machine # [ 32.167177] systemd[1]: Starting Create SUID/SGID Wrappers...1181machine # [ 32.174697] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1182machine # [ 32.187813] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671183machine # [ 32.199937] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1184machine # [ 32.203868] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1185machine # [ 32.209986] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1186machine # [ 32.214079] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1187machine # [ 32.220440] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1188machine # [ 32.222046] systemd[1]: Reached target Remote File Systems.1189machine # [ 32.514067] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1190machine # [ 32.520640] systemd[1]: Finished Create SUID/SGID Wrappers.1191machine # [ 32.523362] systemd[1]: Starting linger-users.service...1192machine # [ 32.576343] systemd[1]: linger-users.service: Deactivated successfully.1193machine # [ 32.578369] systemd[1]: Finished linger-users.service.1194machine # [ 32.836443] nixos[1599]: finished switching to system configuration /nix/store/vhijavx2b2n5379flqv4n6aj5c3wd2fc-nixos-system-machine-test1195machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&1, in 2.27 seconds)1196machine: must succeed: [ -S /run/test.sock ]1197machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1198machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1199machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.53 seconds)1200machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&11201machine # [ 33.677922] nixos[1749]: switching to system configuration /nix/store/ibndlp72dq3kw8wvcncwvavzgpa41v9k-nixos-system-machine-test1202machine # [ 33.683122] systemd[1]: Stopped target Local File Systems.1203machine # [ 33.689104] systemd[1]: Stopped target Remote File Systems.1204machine # [ 33.690263] systemd[1]: Stopping A socket-activated service...1205machine # [ 33.693360] systemd[1]: socket-activated.service: Deactivated successfully.1206machine # [ 33.698393] systemd[1]: Stopped A socket-activated service.1207machine # [ 33.699812] systemd[1]: socket-activated.socket: Deactivated successfully.1208machine # [ 33.702380] systemd[1]: Closed socket-activated.socket.1209machine # [ 34.050055] systemd[1]: Reload requested from client PID 1749 ('.switch-to-conf') (unit backdoor.service)...1210machine # [ 34.052347] systemd[1]: Reloading...1211machine # [ 34.446281] systemd[1]: Reloading finished in 392 ms.1212machine # [ 34.592971] systemd[582]: Reexecution requested from client PID 1818 ('switch-to-confi')...1213machine # [ 34.596173] systemd[582]: Reexecuting.1214machine # [ 34.639785] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1215machine # [ 34.757209] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1216machine # [ 34.765097] systemd[582]: Reload requested from client PID 1818 ('switch-to-confi')...1217machine # [ 34.766595] systemd[582]: Reloading...1218machine # [ 34.889247] systemd[582]: Reloading finished in 120 ms.1219machine # [ 34.904345] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1220machine # [ 34.916983] systemd[582]: Stopped Run user-specific NixOS activation.1221machine # [ 34.919588] systemd[582]: Stopping Run user-specific NixOS activation...1222machine # [ 34.922152] systemd[582]: Starting Run user-specific NixOS activation...1223machine # [ 34.957464] systemd[582]: Finished Run user-specific NixOS activation.1224machine # [ 34.963449] systemd[1]: Stopped target Reactivate sysinit units.1225machine # [ 34.966094] systemd[1]: Stopping Reactivate sysinit units...1226machine # [ 34.967074] systemd[1]: Reached target Reactivate sysinit units.1227machine # [ 34.970787] systemd[1]: Reached target Remote File Systems.1228machine # [ 34.975649] systemd[1]: Starting linger-users.service...1229machine # [ 34.986014] systemd[1]: Reached target Local File Systems.1230machine # [ 34.992284] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1231machine # [ 35.001435] systemd[1]: Starting Create SUID/SGID Wrappers...1232machine # [ 35.002572] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1233machine # [ 35.003943] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671234machine # [ 35.026691] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1235machine # [ 35.034027] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1236machine # [ 35.037609] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1237machine # [ 35.039809] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1238machine # [ 35.044844] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1239machine # [ 35.117676] systemd[1]: linger-users.service: Deactivated successfully.1240machine # [ 35.120757] systemd[1]: Finished linger-users.service.1241machine # [ 35.346969] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1242machine # [ 35.352511] systemd[1]: Finished Create SUID/SGID Wrappers.1243machine # [ 35.356270] systemd[1]: Listening on socket-activated.socket.1244machine # [ 35.610682] nixos[1749]: finished switching to system configuration /nix/store/ibndlp72dq3kw8wvcncwvavzgpa41v9k-nixos-system-machine-test1245machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&1, in 2.22 seconds)1246machine: must succeed: [ -S /run/test.sock ]1247machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1248machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1249machine # [ 35.681592] systemd[1]: Started A socket-activated service.1250machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.63 seconds)1251machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed-and-reloadtrigger/bin/switch-to-configuration test 2>&11252machine # [ 36.705265] nixos[1898]: switching to system configuration /nix/store/ird3sapi1818srv0ak9gl74d221zg6n7-nixos-system-machine-test1253machine # [ 36.709402] systemd[1]: Stopped target Local File Systems.1254machine # [ 36.711026] systemd[1]: Stopped target Remote File Systems.1255machine # [ 37.078361] systemd[1]: Reload requested from client PID 1898 ('.switch-to-conf') (unit backdoor.service)...1256machine # [ 37.080650] systemd[1]: Reloading...1257machine # [ 37.491063] systemd[1]: Reloading finished in 408 ms.1258machine # [ 37.625279] systemd[582]: Reexecution requested from client PID 1968 ('switch-to-confi')...1259machine # [ 37.626861] systemd[582]: Reexecuting.1260machine # [ 37.732412] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1261machine # [ 37.977909] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1262machine # [ 37.987304] systemd[582]: Reload requested from client PID 1968 ('switch-to-confi')...1263machine # [ 37.991889] systemd[582]: Reloading...1264machine # [ 38.111090] systemd[582]: Reloading finished in 120 ms.1265machine # [ 38.132602] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1266machine # [ 38.146198] systemd[582]: Stopped Run user-specific NixOS activation.1267machine # [ 38.149471] systemd[582]: Stopping Run user-specific NixOS activation...1268machine # [ 38.153720] systemd[582]: Starting Run user-specific NixOS activation...1269machine # [ 38.192731] systemd[582]: Finished Run user-specific NixOS activation.1270machine # [ 38.198199] systemd[1]: Stopped target Reactivate sysinit units.1271machine # [ 38.203879] systemd[1]: Stopping Reactivate sysinit units...1272machine # [ 38.208462] systemd[1]: Reached target Reactivate sysinit units.1273machine # [ 38.209548] systemd[1]: Reloading A socket-activated service...1274machine # [ 38.235950] systemd[1]: Reloaded A socket-activated service.1275machine # [ 38.239826] systemd[1]: Reached target Remote File Systems.1276machine # [ 38.243475] systemd[1]: Reached target Local File Systems.1277machine # [ 38.250361] systemd[1]: Starting linger-users.service...1278machine # [ 38.260124] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1279machine # [ 38.261680] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1280machine # [ 38.270099] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1281machine # [ 38.280660] systemd[1]: Starting Create SUID/SGID Wrappers...1282machine # [ 38.281943] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1283machine # [ 38.283330] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671284machine # [ 38.300168] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1285machine # [ 38.302182] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1286machine # [ 38.303681] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1287machine # [ 38.375603] systemd[1]: linger-users.service: Deactivated successfully.1288machine # [ 38.382126] systemd[1]: Finished linger-users.service.1289machine # [ 38.587076] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1290machine # [ 38.590914] systemd[1]: Finished Create SUID/SGID Wrappers.1291machine # [ 38.850910] nixos[1898]: finished switching to system configuration /nix/store/ird3sapi1818srv0ak9gl74d221zg6n7-nixos-system-machine-test1292machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed-and-reloadtrigger/bin/switch-to-configuration test 2>&1, in 2.59 seconds)1293machine: must succeed: [ -S /run/test.sock ]1294machine: (finished: must succeed: [ -S /run/test.sock ], in 0.03 seconds)1295machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1296machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.55 seconds)1297machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/socket-activated-without-socket/bin/switch-to-configuration test 2>&11298machine # [ 40.018024] nixos[2048]: switching to system configuration /nix/store/ffrhs02r2xwvyn0fav5rd798v50qj929-nixos-system-machine-test1299machine # [ 40.022679] systemd[1]: Stopped target Local File Systems.1300machine # [ 40.024955] systemd[1]: Stopped target Remote File Systems.1301machine # [ 40.025896] systemd[1]: socket-activated.socket: Deactivated successfully.1302machine # [ 40.026891] systemd[1]: Closed socket-activated.socket.1303machine # [ 40.418781] systemd[1]: Reload requested from client PID 2048 ('.switch-to-conf') (unit backdoor.service)...1304machine # [ 40.421239] systemd[1]: Reloading...1305machine # [ 40.905476] systemd[1]: Reloading finished in 482 ms.1306machine # [ 41.060223] systemd[582]: Reexecution requested from client PID 2118 ('switch-to-confi')...1307machine # [ 41.061717] systemd[582]: Reexecuting.1308machine # [ 41.120358] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1309machine # [ 41.243271] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1310machine # [ 41.250812] systemd[582]: Reload requested from client PID 2118 ('switch-to-confi')...1311machine # [ 41.254960] systemd[582]: Reloading...1312machine # [ 41.366549] systemd[582]: Reloading finished in 112 ms.1313machine # [ 41.384248] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1314machine # [ 41.395458] systemd[582]: Stopped Run user-specific NixOS activation.1315machine # [ 41.398099] systemd[582]: Stopping Run user-specific NixOS activation...1316machine # [ 41.400479] systemd[582]: Starting Run user-specific NixOS activation...1317machine # [ 41.437770] systemd[582]: Finished Run user-specific NixOS activation.1318machine # [ 41.445358] systemd[1]: Stopped target Reactivate sysinit units.1319machine # [ 41.450445] systemd[1]: Stopping Reactivate sysinit units...1320machine # [ 41.451393] systemd[1]: Reached target Reactivate sysinit units.1321machine # [ 41.457428] systemd[1]: Stopping A socket-activated service...1322machine # [ 41.463447] systemd[1]: socket-activated.service: Deactivated successfully.1323machine # [ 41.464918] systemd[1]: Stopped A socket-activated service.1324machine # [ 41.466857] systemd[1]: Started A socket-activated service.1325machine # [ 41.471735] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1326machine # [ 41.481952] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1327machine # [ 41.492256] systemd[1]: Reached target Local File Systems.1328machine # [ 41.493305] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1329machine # [ 41.494885] systemd[1]: Starting Create SUID/SGID Wrappers...1330machine # [ 41.495737] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1331machine # [ 41.515066] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671332machine # [ 41.521596] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1333machine # [ 41.528676] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1334machine # [ 41.534346] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1335machine # [ 41.535797] systemd[1]: Reached target Remote File Systems.1336machine # [ 41.760884] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1337machine # [ 41.764915] systemd[1]: Finished Create SUID/SGID Wrappers.1338machine # [ 41.769524] systemd[1]: Starting linger-users.service...1339machine # [ 41.813223] systemd[1]: linger-users.service: Deactivated successfully.1340machine # [ 41.816866] systemd[1]: Finished linger-users.service.1341machine # [ 42.071437] nixos[2048]: finished switching to system configuration /nix/store/ffrhs02r2xwvyn0fav5rd798v50qj929-nixos-system-machine-test1342machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/socket-activated-without-socket/bin/switch-to-configuration test 2>&1, in 2.63 seconds)1343machine: must succeed: systemctl is-active socket-activated.service1344machine: (finished: must succeed: systemctl is-active socket-activated.service, in 0.03 seconds)1345machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&11346machine # [ 42.308734] nixos[2195]: switching to system configuration /nix/store/ibndlp72dq3kw8wvcncwvavzgpa41v9k-nixos-system-machine-test1347machine # [ 42.315514] systemd[1]: Stopping A socket-activated service...1348machine # [ 42.319446] systemd[1]: Stopped target Local File Systems.1349machine # [ 42.322738] systemd[1]: Stopped target Remote File Systems.1350machine # [ 42.323671] systemd[1]: socket-activated.service: Deactivated successfully.1351machine # [ 42.327147] systemd[1]: Stopped A socket-activated service.1352machine # [ 42.617010] systemd[1]: Reload requested from client PID 2195 ('.switch-to-conf') (unit backdoor.service)...1353machine # [ 42.618679] systemd[1]: Reloading...1354machine # [ 42.954752] systemd[1]: Reloading finished in 334 ms.1355machine # [ 43.051637] systemd[582]: Reexecution requested from client PID 2265 ('switch-to-confi')...1356machine # [ 43.056310] systemd[582]: Reexecuting.1357machine # [ 43.123761] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1358machine # [ 43.268749] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1359machine # [ 43.276150] systemd[582]: Reload requested from client PID 2265 ('switch-to-confi')...1360machine # [ 43.277582] systemd[582]: Reloading...1361machine # [ 43.394927] systemd[582]: Reloading finished in 115 ms.1362machine # [ 43.412194] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1363machine # [ 43.426427] systemd[582]: Stopped Run user-specific NixOS activation.1364machine # [ 43.427579] systemd[582]: Stopping Run user-specific NixOS activation...1365machine # [ 43.432348] systemd[582]: Starting Run user-specific NixOS activation...1366machine # [ 43.463525] systemd[582]: Finished Run user-specific NixOS activation.1367machine # [ 43.468635] systemd[1]: Stopped target Reactivate sysinit units.1368machine # [ 43.469695] systemd[1]: Stopping Reactivate sysinit units...1369machine # [ 43.470532] systemd[1]: Reached target Reactivate sysinit units.1370machine # [ 43.474410] systemd[1]: Reached target Local File Systems.1371machine # [ 43.478225] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1372machine # [ 43.482597] systemd[1]: Starting Create SUID/SGID Wrappers...1373machine # [ 43.483610] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1374machine # [ 43.493834] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671375machine # [ 43.504967] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1376machine # [ 43.506672] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1377machine # [ 43.507968] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1378machine # [ 43.511263] systemd[1]: Reached target Remote File Systems.1379machine # [ 43.512282] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1380machine # [ 43.513604] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1381machine # [ 43.679027] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1382machine # [ 43.684893] systemd[1]: Finished Create SUID/SGID Wrappers.1383machine # [ 43.685831] systemd[1]: Listening on socket-activated.socket.1384machine # [ 43.690740] systemd[1]: Starting linger-users.service...1385machine # [ 43.741500] systemd[1]: linger-users.service: Deactivated successfully.1386machine # [ 43.744492] systemd[1]: Finished linger-users.service.1387machine # [ 44.001912] nixos[2195]: finished switching to system configuration /nix/store/ibndlp72dq3kw8wvcncwvavzgpa41v9k-nixos-system-machine-test1388machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&1, in 1.90 seconds)1389machine: must succeed: [ -S /run/test.sock ]1390machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1391machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1392machine # [ 44.058592] systemd[1]: Started A socket-activated service.1393machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.60 seconds)1394(finished: subtest: socket-activated services, in 19.00 seconds)1395subtest: socket-activated services with Accept=yes1396machine: must fail: [ -S /run/accept-test.sock ]1397machine: (finished: must fail: [ -S /run/accept-test.sock ], in 0.02 seconds)1398machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/accept-socket/bin/switch-to-configuration test 2>&11399machine # [ 44.869232] nixos[2347]: switching to system configuration /nix/store/33kdszx9y4hzqbl7vh8lxwp3xm4wakdx-nixos-system-machine-test1400machine # [ 44.875853] systemd[1]: Stopping A socket-activated service...1401machine # [ 44.880999] systemd[1]: Stopped target Remote File Systems.1402machine # [ 44.881927] systemd[1]: Stopped target Local File Systems.1403machine # [ 44.882850] systemd[1]: socket-activated.service: Deactivated successfully.1404machine # [ 44.885891] systemd[1]: Stopped A socket-activated service.1405machine # [ 44.887080] systemd[1]: socket-activated.socket: Deactivated successfully.1406machine # [ 44.889475] systemd[1]: Closed socket-activated.socket.1407machine # [ 45.193157] systemd[1]: Reload requested from client PID 2347 ('.switch-to-conf') (unit backdoor.service)...1408machine # [ 45.195206] systemd[1]: Reloading...1409machine # [ 45.530083] systemd[1]: Reloading finished in 333 ms.1410machine # [ 45.627604] systemd[582]: Reexecution requested from client PID 2417 ('switch-to-confi')...1411machine # [ 45.630052] systemd[582]: Reexecuting.1412machine # [ 45.679711] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1413machine # [ 45.794152] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1414machine # [ 45.801627] systemd[582]: Reload requested from client PID 2417 ('switch-to-confi')...1415machine # [ 45.804398] systemd[582]: Reloading...1416machine # [ 45.916722] systemd[582]: Reloading finished in 111 ms.1417machine # [ 45.934970] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1418machine # [ 45.946082] systemd[582]: Stopped Run user-specific NixOS activation.1419machine # [ 45.949045] systemd[582]: Stopping Run user-specific NixOS activation...1420machine # [ 45.951634] systemd[582]: Starting Run user-specific NixOS activation...1421machine # [ 45.999688] systemd[582]: Finished Run user-specific NixOS activation.1422machine # [ 46.006475] systemd[1]: Stopped target Reactivate sysinit units.1423machine # [ 46.010164] systemd[1]: Stopping Reactivate sysinit units...1424machine # [ 46.011166] systemd[1]: Reached target Reactivate sysinit units.1425machine # [ 46.015768] systemd[1]: Reached target Local File Systems.1426machine # [ 46.020595] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1427machine # [ 46.033447] systemd[1]: Starting Create SUID/SGID Wrappers...1428machine # [ 46.034559] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1429machine # [ 46.035873] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671430machine # [ 46.047461] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1431machine # [ 46.050923] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1432machine # [ 46.053171] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1433machine # [ 46.054490] systemd[1]: Reached target Remote File Systems.1434machine # [ 46.056687] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1435machine # [ 46.058869] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1436machine # [ 46.249087] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1437machine # [ 46.253467] systemd[1]: Finished Create SUID/SGID Wrappers.1438machine # [ 46.256609] systemd[1]: Listening on accept-socket.socket.1439machine # [ 46.262451] systemd[1]: Starting linger-users.service...1440machine # [ 46.311244] systemd[1]: linger-users.service: Deactivated successfully.1441machine # [ 46.313945] systemd[1]: Finished linger-users.service.1442machine # [ 46.572509] nixos[2347]: finished switching to system configuration /nix/store/33kdszx9y4hzqbl7vh8lxwp3xm4wakdx-nixos-system-machine-test1443machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/accept-socket/bin/switch-to-configuration test 2>&1, in 1.93 seconds)1444machine: must succeed: [ -S /run/accept-test.sock ]1445machine: (finished: must succeed: [ -S /run/accept-test.sock ], in 0.02 seconds)1446machine: must succeed: socat EXEC:'sleep infinity' UNIX-CONNECT:/run/accept-test.sock >&2 &1447machine: (finished: must succeed: socat EXEC:'sleep infinity' UNIX-CONNECT:/run/accept-test.sock >&2 &, in 0.02 seconds)1448machine: waiting for success: systemctl list-units --no-legend --state=running 'accept-socket@*.service' | grep -m1 -o 'accept-socket@[^ ]*\.service'1449machine # [ 46.654997] systemd[1]: Created slice Slice /system/accept-socket.1450machine # [ 46.661166] systemd[1]: Started A per-connection socket-activated service (PID 2491/UID 0).1451machine: (finished: waiting for success: systemctl list-units --no-legend --state=running 'accept-socket@*.service' | grep -m1 -o 'accept-socket@[^ ]*\.service', in 0.08 seconds)1452machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/accept-socket-service-modified/bin/switch-to-configuration test 2>&11453machine # [ 46.944221] nixos[2503]: switching to system configuration /nix/store/wp179a8kmxmis60gsqncp4wvh9hpcw6h-nixos-system-machine-test1454machine # [ 46.946963] systemd[1]: Stopped target Local File Systems.1455machine # [ 46.950252] systemd[1]: accept-socket.socket: Deactivated successfully.1456machine # [ 46.951345] systemd[1]: Closed accept-socket.socket.1457machine # [ 46.954954] systemd[1]: Stopped target Remote File Systems.1458machine # [ 46.957712] systemd[1]: Stopping A per-connection socket-activated service (PID 2491/UID 0)...1459machine # [ 46.959007] systemd[1]: accept-socket@0-1-2491_2492-0.service: Deactivated successfully.1460machine # [ 46.964341] systemd[1]: Stopped A per-connection socket-activated service (PID 2491/UID 0).1461machine # [ 47.278371] systemd[1]: Reload requested from client PID 2503 ('.switch-to-conf') (unit backdoor.service)...1462machine # [ 47.280710] systemd[1]: Reloading...1463machine # [ 47.627436] systemd[1]: Reloading finished in 345 ms.1464machine # [ 47.716966] systemd[582]: Reexecution requested from client PID 2574 ('switch-to-confi')...1465machine # [ 47.721671] systemd[582]: Reexecuting.1466machine # [ 47.784282] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1467machine # [ 47.897579] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1468machine # [ 47.904627] systemd[582]: Reload requested from client PID 2574 ('switch-to-confi')...1469machine # [ 47.907312] systemd[582]: Reloading...1470machine # [ 48.019131] systemd[582]: Reloading finished in 110 ms.1471machine # [ 48.036260] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1472machine # [ 48.047505] systemd[582]: Stopped Run user-specific NixOS activation.1473machine # [ 48.050443] systemd[582]: Stopping Run user-specific NixOS activation...1474machine # [ 48.052723] systemd[582]: Starting Run user-specific NixOS activation...1475machine # [ 48.085958] systemd[582]: Finished Run user-specific NixOS activation.1476machine # [ 48.090585] systemd[1]: Stopped target Reactivate sysinit units.1477machine # [ 48.093968] systemd[1]: Stopping Reactivate sysinit units...1478machine # [ 48.094943] systemd[1]: Reached target Reactivate sysinit units.1479machine # [ 48.098885] systemd[1]: Reached target Local File Systems.1480machine # [ 48.102340] systemd[1]: Reached target Remote File Systems.1481machine # [ 48.111376] systemd[1]: Starting linger-users.service...1482machine # [ 48.116279] systemd[1]: Listening on accept-socket.socket.1483machine # [ 48.126328] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1484machine # [ 48.127892] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1485machine # [ 48.131921] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1486machine # [ 48.141715] systemd[1]: Starting Create SUID/SGID Wrappers...1487machine # [ 48.142721] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1488machine # [ 48.148677] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671489machine # [ 48.151006] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1490machine # [ 48.161601] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1491machine # [ 48.163129] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1492machine # [ 48.210585] systemd[1]: linger-users.service: Deactivated successfully.1493machine # [ 48.215093] systemd[1]: Finished linger-users.service.1494machine # [ 48.356287] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1495machine # [ 48.359742] systemd[1]: Finished Create SUID/SGID Wrappers.1496machine # [ 48.613188] nixos[2503]: finished switching to system configuration /nix/store/wp179a8kmxmis60gsqncp4wvh9hpcw6h-nixos-system-machine-test1497machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/accept-socket-service-modified/bin/switch-to-configuration test 2>&1, in 1.92 seconds)1498machine: must succeed: socat - UNIX-CONNECT:/run/accept-test.sock </dev/null1499machine # [ 48.646081] systemd[1]: Started A per-connection socket-activated service (PID 2645/UID 0).1500machine # [ 48.682454] systemd[1]: accept-socket@1-2-2645_2646-0.service: Deactivated successfully.1501machine: (finished: must succeed: socat - UNIX-CONNECT:/run/accept-test.sock </dev/null, in 0.07 seconds)1502(finished: subtest: socket-activated services with Accept=yes, in 4.06 seconds)1503subtest: mounts1504machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&11505machine # [ 48.908225] nixos[2652]: switching to system configuration /nix/store/1s21y8jnmyxkgvgfdbkw8f8wbgcimnf6-nixos-system-machine-test1506machine # [ 48.911142] systemd[1]: accept-socket.socket: Deactivated successfully.1507machine # [ 48.914699] systemd[1]: Closed accept-socket.socket.1508machine # [ 48.915554] systemd[1]: Stopped target Remote File Systems.1509machine # [ 48.918099] systemd[1]: Stopped target Local File Systems.1510machine # [ 49.225209] systemd[1]: Reload requested from client PID 2652 ('.switch-to-conf') (unit backdoor.service)...1511machine # [ 49.227263] systemd[1]: Reloading...1512machine # [ 49.636309] systemd[1]: Reloading finished in 406 ms.1513machine # [ 49.726376] systemd[582]: Reexecution requested from client PID 2721 ('switch-to-confi')...1514machine # [ 49.730821] systemd[582]: Reexecuting.1515machine # [ 49.787407] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1516machine # [ 49.911189] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1517machine # [ 49.918793] systemd[582]: Reload requested from client PID 2721 ('switch-to-confi')...1518machine # [ 49.921898] systemd[582]: Reloading...1519machine # [ 50.035726] systemd[582]: Reloading finished in 112 ms.1520machine # [ 50.052465] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1521machine # [ 50.065921] systemd[582]: Stopped Run user-specific NixOS activation.1522machine # [ 50.067111] systemd[582]: Stopping Run user-specific NixOS activation...1523machine # [ 50.070087] systemd[582]: Starting Run user-specific NixOS activation...1524machine # [ 50.104513] systemd[582]: Finished Run user-specific NixOS activation.1525machine # [ 50.109652] systemd[1]: Stopped target Reactivate sysinit units.1526machine # [ 50.110760] systemd[1]: Stopping Reactivate sysinit units...1527machine # [ 50.111602] systemd[1]: Reached target Reactivate sysinit units.1528machine # [ 50.116525] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1529machine # [ 50.120562] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1530machine # [ 50.124464] systemd[1]: Reached target Remote File Systems.1531machine # [ 50.129199] systemd[1]: Starting linger-users.service...1532machine # [ 50.145888] systemd[1]: Mounting Testmount...1533machine # [ 50.146744] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1534machine # [ 50.163230] systemd[1]: Starting Create SUID/SGID Wrappers...1535machine # [ 50.169660] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671536machine # [ 50.180510] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1537machine # [ 50.182375] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1538machine # [ 50.183736] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1539machine # [ 50.234354] systemd[1]: Mounted Testmount.1540machine # [ 50.239193] systemd[1]: Reached target Local File Systems.1541machine # [ 50.242631] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1542machine # [ 50.268220] systemd[1]: linger-users.service: Deactivated successfully.1543machine # [ 50.272520] systemd[1]: Finished linger-users.service.1544machine # [ 50.435767] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1545machine # [ 50.438044] systemd[1]: Finished Create SUID/SGID Wrappers.1546machine # [ 50.696784] nixos[2652]: finished switching to system configuration /nix/store/1s21y8jnmyxkgvgfdbkw8f8wbgcimnf6-nixos-system-machine-test1547machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&1, in 2.01 seconds)1548machine: must succeed: mount | grep 'on /testmount'1549machine: (finished: must succeed: mount | grep 'on /testmount', in 0.03 seconds)1550machine: must succeed: systemctl stop testmount.mount1551machine # [ 50.771083] systemd[1]: Unmounting Testmount...1552machine # [ 50.811086] systemd[1]: testmount.mount: Deactivated successfully.1553machine # [ 50.813339] systemd[1]: Unmounted Testmount.1554machine: (finished: must succeed: systemctl stop testmount.mount, in 0.08 seconds)1555machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&11556machine # [ 51.009601] nixos[2805]: switching to system configuration /nix/store/1s21y8jnmyxkgvgfdbkw8f8wbgcimnf6-nixos-system-machine-test1557machine # [ 51.014352] systemd[1]: Stopped target Local File Systems.1558machine # [ 51.016699] systemd[1]: Stopped target Remote File Systems.1559machine # [ 51.322114] systemd[1]: Reload requested from client PID 2805 ('.switch-to-conf') (unit backdoor.service)...1560machine # [ 51.324443] systemd[1]: Reloading...1561machine # [ 51.721067] systemd[1]: Reloading finished in 395 ms.1562machine # [ 51.813545] systemd[582]: Reexecution requested from client PID 2873 ('switch-to-confi')...1563machine # [ 51.818504] systemd[582]: Reexecuting.1564machine # [ 51.875689] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1565machine # [ 51.991340] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1566machine # [ 51.999421] systemd[582]: Reload requested from client PID 2873 ('switch-to-confi')...1567machine # [ 52.003061] systemd[582]: Reloading...1568machine # [ 52.118739] systemd[582]: Reloading finished in 117 ms.1569machine # [ 52.132220] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1570machine # [ 52.143165] systemd[582]: Stopped Run user-specific NixOS activation.1571machine # [ 52.145877] systemd[582]: Stopping Run user-specific NixOS activation...1572machine # [ 52.148381] systemd[582]: Starting Run user-specific NixOS activation...1573machine # [ 52.183305] systemd[582]: Finished Run user-specific NixOS activation.1574machine # [ 52.188816] systemd[1]: Stopped target Reactivate sysinit units.1575machine # [ 52.189893] systemd[1]: Stopping Reactivate sysinit units...1576machine # [ 52.190774] systemd[1]: Reached target Reactivate sysinit units.1577machine # [ 52.199780] systemd[1]: Mounting Testmount...1578machine # [ 52.204256] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1579machine # [ 52.216708] systemd[1]: Starting Create SUID/SGID Wrappers...1580machine # [ 52.217797] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671581machine # [ 52.234447] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1582machine # [ 52.239552] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1583machine # [ 52.245619] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1584machine # [ 52.252221] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1585machine # [ 52.253734] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1586machine # [ 52.255040] systemd[1]: Reached target Remote File Systems.1587machine # [ 52.275682] systemd[1]: Mounted Testmount.1588machine # [ 52.279216] systemd[1]: Reached target Local File Systems.1589machine # [ 52.281567] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1590machine # [ 52.435319] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1591machine # [ 52.438526] systemd[1]: Finished Create SUID/SGID Wrappers.1592machine # [ 52.443618] systemd[1]: Starting linger-users.service...1593machine # [ 52.492927] systemd[1]: linger-users.service: Deactivated successfully.1594machine # [ 52.495694] systemd[1]: Finished linger-users.service.1595machine # [ 52.752727] nixos[2805]: finished switching to system configuration /nix/store/1s21y8jnmyxkgvgfdbkw8f8wbgcimnf6-nixos-system-machine-test1596machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&1, in 1.94 seconds)1597machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/mountOptionsModified/bin/switch-to-configuration test 2>&11598machine # [ 52.986924] nixos[2947]: switching to system configuration /nix/store/qzhax0jgfj3843y52rp41l12xg0nhhld-nixos-system-machine-test1599machine # [ 52.989480] systemd[1]: Stopped target Local File Systems.1600machine # [ 52.992233] systemd[1]: Stopped target Remote File Systems.1601machine # [ 53.296715] systemd[1]: Reload requested from client PID 2947 ('.switch-to-conf') (unit backdoor.service)...1602machine # [ 53.298342] systemd[1]: Reloading...1603machine # [ 53.627795] systemd[1]: Reloading finished in 328 ms.1604machine # [ 53.718654] systemd[582]: Reexecution requested from client PID 3015 ('switch-to-confi')...1605machine # [ 53.723069] systemd[582]: Reexecuting.1606machine # [ 53.787754] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1607machine # [ 53.908813] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1608machine # [ 53.916140] systemd[582]: Reload requested from client PID 3015 ('switch-to-confi')...1609machine # [ 53.917566] systemd[582]: Reloading...1610machine # [ 54.029279] systemd[582]: Reloading finished in 109 ms.1611machine # [ 54.044206] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1612machine # [ 54.055067] systemd[582]: Stopped Run user-specific NixOS activation.1613machine # [ 54.057825] systemd[582]: Stopping Run user-specific NixOS activation...1614machine # [ 54.060388] systemd[582]: Starting Run user-specific NixOS activation...1615machine # [ 54.105353] systemd[582]: Finished Run user-specific NixOS activation.1616machine # [ 54.109845] systemd[1]: Stopped target Reactivate sysinit units.1617machine # [ 54.110954] systemd[1]: Stopping Reactivate sysinit units...1618machine # [ 54.113004] systemd[1]: Reached target Reactivate sysinit units.1619machine # [ 54.117730] systemd[1]: Reloading Testmount...1620machine # [ 54.145821] systemd[1]: Reloaded Testmount.1621machine # [ 54.152924] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1622machine # [ 54.158273] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1623machine # [ 54.159775] systemd[1]: Reached target Local File Systems.1624machine # [ 54.163427] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1625machine # [ 54.172537] systemd[1]: Starting Create SUID/SGID Wrappers...1626machine # [ 54.173496] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1627machine # [ 54.174795] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671628machine # [ 54.188507] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1629machine # [ 54.190270] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1630machine # [ 54.191600] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1631machine # [ 54.193250] systemd[1]: Reached target Remote File Systems.1632machine # [ 54.374712] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1633machine # [ 54.380645] systemd[1]: Finished Create SUID/SGID Wrappers.1634machine # [ 54.382360] systemd[1]: Starting linger-users.service...1635machine # [ 54.431051] systemd[1]: linger-users.service: Deactivated successfully.1636machine # [ 54.433393] systemd[1]: Finished linger-users.service.1637machine # [ 54.690436] nixos[2947]: finished switching to system configuration /nix/store/qzhax0jgfj3843y52rp41l12xg0nhhld-nixos-system-machine-test1638machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/mountOptionsModified/bin/switch-to-configuration test 2>&1, in 1.94 seconds)1639machine: must succeed: mount | grep 'on /testmount'1640machine: (finished: must succeed: mount | grep 'on /testmount', in 0.03 seconds)1641machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/mountModified/bin/switch-to-configuration test 2>&11642machine # [ 54.953837] nixos[3094]: switching to system configuration /nix/store/gfa3d498nmy489v46nrdz75rfhdn7bf7-nixos-system-machine-test1643machine # [ 54.956732] systemd[1]: Stopped target Remote File Systems.1644machine # [ 54.959405] systemd[1]: Stopped target Local File Systems.1645machine # [ 55.298942] systemd[1]: Reload requested from client PID 3094 ('.switch-to-conf') (unit backdoor.service)...1646machine # [ 55.301320] systemd[1]: Reloading...1647machine # [ 55.864329] systemd[1]: Reloading finished in 560 ms.1648machine # [ 55.962623] systemd[582]: Reexecution requested from client PID 3162 ('switch-to-confi')...1649machine # [ 55.967215] systemd[582]: Reexecuting.1650machine # [ 56.015553] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1651machine # [ 56.132007] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1652machine # [ 56.139617] systemd[582]: Reload requested from client PID 3162 ('switch-to-confi')...1653machine # [ 56.144484] systemd[582]: Reloading...1654machine # [ 56.255047] systemd[582]: Reloading finished in 112 ms.1655machine # [ 56.272277] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1656machine # [ 56.283716] systemd[582]: Stopped Run user-specific NixOS activation.1657machine # [ 56.286606] systemd[582]: Stopping Run user-specific NixOS activation...1658machine # [ 56.289358] systemd[582]: Starting Run user-specific NixOS activation...1659machine # [ 56.325867] systemd[582]: Finished Run user-specific NixOS activation.1660machine # [ 56.331635] systemd[1]: Stopped target Reactivate sysinit units.1661machine # [ 56.334083] systemd[1]: Stopping Reactivate sysinit units...1662machine # [ 56.335172] systemd[1]: Reached target Reactivate sysinit units.1663machine # [ 56.340317] systemd[1]: Unmounting Testmount...1664machine # [ 56.372855] systemd[1]: testmount.mount: Deactivated successfully.1665machine # [ 56.375003] systemd[1]: Unmounted Testmount.1666machine # [ 56.383404] systemd[1]: Mounting Testmount...1667machine # [ 56.415221] systemd[1]: Mounted Testmount.1668machine # [ 56.425245] systemd[1]: Reached target Local File Systems.1669machine # [ 56.428684] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1670machine # [ 56.434603] systemd[1]: Starting Create SUID/SGID Wrappers...1671machine # [ 56.435596] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1672machine # [ 56.444446] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671673machine # [ 56.446827] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1674machine # [ 56.453526] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1675machine # [ 56.454880] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1676machine # [ 56.456872] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1677machine # [ 56.466874] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1678machine # [ 56.469374] systemd[1]: Reached target Remote File Systems.1679machine # [ 56.651511] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1680machine # [ 56.653676] systemd[1]: Finished Create SUID/SGID Wrappers.1681machine # [ 56.659438] systemd[1]: Starting linger-users.service...1682machine # [ 56.705928] systemd[1]: linger-users.service: Deactivated successfully.1683machine # [ 56.708248] systemd[1]: Finished linger-users.service.1684machine # [ 56.965753] nixos[3094]: finished switching to system configuration /nix/store/gfa3d498nmy489v46nrdz75rfhdn7bf7-nixos-system-machine-test1685machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/mountModified/bin/switch-to-configuration test 2>&1, in 2.24 seconds)1686machine: must succeed: mount | grep 'on /testmount'1687machine: (finished: must succeed: mount | grep 'on /testmount', in 0.03 seconds)1688(finished: subtest: mounts, in 8.31 seconds)1689subtest: timers1690machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/timer/bin/switch-to-configuration test 2>&11691machine # [ 57.516376] nixos[3243]: switching to system configuration /nix/store/1n4sgpg71z48wlsgr2sfv7nla9bb0cd0-nixos-system-machine-test1692machine # [ 57.519321] systemd[1]: Stopped target Local File Systems.1693machine # [ 57.523513] systemd[1]: Stopped target Remote File Systems.1694machine # [ 57.524942] systemd[1]: Unmounting Testmount...1695machine # [ 57.554832] systemd[1]: testmount.mount: Deactivated successfully.1696machine # [ 57.558181] systemd[1]: Unmounted Testmount.1697machine # [ 57.862816] systemd[1]: Reload requested from client PID 3243 ('.switch-to-conf') (unit backdoor.service)...1698machine # [ 57.865451] systemd[1]: Reloading...1699machine # [ 58.226884] systemd[1]: Reloading finished in 360 ms.1700machine # [ 58.322638] systemd[582]: Reexecution requested from client PID 3314 ('switch-to-confi')...1701machine # [ 58.327218] systemd[582]: Reexecuting.1702machine # [ 58.363207] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1703machine # [ 58.478216] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1704machine # [ 58.485444] systemd[582]: Reload requested from client PID 3314 ('switch-to-confi')...1705machine # [ 58.489903] systemd[582]: Reloading...1706machine # [ 58.604927] systemd[582]: Reloading finished in 116 ms.1707machine # [ 58.616223] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1708machine # [ 58.627229] systemd[582]: Stopped Run user-specific NixOS activation.1709machine # [ 58.629873] systemd[582]: Stopping Run user-specific NixOS activation...1710machine # [ 58.632704] systemd[582]: Starting Run user-specific NixOS activation...1711machine # [ 58.665943] systemd[582]: Finished Run user-specific NixOS activation.1712machine # [ 58.670707] systemd[1]: Stopped target Reactivate sysinit units.1713machine # [ 58.673471] systemd[1]: Stopping Reactivate sysinit units...1714machine # [ 58.674398] systemd[1]: Reached target Reactivate sysinit units.1715machine # [ 58.677089] systemd[1]: Reached target Remote File Systems.1716machine # [ 58.681800] systemd[1]: Starting linger-users.service...1717machine # [ 58.692564] systemd[1]: Reached target Local File Systems.1718machine # [ 58.693589] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1719machine # [ 58.694905] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1720machine # [ 58.705144] systemd[1]: Started test-timer.timer.1721machine # [ 58.705872] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1722machine # [ 58.717110] systemd[1]: Starting Create SUID/SGID Wrappers...1723machine # [ 58.718106] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1724machine # [ 58.719393] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671725machine # [ 58.730351] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1726machine # [ 58.735125] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1727machine # [ 58.739952] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1728machine # [ 58.780950] systemd[1]: linger-users.service: Deactivated successfully.1729machine # [ 58.784717] systemd[1]: Finished linger-users.service.1730machine # [ 58.927793] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1731machine # [ 58.930170] systemd[1]: Finished Create SUID/SGID Wrappers.1732machine # [ 59.188297] nixos[3243]: finished switching to system configuration /nix/store/1n4sgpg71z48wlsgr2sfv7nla9bb0cd0-nixos-system-machine-test1733machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/timer/bin/switch-to-configuration test 2>&1, in 2.19 seconds)1734machine: must succeed: systemctl show test-timer.timer1735machine: (finished: must succeed: systemctl show test-timer.timer, in 0.04 seconds)1736machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&11737machine # [ 59.447495] nixos[3388]: switching to system configuration /nix/store/ijkk1ckfmjl70dxcsil07560acghwcb6-nixos-system-machine-test1738machine # [ 59.453019] systemd[1]: Stopped target Remote File Systems.1739machine # [ 59.453943] systemd[1]: Stopped target Local File Systems.1740machine # [ 59.753804] systemd[1]: Reload requested from client PID 3388 ('.switch-to-conf') (unit backdoor.service)...1741machine # [ 59.755845] systemd[1]: Reloading...1742machine # [ 60.105096] systemd[1]: Reloading finished in 347 ms.1743machine # [ 60.192772] systemd[582]: Reexecution requested from client PID 3457 ('switch-to-confi')...1744machine # [ 60.197024] systemd[582]: Reexecuting.1745machine # [ 60.247024] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1746machine # [ 60.357097] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1747machine # [ 60.364386] systemd[582]: Reload requested from client PID 3457 ('switch-to-confi')...1748machine # [ 60.368146] systemd[582]: Reloading...1749machine # [ 60.476897] systemd[582]: Reloading finished in 109 ms.1750machine # [ 60.492221] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1751machine # [ 60.503418] systemd[582]: Stopped Run user-specific NixOS activation.1752machine # [ 60.506118] systemd[582]: Stopping Run user-specific NixOS activation...1753machine # [ 60.508586] systemd[582]: Starting Run user-specific NixOS activation...1754machine # [ 60.540998] systemd[582]: Finished Run user-specific NixOS activation.1755machine # [ 60.545492] systemd[1]: Stopped target Reactivate sysinit units.1756machine # [ 60.548169] systemd[1]: Stopping Reactivate sysinit units...1757machine # [ 60.549096] systemd[1]: Reached target Reactivate sysinit units.1758machine # [ 60.550772] systemd[1]: test-timer.timer: Deactivated successfully.1759machine # [ 60.551753] systemd[1]: Stopped test-timer.timer.1760machine # [ 60.554871] systemd[1]: Stopping test-timer.timer...1761machine # [ 60.555658] systemd[1]: Started test-timer.timer.1762machine # [ 60.558440] systemd[1]: Reached target Remote File Systems.1763machine # [ 60.561862] systemd[1]: Reached target Local File Systems.1764machine # [ 60.569398] systemd[1]: Starting linger-users.service...1765machine # [ 60.577357] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1766machine # [ 60.583508] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1767machine # [ 60.585272] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1768machine # [ 60.592649] systemd[1]: Starting Create SUID/SGID Wrappers...1769machine # [ 60.598092] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1770machine # [ 60.599607] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671771machine # [ 60.609717] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1772machine # [ 60.617608] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1773machine # [ 60.619000] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1774machine # [ 60.660183] systemd[1]: linger-users.service: Deactivated successfully.1775machine # [ 60.663242] systemd[1]: Finished linger-users.service.1776machine # [ 60.811087] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1777machine # [ 60.814215] systemd[1]: Finished Create SUID/SGID Wrappers.1778machine # [ 61.071024] nixos[3388]: finished switching to system configuration /nix/store/ijkk1ckfmjl70dxcsil07560acghwcb6-nixos-system-machine-test1779machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&1, in 1.85 seconds)1780machine: must succeed: systemctl show test-timer.timer1781machine: (finished: must succeed: systemctl show test-timer.timer, in 0.04 seconds)1782(finished: subtest: timers, in 4.11 seconds)1783subtest: targets1784machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/hybridSleepModified/bin/switch-to-configuration test 2>&11785machine # [ 61.333351] nixos[3531]: switching to system configuration /nix/store/xb0fjq4hb8z3zg7mak7iw6mcnil8h1xa-nixos-system-machine-test1786machine # [ 61.336363] systemd[1]: Stopped target Remote File Systems.1787machine # [ 61.339655] systemd[1]: Stopped target Local File Systems.1788machine # [ 61.341073] systemd[1]: test-timer.timer: Deactivated successfully.1789machine # [ 61.342035] systemd[1]: Stopped test-timer.timer.1790machine # [ 61.644407] systemd[1]: Reload requested from client PID 3531 ('.switch-to-conf') (unit backdoor.service)...1791machine # [ 61.646470] systemd[1]: Reloading...1792machine # [ 61.983587] systemd[1]: Reloading finished in 335 ms.1793machine # [ 62.074165] systemd[582]: Reexecution requested from client PID 3600 ('switch-to-confi')...1794machine # [ 62.075586] systemd[582]: Reexecuting.1795machine # [ 62.143585] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1796machine # [ 62.262801] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1797machine # [ 62.269633] systemd[582]: Reload requested from client PID 3600 ('switch-to-confi')...1798machine # [ 62.273817] systemd[582]: Reloading...1799machine # [ 62.379110] systemd[582]: Reloading finished in 106 ms.1800machine # [ 62.396329] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1801machine # [ 62.409772] systemd[582]: Stopped Run user-specific NixOS activation.1802machine # [ 62.410957] systemd[582]: Stopping Run user-specific NixOS activation...1803machine # [ 62.413213] systemd[582]: Starting Run user-specific NixOS activation...1804machine # [ 62.446920] systemd[582]: Finished Run user-specific NixOS activation.1805machine # [ 62.452565] systemd[1]: Stopped target Reactivate sysinit units.1806machine # [ 62.456697] systemd[1]: Stopping Reactivate sysinit units...1807machine # [ 62.461462] systemd[1]: Reached target Reactivate sysinit units.1808machine # [ 62.465168] systemd[1]: Reached target Local File Systems.1809machine # [ 62.468677] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1810machine # [ 62.473824] systemd[1]: Starting Create SUID/SGID Wrappers...1811machine # [ 62.474901] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1812machine # [ 62.485934] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671813machine # [ 62.496961] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1814machine # [ 62.498768] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1815machine # [ 62.500422] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1816machine # [ 62.501748] systemd[1]: Reached target Remote File Systems.1817machine # [ 62.502591] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1818machine # [ 62.505914] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1819machine # [ 62.673966] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1820machine # [ 62.677340] systemd[1]: Finished Create SUID/SGID Wrappers.1821machine # [ 62.682257] systemd[1]: Starting linger-users.service...1822machine # [ 62.728596] systemd[1]: linger-users.service: Deactivated successfully.1823machine # [ 62.730452] systemd[1]: Finished linger-users.service.1824machine # [ 62.987298] nixos[3531]: finished switching to system configuration /nix/store/xb0fjq4hb8z3zg7mak7iw6mcnil8h1xa-nixos-system-machine-test1825machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/hybridSleepModified/bin/switch-to-configuration test 2>&1, in 1.88 seconds)1826machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/target/bin/switch-to-configuration test 2>&11827machine # [ 63.219613] nixos[3671]: switching to system configuration /nix/store/d0m5x7hmnpf1820ajvv4c9dglv78wi45-nixos-system-machine-test1828machine # [ 63.225642] systemd[1]: Stopped target Remote File Systems.1829machine # [ 63.226619] systemd[1]: Stopped target Local File Systems.1830machine # [ 63.526029] systemd[1]: Reload requested from client PID 3671 ('.switch-to-conf') (unit backdoor.service)...1831machine # [ 63.528388] systemd[1]: Reloading...1832machine # [ 63.861350] systemd[1]: Reloading finished in 330 ms.1833machine # [ 63.947912] systemd[582]: Reexecution requested from client PID 3740 ('switch-to-confi')...1834machine # [ 63.952194] systemd[582]: Reexecuting.1835machine # [ 63.991274] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1836machine # [ 64.100116] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1837machine # [ 64.106980] systemd[582]: Reload requested from client PID 3740 ('switch-to-confi')...1838machine # [ 64.110909] systemd[582]: Reloading...1839machine # [ 64.215724] systemd[582]: Reloading finished in 105 ms.1840machine # [ 64.228219] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1841machine # [ 64.238722] systemd[582]: Stopped Run user-specific NixOS activation.1842machine # [ 64.241236] systemd[582]: Stopping Run user-specific NixOS activation...1843machine # [ 64.243414] systemd[582]: Starting Run user-specific NixOS activation...1844machine # [ 64.276713] systemd[582]: Finished Run user-specific NixOS activation.1845machine # [ 64.281298] systemd[1]: Stopped target Reactivate sysinit units.1846machine # [ 64.285125] systemd[1]: Stopping Reactivate sysinit units...1847machine # [ 64.286094] systemd[1]: Reached target Reactivate sysinit units.1848machine # [ 64.289198] systemd[1]: Reached target Remote File Systems.1849machine # [ 64.292989] systemd[1]: Reached target Local File Systems.1850machine # [ 64.297064] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1851machine # [ 64.298421] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1852machine # [ 64.302655] systemd[1]: Reached target test-target.target.1853machine # [ 64.306907] systemd[1]: Starting linger-users.service...1854machine # [ 64.316789] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1855machine # [ 64.322405] systemd[1]: Starting Create SUID/SGID Wrappers...1856machine # [ 64.323366] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1857machine # [ 64.328198] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671858machine # [ 64.330488] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1859machine # [ 64.337450] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1860machine # [ 64.338870] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1861machine # [ 64.396255] systemd[1]: linger-users.service: Deactivated successfully.1862machine # [ 64.401387] systemd[1]: Finished linger-users.service.1863machine # [ 64.543230] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1864machine # [ 64.547045] systemd[1]: Finished Create SUID/SGID Wrappers.1865machine # [ 64.802716] nixos[3671]: finished switching to system configuration /nix/store/d0m5x7hmnpf1820ajvv4c9dglv78wi45-nixos-system-machine-test1866machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/target/bin/switch-to-configuration test 2>&1, in 1.81 seconds)1867machine # [ 64.859401] systemd[1]: Started test-service.service.1868machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/targetModified/bin/switch-to-configuration test 2>&11869machine # [ 65.093448] nixos[3817]: switching to system configuration /nix/store/q6pr7p2dl23p1nkzfw24389iqx57vgid-nixos-system-machine-test1870machine # [ 65.097549] systemd[1]: Stopped target Local File Systems.1871machine # [ 65.099789] systemd[1]: Stopped target Remote File Systems.1872machine # [ 65.395857] systemd[1]: Reload requested from client PID 3817 ('.switch-to-conf') (unit backdoor.service)...1873machine # [ 65.398085] systemd[1]: Reloading...1874machine # [ 65.737857] systemd[1]: Reloading finished in 338 ms.1875machine # [ 65.831262] systemd[582]: Reexecution requested from client PID 3887 ('switch-to-confi')...1876machine # [ 65.834034] systemd[582]: Reexecuting.1877machine # [ 65.900578] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1878machine # [ 66.020324] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1879machine # [ 66.027258] systemd[582]: Reload requested from client PID 3887 ('switch-to-confi')...1880machine # [ 66.031405] systemd[582]: Reloading...1881machine # [ 66.142447] systemd[582]: Reloading finished in 112 ms.1882machine # [ 66.160205] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1883machine # [ 66.170392] systemd[582]: Stopped Run user-specific NixOS activation.1884machine # [ 66.172927] systemd[582]: Stopping Run user-specific NixOS activation...1885machine # [ 66.175122] systemd[582]: Starting Run user-specific NixOS activation...1886machine # [ 66.209954] systemd[582]: Finished Run user-specific NixOS activation.1887machine # [ 66.214209] systemd[1]: Stopped target Reactivate sysinit units.1888machine # [ 66.217307] systemd[1]: Stopping Reactivate sysinit units...1889machine # [ 66.218249] systemd[1]: Reached target Reactivate sysinit units.1890machine # [ 66.220790] systemd[1]: Reached target Remote File Systems.1891machine # [ 66.225199] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1892machine # [ 66.231334] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1893machine # [ 66.233098] systemd[1]: Reached target Local File Systems.1894machine # [ 66.237658] systemd[1]: Starting linger-users.service...1895machine # [ 66.247388] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1896machine # [ 66.256399] systemd[1]: Starting Create SUID/SGID Wrappers...1897machine # [ 66.257334] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1898machine # [ 66.258664] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671899machine # [ 66.264931] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1900machine # [ 66.266658] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1901machine # [ 66.268092] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1902machine # [ 66.333172] systemd[1]: linger-users.service: Deactivated successfully.1903machine # [ 66.335176] systemd[1]: Finished linger-users.service.1904machine # [ 66.470210] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1905machine # [ 66.473765] systemd[1]: Finished Create SUID/SGID Wrappers.1906machine # [ 66.729574] nixos[3817]: finished switching to system configuration /nix/store/q6pr7p2dl23p1nkzfw24389iqx57vgid-nixos-system-machine-test1907machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/targetModified/bin/switch-to-configuration test 2>&1, in 1.86 seconds)1908machine: must succeed: systemctl is-active test-service.service1909machine: (finished: must succeed: systemctl is-active test-service.service, in 0.03 seconds)1910machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/targetModifiedStopOnReconfig/bin/switch-to-configuration test 2>&11911machine # [ 66.996097] nixos[3961]: switching to system configuration /nix/store/h0q0dahagaadzg5030yh96iwd5663a5z-nixos-system-machine-test1912machine # [ 66.998711] systemd[1]: Stopped target Remote File Systems.1913machine # [ 67.003660] systemd[1]: Stopped target Local File Systems.1914machine # [ 67.008361] systemd[1]: Stopped target test-target.target.1915machine # [ 67.009271] systemd[1]: Stopping test-service.service...1916machine # [ 67.010073] systemd[1]: test-service.service: Deactivated successfully.1917machine # [ 67.011031] systemd[1]: Stopped test-service.service.1918machine # [ 67.313173] systemd[1]: Reload requested from client PID 3961 ('.switch-to-conf') (unit backdoor.service)...1919machine # [ 67.315231] systemd[1]: Reloading...1920machine # [ 67.653815] systemd[1]: Reloading finished in 336 ms.1921machine # [ 67.746710] systemd[582]: Reexecution requested from client PID 4031 ('switch-to-confi')...1922machine # [ 67.749433] systemd[582]: Reexecuting.1923machine # [ 67.808710] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1924machine # [ 67.923388] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1925machine # [ 67.931119] systemd[582]: Reload requested from client PID 4031 ('switch-to-confi')...1926machine # [ 67.934353] systemd[582]: Reloading...1927machine # [ 68.039650] systemd[582]: Reloading finished in 106 ms.1928machine # [ 68.053616] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1929machine # [ 68.061840] systemd[582]: Stopped Run user-specific NixOS activation.1930machine # [ 68.064088] systemd[582]: Stopping Run user-specific NixOS activation...1931machine # [ 68.066564] systemd[582]: Starting Run user-specific NixOS activation...1932machine # [ 68.100935] systemd[582]: Finished Run user-specific NixOS activation.1933machine # [ 68.104808] systemd[1]: Stopped target Reactivate sysinit units.1934machine # [ 68.106854] systemd[1]: Stopping Reactivate sysinit units...1935machine # [ 68.107751] systemd[1]: Reached target Reactivate sysinit units.1936machine # [ 68.110985] systemd[1]: Reached target test-target.target.1937machine # [ 68.113472] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1938machine # [ 68.114846] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1939machine # [ 68.120447] systemd[1]: Reached target Local File Systems.1940machine # [ 68.126266] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1941machine # [ 68.127905] systemd[1]: Starting Create SUID/SGID Wrappers...1942machine # [ 68.135908] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1943machine # [ 68.141519] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671944machine # [ 68.146376] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1945machine # [ 68.148182] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1946machine # [ 68.149491] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1947machine # [ 68.150708] systemd[1]: Reached target Remote File Systems.1948machine # [ 68.307092] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1949machine # [ 68.310246] systemd[1]: Finished Create SUID/SGID Wrappers.1950machine # [ 68.314907] systemd[1]: Starting linger-users.service...1951machine # [ 68.360559] systemd[1]: linger-users.service: Deactivated successfully.1952machine # [ 68.362450] systemd[1]: Finished linger-users.service.1953machine # [ 68.619309] nixos[3961]: finished switching to system configuration /nix/store/h0q0dahagaadzg5030yh96iwd5663a5z-nixos-system-machine-test1954machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/targetModifiedStopOnReconfig/bin/switch-to-configuration test 2>&1, in 1.86 seconds)1955machine: must fail: systemctl is-active test-service.servce1956machine: (finished: must fail: systemctl is-active test-service.servce, in 0.03 seconds)1957machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&11958machine # [ 68.858392] nixos[4105]: switching to system configuration /nix/store/ijkk1ckfmjl70dxcsil07560acghwcb6-nixos-system-machine-test1959machine # [ 68.861623] systemd[1]: Stopped target Local File Systems.1960machine # [ 68.865354] systemd[1]: Stopped target Remote File Systems.1961machine # [ 68.866271] systemd[1]: Stopped target test-target.target.1962machine # [ 69.146818] systemd[1]: Reload requested from client PID 4105 ('.switch-to-conf') (unit backdoor.service)...1963machine # [ 69.149298] systemd[1]: Reloading...1964machine # [ 69.476139] systemd[1]: Reloading finished in 325 ms.1965machine # [ 69.568556] systemd[582]: Reexecution requested from client PID 4174 ('switch-to-confi')...1966machine # [ 69.573096] systemd[582]: Reexecuting.1967machine # [ 69.631444] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1968machine # [ 69.743346] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1969machine # [ 69.750353] systemd[582]: Reload requested from client PID 4174 ('switch-to-confi')...1970machine # [ 69.753029] systemd[582]: Reloading...1971machine # [ 69.857154] systemd[582]: Reloading finished in 102 ms.1972machine # [ 69.872200] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1973machine # [ 69.882698] systemd[582]: Stopped Run user-specific NixOS activation.1974machine # [ 69.885162] systemd[582]: Stopping Run user-specific NixOS activation...1975machine # [ 69.887300] systemd[582]: Starting Run user-specific NixOS activation...1976machine # [ 69.919597] systemd[582]: Finished Run user-specific NixOS activation.1977machine # [ 69.924841] systemd[1]: Stopped target Reactivate sysinit units.1978machine # [ 69.925902] systemd[1]: Stopping Reactivate sysinit units...1979machine # [ 69.926742] systemd[1]: Reached target Reactivate sysinit units.1980machine # [ 69.930776] systemd[1]: Reached target Remote File Systems.1981machine # [ 69.935177] systemd[1]: Starting linger-users.service...1982machine # [ 69.942303] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1983machine # [ 69.943890] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1984machine # [ 69.952491] systemd[1]: Reached target Local File Systems.1985machine # [ 69.953379] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1986machine # [ 69.964593] systemd[1]: Starting Create SUID/SGID Wrappers...1987machine # [ 69.965619] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1988machine # [ 69.966894] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671989machine # [ 69.979678] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1990machine # [ 69.985710] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1991machine # [ 69.991319] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1992machine # [ 70.030868] systemd[1]: linger-users.service: Deactivated successfully.1993machine # [ 70.033860] systemd[1]: Finished linger-users.service.1994machine # [ 70.164134] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1995machine # [ 70.168338] systemd[1]: Finished Create SUID/SGID Wrappers.1996machine # [ 70.169295] systemd[1]: Started test-timer.timer.1997machine # [ 70.423178] nixos[4105]: finished switching to system configuration /nix/store/ijkk1ckfmjl70dxcsil07560acghwcb6-nixos-system-machine-test1998machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&1, in 1.77 seconds)1999(finished: subtest: targets, in 9.31 seconds)2000subtest: paths2001machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/path/bin/switch-to-configuration test 2>&12002machine # [ 70.647154] nixos[4245]: switching to system configuration /nix/store/bggrz7xyv12q6qrwwf9ijf40zcds13qd-nixos-system-machine-test2003machine # [ 70.652473] systemd[1]: Stopped target Local File Systems.2004machine # [ 70.653352] systemd[1]: Stopped target Remote File Systems.2005machine # [ 70.654173] systemd[1]: test-timer.timer: Deactivated successfully.2006machine # [ 70.655050] systemd[1]: Stopped test-timer.timer.2007machine # [ 70.946643] systemd[1]: Reload requested from client PID 4245 ('.switch-to-conf') (unit backdoor.service)...2008machine # [ 70.948961] systemd[1]: Reloading...2009machine # [ 71.289515] systemd[1]: Reloading finished in 338 ms.2010machine # [ 71.380474] systemd[582]: Reexecution requested from client PID 4314 ('switch-to-confi')...2011machine # [ 71.382009] systemd[582]: Reexecuting.2012machine # [ 71.440528] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2013machine # [ 71.557241] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2014machine # [ 71.564079] systemd[582]: Reload requested from client PID 4314 ('switch-to-confi')...2015machine # [ 71.568046] systemd[582]: Reloading...2016machine # [ 71.672927] systemd[582]: Reloading finished in 106 ms.2017machine # [ 71.688188] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2018machine # [ 71.698140] systemd[582]: Stopped Run user-specific NixOS activation.2019machine # [ 71.700613] systemd[582]: Stopping Run user-specific NixOS activation...2020machine # [ 71.702839] systemd[582]: Starting Run user-specific NixOS activation...2021machine # [ 71.736111] systemd[582]: Finished Run user-specific NixOS activation.2022machine # [ 71.739944] systemd[1]: Stopped target Reactivate sysinit units.2023machine # [ 71.742019] systemd[1]: Stopping Reactivate sysinit units...2024machine # [ 71.742883] systemd[1]: Reached target Reactivate sysinit units.2025machine # [ 71.745284] systemd[1]: Started test-watch.path.2026machine # [ 71.747028] systemd[1]: Reached target Local File Systems.2027machine # [ 71.750271] systemd[1]: Reached target Remote File Systems.2028machine # [ 71.754745] systemd[1]: Starting linger-users.service...2029machine # [ 71.765115] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2030machine # [ 71.774067] systemd[1]: Starting Create SUID/SGID Wrappers...2031machine # [ 71.775070] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2032machine # [ 71.786726] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672033machine # [ 71.794556] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2034machine # [ 71.798190] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2035machine # [ 71.799527] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2036machine # [ 71.806285] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2037machine # [ 71.807867] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2038machine # [ 71.846556] systemd[1]: linger-users.service: Deactivated successfully.2039machine # [ 71.850264] systemd[1]: Finished linger-users.service.2040machine # [ 71.989170] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2041machine # [ 71.992614] systemd[1]: Finished Create SUID/SGID Wrappers.2042machine # [ 72.249079] nixos[4245]: finished switching to system configuration /nix/store/bggrz7xyv12q6qrwwf9ijf40zcds13qd-nixos-system-machine-test2043machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/path/bin/switch-to-configuration test 2>&1, in 1.83 seconds)2044machine: must fail: test -f /testpath-modified2045machine: (finished: must fail: test -f /testpath-modified, in 0.02 seconds)2046machine: must succeed: touch /testpath2047machine: (finished: must succeed: touch /testpath, in 0.03 seconds)2048machine: waiting for success: test -f /testpath-modified2049machine # [ 72.314426] systemd[1]: Starting test-watch.service...2050machine # [ 72.355607] systemd[1]: Finished test-watch.service.2051machine: (finished: waiting for success: test -f /testpath-modified, in 1.06 seconds)2052machine: must succeed: rm /testpath /testpath-modified2053machine: (finished: must succeed: rm /testpath /testpath-modified, in 0.03 seconds)2054machine # [ 73.428634] systemd[1]: test-watch.service: Deactivated successfully.2055machine # [ 73.429983] systemd[1]: Stopped test-watch.service.2056machine # Stopping 'test-watch.service', but its triggering units are still active:2057machine # test-watch.path2058machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/pathModified/bin/switch-to-configuration test 2>&12059machine # [ 73.640701] nixos[4410]: switching to system configuration /nix/store/5izz4rzz8xy86l36hfw29bmqhkv50mxq-nixos-system-machine-test2060machine # [ 73.643210] systemd[1]: Stopped target Local File Systems.2061machine # [ 73.646358] systemd[1]: Stopped target Remote File Systems.2062machine # [ 73.947917] systemd[1]: Reload requested from client PID 4410 ('.switch-to-conf') (unit backdoor.service)...2063machine # [ 73.950462] systemd[1]: Reloading...2064machine # [ 74.286739] systemd[1]: Reloading finished in 335 ms.2065machine # [ 74.375259] systemd[582]: Reexecution requested from client PID 4480 ('switch-to-confi')...2066machine # [ 74.379807] systemd[582]: Reexecuting.2067machine # [ 74.431646] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2068machine # [ 74.543067] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2069machine # [ 74.549826] systemd[582]: Reload requested from client PID 4480 ('switch-to-confi')...2070machine # [ 74.553889] systemd[582]: Reloading...2071machine # [ 74.657620] systemd[582]: Reloading finished in 104 ms.2072machine # [ 74.672193] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2073machine # [ 74.682669] systemd[582]: Stopped Run user-specific NixOS activation.2074machine # [ 74.685232] systemd[582]: Stopping Run user-specific NixOS activation...2075machine # [ 74.687442] systemd[582]: Starting Run user-specific NixOS activation...2076machine # [ 74.719867] systemd[582]: Finished Run user-specific NixOS activation.2077machine # [ 74.724885] systemd[1]: Stopped target Reactivate sysinit units.2078machine # [ 74.725912] systemd[1]: Stopping Reactivate sysinit units...2079machine # [ 74.726740] systemd[1]: Reached target Reactivate sysinit units.2080machine # [ 74.730275] systemd[1]: Reached target Remote File Systems.2081machine # [ 74.738563] systemd[1]: Reached target Local File Systems.2082machine # [ 74.742417] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2083machine # [ 74.746544] systemd[1]: Starting Create SUID/SGID Wrappers...2084machine # [ 74.747496] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2085machine # [ 74.756310] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672086machine # [ 74.758636] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2087machine # [ 74.763235] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2088machine # [ 74.765066] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2089machine # [ 74.766353] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2090machine # [ 74.768535] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2091machine # [ 74.920843] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2092machine # [ 74.924570] systemd[1]: Finished Create SUID/SGID Wrappers.2093machine # [ 74.927474] systemd[1]: Starting linger-users.service...2094machine # [ 74.969409] systemd[1]: linger-users.service: Deactivated successfully.2095machine # [ 74.972494] systemd[1]: Finished linger-users.service.2096machine # [ 75.227940] nixos[4410]: finished switching to system configuration /nix/store/5izz4rzz8xy86l36hfw29bmqhkv50mxq-nixos-system-machine-test2097machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/pathModified/bin/switch-to-configuration test 2>&1, in 1.80 seconds)2098machine: must succeed: touch /testpath2099machine: (finished: must succeed: touch /testpath, in 0.03 seconds)2100machine: must fail: test -f /testpath-modified2101machine: (finished: must fail: test -f /testpath-modified, in 0.02 seconds)2102machine: must succeed: touch /testpath22103machine: (finished: must succeed: touch /testpath2, in 0.03 seconds)2104machine: waiting for success: test -f /testpath-modified2105machine # [ 75.317547] systemd[1]: Starting test-watch.service...2106machine # [ 75.355512] systemd[1]: Finished test-watch.service.2107machine: (finished: waiting for success: test -f /testpath-modified, in 1.06 seconds)2108(finished: subtest: paths, in 5.94 seconds)2109subtest: slices2110machine: must succeed: echo 0 > /proc/sys/vm/panic_on_oom2111machine: (finished: must succeed: echo 0 > /proc/sys/vm/panic_on_oom, in 0.02 seconds)2112machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/slice/bin/switch-to-configuration test 2>&12113machine # [ 76.600598] nixos[4575]: switching to system configuration /nix/store/60sjxlxlk04r61fa65gx3dqw6fjbzl0g-nixos-system-machine-test2114machine # [ 76.603372] systemd[1]: Stopped target Local File Systems.2115machine # [ 76.606366] systemd[1]: test-watch.service: Deactivated successfully.2116machine # [ 76.607350] systemd[1]: Stopped test-watch.service.2117machine # [ 76.611380] systemd[1]: Stopped target Remote File Systems.2118machine # [ 76.613808] systemd[1]: Starting test-watch.service...2119machine # [ 76.620504] systemd[1]: test-watch.path: Deactivated successfully.2120machine # [ 76.621525] systemd[1]: Stopped test-watch.path.2121machine # [ 76.658763] systemd[1]: Finished test-watch.service.2122machine # [ 76.937165] systemd[1]: Reload requested from client PID 4575 ('.switch-to-conf') (unit backdoor.service)...2123machine # [ 76.939241] systemd[1]: Reloading...2124machine # [ 77.269408] systemd[1]: Reloading finished in 328 ms.2125machine # [ 77.355157] systemd[582]: Reexecution requested from client PID 4646 ('switch-to-confi')...2126machine # [ 77.358833] systemd[582]: Reexecuting.2127machine # [ 77.411576] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2128machine # [ 77.523340] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2129machine # [ 77.530402] systemd[582]: Reload requested from client PID 4646 ('switch-to-confi')...2130machine # [ 77.534376] systemd[582]: Reloading...2131machine # [ 77.640159] systemd[582]: Reloading finished in 106 ms.2132machine # [ 77.656197] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2133machine # [ 77.668829] systemd[582]: Stopped Run user-specific NixOS activation.2134machine # [ 77.671302] systemd[582]: Stopping Run user-specific NixOS activation...2135machine # [ 77.673727] systemd[582]: Starting Run user-specific NixOS activation...2136machine # [ 77.703911] systemd[582]: Finished Run user-specific NixOS activation.2137machine # [ 77.708795] systemd[1]: Stopped target Reactivate sysinit units.2138machine # [ 77.712559] systemd[1]: Stopping Reactivate sysinit units...2139machine # [ 77.713477] systemd[1]: Reached target Reactivate sysinit units.2140machine # [ 77.714344] systemd[1]: Reached target Remote File Systems.2141machine # [ 77.720691] systemd[1]: Reached target Local File Systems.2142machine # [ 77.727489] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2143machine # [ 77.736627] systemd[1]: Starting Create SUID/SGID Wrappers...2144machine # [ 77.737565] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2145machine # [ 77.738845] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672146machine # [ 77.745238] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2147machine # [ 77.746890] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2148machine # [ 77.748965] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2149machine # [ 77.750245] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2150machine # [ 77.752417] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2151machine # [ 77.908528] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2152machine # [ 77.910461] systemd[1]: Finished Create SUID/SGID Wrappers.2153machine # [ 77.915290] systemd[1]: Starting linger-users.service...2154machine # [ 77.957548] systemd[1]: linger-users.service: Deactivated successfully.2155machine # [ 77.960473] systemd[1]: Finished linger-users.service.2156machine # [ 78.218012] nixos[4575]: finished switching to system configuration /nix/store/60sjxlxlk04r61fa65gx3dqw6fjbzl0g-nixos-system-machine-test2157machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/slice/bin/switch-to-configuration test 2>&1, in 1.84 seconds)2158machine: must fail: systemctl start testservice.service2159machine # [ 78.263626] systemd[1]: Created slice Slice /testslice.2160machine # [ 78.282808] systemd invoked oom-killer: gfp_mask=0x400dc0(GFP_KERNEL_ACCOUNT|__GFP_ZERO), order=0, oom_score_adj=02161machine # [ 78.282958] CPU: 0 UID: 0 PID: 1 Comm: systemd Tainted: G E 6.18.57 #1-NixOS PREEMPTLAZY2162machine # [ 78.282966] Tainted: [E]=UNSIGNED_MODULE2163machine # [ 78.282974] Hardware name: linux,dummy-virt (DT)2164machine # [ 78.282977] Call trace:2165machine # [ 78.282979] show_stack+0x30/0x90 (C)2166machine # [ 78.283037] dump_stack_lvl+0x60/0x802167machine # [ 78.283047] dump_stack+0x18/0x242168machine # [ 78.283049] dump_header+0x48/0x1982169machine # [ 78.283060] out_of_memory+0x548/0x5a02170machine # [ 78.283082] mem_cgroup_out_of_memory+0xcc/0xe02171machine # [ 78.283101] try_charge_memcg+0x4c0/0x6e02172machine # [ 78.283108] obj_cgroup_charge_pages+0x70/0x1f82173machine # [ 78.283111] obj_cgroup_charge_account+0x120/0x1c02174machine # [ 78.283113] __memcg_slab_post_alloc_hook+0xf4/0x2d02175machine # [ 78.283116] __kmalloc_cache_noprof+0x4c0/0x5902176machine # [ 78.283126] mem_cgroup_css_alloc+0x10c/0x8402177machine # [ 78.283150] cgroup_apply_control_enable+0x144/0x3802178machine # [ 78.283166] cgroup_mkdir+0x150/0x6802179machine # [ 78.283170] kernfs_iop_mkdir+0x90/0xf82180machine # [ 78.283194] vfs_mkdir+0x1a0/0x2a02181machine # [ 78.283206] do_mkdirat+0x154/0x1902182machine # [ 78.283209] __arm64_sys_mkdirat+0x54/0xb02183machine # [ 78.283212] invoke_syscall.constprop.0+0x48/0xf02184machine # [ 78.283219] el0_svc_common.constprop.0+0x40/0xf02185machine # [ 78.283221] do_el0_svc+0x24/0x402186machine # [ 78.283224] el0_svc+0x40/0x1a82187machine # [ 78.283227] el0t_64_sync_handler+0xa0/0xe82188machine # [ 78.283229] el0t_64_sync+0x198/0x1a02189machine # [ 78.283232] memory: usage 0kB, limit 0kB, failcnt 182190machine # [ 78.310641] swap: usage 0kB, limit 9007199254740988kB, failcnt 02191machine # [ 78.310709] Memory cgroup stats for /testslice.slice:2192machine # [ 78.310749] anon 02193machine # [ 78.310840] file 02194machine # [ 78.310883] kernel 02195machine # [ 78.310925] kernel_stack 02196machine # [ 78.310966] pagetables 02197machine # [ 78.311007] sec_pagetables 02198machine # [ 78.311049] percpu 02199machine # [ 78.311078] sock 02200machine # [ 78.311121] vmalloc 02201machine # [ 78.311164] shmem 02202machine # [ 78.311193] zswap 02203machine # [ 78.311235] zswapped 02204machine # [ 78.311277] file_mapped 02205machine # [ 78.311688] file_dirty 02206machine # [ 78.311961] file_writeback 02207machine # [ 78.312268] swapcached 02208machine # [ 78.321758] anon_thp 02209machine # [ 78.322012] file_thp 02210machine # [ 78.322164] shmem_thp 02211machine # [ 78.322306] inactive_anon 02212machine # [ 78.322442] active_anon 02213machine # [ 78.322577] inactive_file 02214machine # [ 78.322709] active_file 02215machine # [ 78.322839] unevictable 02216machine # [ 78.322968] slab_reclaimable 02217machine # [ 78.323110] slab_unreclaimable 02218machine # [ 78.323248] slab 02219machine # [ 78.323388] hugetlb 02220machine # [ 78.323530] workingset_refault_anon 02221machine # [ 78.323666] workingset_refault_file 02222machine # [ 78.323805] workingset_activate_anon 02223machine # [ 78.323944] workingset_activate_file 02224machine # [ 78.324104] workingset_restore_anon 02225machine # [ 78.324239] workingset_restore_file 02226machine # [ 78.333808] workingset_nodereclaim 02227machine # [ 78.334033] pgdemote_kswapd 02228machine # [ 78.334173] pgdemote_direct 02229machine # [ 78.334294] pgdemote_khugepaged 02230machine # [ 78.334413] pgdemote_proactive 02231machine # [ 78.334579] pgpromote_success 02232machine # [ 78.334721] pgscan 02233machine # [ 78.334838] pgsteal 02234machine # [ 78.334952] pswpin 02235machine # [ 78.335065] pswpout 02236machine # [ 78.335177] pgscan_kswapd 02237machine # [ 78.335290] pgscan_direct 02238machine # [ 78.335411] pgscan_khugepaged 02239machine # [ 78.335522] pgscan_proactive 02240machine # [ 78.335629] pgsteal_kswapd 02241machine # [ 78.335734] pgsteal_direct 02242machine # [ 78.335835] pgsteal_khugepaged 02243machine # [ 78.335938] pgsteal_proactive 02244machine # [ 78.336065] pgfault 02245machine # [ 78.336169] pgmajfault 02246machine # [ 78.336272] pgrefill 02247machine # [ 78.347408] pgactivate 02248machine # [ 78.347611] pgdeactivate 02249machine # [ 78.347723] pglazyfree 02250machine # [ 78.347830] pglazyfreed 02251machine # [ 78.347936] swpin_zero 02252machine # [ 78.348053] swpout_zero 02253machine # [ 78.348161] zswpin 02254machine # [ 78.348266] zswpout 02255machine # [ 78.351872] zswpwb 02256machine # [ 78.352122] thp_fault_alloc 02257machine # [ 78.352236] thp_collapse_alloc 02258machine # [ 78.339027] systemd[1]: testservice.service: Failed to create cgroup /testslice.slice/testservice.service: Cannot allocate memory[ 78.354119] thp_swpout 02259machine # [ 78.354283] thp_swpout_fallback 02260machine # [ 78.354392] numa_pages_migrated 02261machine # [ 78.354498] numa_pte_updates 02262machine # [ 78.354602] numa_hint_faults 02263machine # [ 78.354714] Tasks state (memory values in pages):2264machine # [ 78.354826] [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name2265machine # [ 78.354959] Out of memory and no killable processes...2266machine # 2267machine # [ 78.347532] systemd[1]: testservice.service: Failed to spawn 'start' task: Cannot allocate memory2268machine # [ 78.350695] systemd[1]: testservice.service: Failed with result 'resources'.2269machine # Job for testservice.service failed because of unavailable resources or another system error.2270machine # See "systemctl status testservice.service" and "journalctl -xeu testservice.service" for details.2271machine # [ 78.357073] systemd[1]: Failed to start testservice.service.2272machine: (finished: must fail: systemctl start testservice.service, in 0.14 seconds)2273machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/sliceModified/bin/switch-to-configuration test 2>&12274machine # [ 78.558257] nixos[4721]: switching to system configuration /nix/store/z9ggczbiv8yildgyyczrz64wys14yvdl-nixos-system-machine-test2275machine # [ 78.560894] systemd[1]: Stopped target Remote File Systems.2276machine # [ 78.563493] systemd[1]: Stopped target Local File Systems.2277machine # [ 78.854703] systemd[1]: Reload requested from client PID 4721 ('.switch-to-conf') (unit backdoor.service)...2278machine # [ 78.856877] systemd[1]: Reloading...2279machine # [ 79.176083] systemd[1]: Reloading finished in 317 ms.2280machine # [ 79.265988] systemd[582]: Reexecution requested from client PID 4790 ('switch-to-confi')...2281machine # [ 79.267478] systemd[582]: Reexecuting.2282machine # [ 79.319096] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2283machine # [ 79.429063] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2284machine # [ 79.436099] systemd[582]: Reload requested from client PID 4790 ('switch-to-confi')...2285machine # [ 79.437466] systemd[582]: Reloading...2286machine # [ 79.544150] systemd[582]: Reloading finished in 104 ms.2287machine # [ 79.560199] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2288machine # [ 79.571158] systemd[582]: Stopped Run user-specific NixOS activation.2289machine # [ 79.573652] systemd[582]: Stopping Run user-specific NixOS activation...2290machine # [ 79.575653] systemd[582]: Starting Run user-specific NixOS activation...2291machine # [ 79.608214] systemd[582]: Finished Run user-specific NixOS activation.2292machine # [ 79.612357] systemd[1]: Stopped target Reactivate sysinit units.2293machine # [ 79.615393] systemd[1]: Stopping Reactivate sysinit units...2294machine # [ 79.616840] systemd[1]: Reached target Reactivate sysinit units.2295machine # [ 79.617710] systemd[1]: Reached target Local File Systems.2296machine # [ 79.620079] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2297machine # [ 79.621430] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2298machine # [ 79.626090] systemd[1]: Reached target Remote File Systems.2299machine # [ 79.630794] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2300machine # [ 79.641360] systemd[1]: Starting Create SUID/SGID Wrappers...2301machine # [ 79.642272] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2302machine # [ 79.643553] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672303machine # [ 79.650923] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2304machine # [ 79.653326] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2305machine # [ 79.654703] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2306machine # [ 79.809364] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2307machine # [ 79.812510] systemd[1]: Finished Create SUID/SGID Wrappers.2308machine # [ 79.816306] systemd[1]: Starting linger-users.service...2309machine # [ 79.858786] systemd[1]: linger-users.service: Deactivated successfully.2310machine # [ 79.862081] systemd[1]: Finished linger-users.service.2311machine # [ 80.117262] nixos[4721]: finished switching to system configuration /nix/store/z9ggczbiv8yildgyyczrz64wys14yvdl-nixos-system-machine-test2312machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/sliceModified/bin/switch-to-configuration test 2>&1, in 1.76 seconds)2313machine: must succeed: systemctl start testservice.service2314machine # [ 80.164653] systemd[1]: Starting testservice.service...2315machine # [ 80.198592] systemd[1]: Finished testservice.service.2316machine: (finished: must succeed: systemctl start testservice.service, in 0.08 seconds)2317machine: must succeed: echo 1 > /proc/sys/vm/panic_on_oom2318machine: (finished: must succeed: echo 1 > /proc/sys/vm/panic_on_oom, in 0.02 seconds)2319(finished: subtest: slices, in 3.85 seconds)2320subtest: dbus reloads2321machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/bin/switch-to-configuration test 2>&12322machine # [ 80.405808] nixos[4870]: switching to system configuration /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test2323machine # [ 80.408671] systemd[1]: Stopped target Local File Systems.2324machine # [ 80.412673] systemd[1]: testservice.service: Deactivated successfully.2325machine # [ 80.413685] systemd[1]: Stopped testservice.service.2326machine # [ 80.415957] systemd[1]: Removed slice Slice /testslice.2327machine # [ 80.418823] systemd[1]: Stopped target Remote File Systems.2328machine # [ 80.691254] systemd[1]: Reload requested from client PID 4870 ('.switch-to-conf') (unit backdoor.service)...2329machine # [ 80.693465] systemd[1]: Reloading...2330machine # [ 81.002881] systemd[1]: Reloading finished in 308 ms.2331machine # [ 81.092356] systemd[582]: Reexecution requested from client PID 4939 ('switch-to-confi')...2332machine # [ 81.093866] systemd[582]: Reexecuting.2333machine # [ 81.151652] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2334machine # [ 81.262926] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2335machine # [ 81.270165] systemd[582]: Reload requested from client PID 4939 ('switch-to-confi')...2336machine # [ 81.272692] systemd[582]: Reloading...2337machine # [ 81.377725] systemd[582]: Reloading finished in 103 ms.2338machine # [ 81.392186] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2339machine # [ 81.402474] systemd[582]: Stopped Run user-specific NixOS activation.2340machine # [ 81.404997] systemd[582]: Stopping Run user-specific NixOS activation...2341machine # [ 81.407119] systemd[582]: Starting Run user-specific NixOS activation...2342machine # [ 81.440166] systemd[582]: Finished Run user-specific NixOS activation.2343machine # [ 81.444660] systemd[1]: Stopped target Reactivate sysinit units.2344machine # [ 81.445618] systemd[1]: Stopping Reactivate sysinit units...2345machine # [ 81.446414] systemd[1]: Reached target Reactivate sysinit units.2346machine # [ 81.449608] systemd[1]: Reached target Local File Systems.2347machine # [ 81.452945] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2348machine # [ 81.457357] systemd[1]: Starting Create SUID/SGID Wrappers...2349machine # [ 81.458249] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2350machine # [ 81.459532] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672351machine # [ 81.468679] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2352machine # [ 81.470345] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2353machine # [ 81.471615] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2354machine # [ 81.478810] systemd[1]: Reached target Remote File Systems.2355machine # [ 81.481324] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2356machine # [ 81.490996] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2357machine # [ 81.641014] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2358machine # [ 81.642855] systemd[1]: Finished Create SUID/SGID Wrappers.2359machine # [ 81.648270] systemd[1]: Starting linger-users.service...2360machine # [ 81.688695] systemd[1]: linger-users.service: Deactivated successfully.2361machine # [ 81.690403] systemd[1]: Finished linger-users.service.2362machine # [ 81.946871] nixos[4870]: finished switching to system configuration /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test2363machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.73 seconds)2364machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/dbusReload/bin/switch-to-configuration test 2>&12365machine # [ 82.171236] nixos[5010]: switching to system configuration /nix/store/n1gzmyzf9cjban2l9pmdyci9f0xnm206-nixos-system-machine-test2366machine # [ 82.174181] systemd[1]: Stopped target Remote File Systems.2367machine # [ 82.176847] systemd[1]: Stopped target Local File Systems.2368machine # [ 82.459109] systemd[1]: Reload requested from client PID 5010 ('.switch-to-conf') (unit backdoor.service)...2369machine # [ 82.461405] systemd[1]: Reloading...2370machine # [ 82.773957] systemd[1]: Reloading finished in 311 ms.2371machine # [ 82.863600] systemd[582]: Reexecution requested from client PID 5079 ('switch-to-confi')...2372machine # [ 82.866245] systemd[582]: Reexecuting.2373machine # [ 82.924136] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2374machine # [ 83.034900] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2375machine # [ 83.041464] systemd[582]: Reload requested from client PID 5079 ('switch-to-confi')...2376machine # [ 83.045150] systemd[582]: Reloading...2377machine # [ 83.147925] systemd[582]: Reloading finished in 103 ms.2378machine # [ 83.164177] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2379machine # [ 83.177908] systemd[582]: Stopped Run user-specific NixOS activation.2380machine # [ 83.178979] systemd[582]: Stopping Run user-specific NixOS activation...2381machine # [ 83.181662] systemd[582]: Starting Run user-specific NixOS activation...2382machine # [ 83.210912] systemd[582]: Finished Run user-specific NixOS activation.2383machine # [ 83.215059] systemd[1]: Stopped target Reactivate sysinit units.2384machine # [ 83.217468] systemd[1]: Stopping Reactivate sysinit units...2385machine # [ 83.218319] systemd[1]: Reached target Reactivate sysinit units.2386machine # [ 83.220929] systemd[1]: Reloading D-Bus System Message Bus...2387machine # [ 83.222344] dbus-broker-launch[435]: Caught SIGHUP, trigger reload.2388machine # [ 83.227536] dbus-broker-launch[435]: Looking up NSS user entry for 'systemd-timesync'...2389machine # [ 83.229903] dbus-broker-launch[435]: NSS returned no entry for 'systemd-timesync'2390machine # [ 83.230943] dbus-broker-launch[435]: Invalid user-name in /nix/store/lwvd8zypc7d831h4k85sqslcsqwvyagn-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2391machine # [ 83.244980] systemd[1]: Reloaded D-Bus System Message Bus.2392machine # [ 83.249480] systemd[1]: Reached target Local File Systems.2393machine # [ 83.252467] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2394machine # [ 83.256520] systemd[1]: Starting Create SUID/SGID Wrappers...2395machine # [ 83.257397] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2396machine # [ 83.258629] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672397machine # [ 83.270765] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2398machine # [ 83.277642] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2399machine # [ 83.278985] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2400machine # [ 83.280965] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2401machine # [ 83.282356] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2402machine # [ 83.283750] systemd[1]: Reached target Remote File Systems.2403machine # [ 83.432481] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2404machine # [ 83.436614] systemd[1]: Finished Create SUID/SGID Wrappers.2405machine # [ 83.439775] systemd[1]: Starting linger-users.service...2406machine # [ 83.480714] systemd[1]: linger-users.service: Deactivated successfully.2407machine # [ 83.483565] systemd[1]: Finished linger-users.service.2408machine # [ 83.740688] nixos[5010]: finished switching to system configuration /nix/store/n1gzmyzf9cjban2l9pmdyci9f0xnm206-nixos-system-machine-test2409machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/dbusReload/bin/switch-to-configuration test 2>&1, in 1.79 seconds)2410(finished: subtest: dbus reloads, in 3.52 seconds)2411subtest: generators2412machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/generators/bin/switch-to-configuration test 2>&12413machine # [ 83.943798] nixos[5150]: switching to system configuration /nix/store/xb4dpqxqpi6c69x3ac9gbxvrhj30vv8f-nixos-system-machine-test2414machine # [ 83.946466] systemd[1]: Stopped target Local File Systems.2415machine # [ 83.948851] systemd[1]: Stopped target Remote File Systems.2416machine # [ 84.229446] systemd[1]: Reload requested from client PID 5150 ('.switch-to-conf') (unit backdoor.service)...2417machine # [ 84.231442] systemd[1]: Reloading...2418machine # [ 84.540107] systemd[1]: Reloading finished in 307 ms.2419machine # [ 84.625320] systemd[582]: Reexecution requested from client PID 5221 ('switch-to-confi')...2420machine # [ 84.629368] systemd[582]: Reexecuting.2421machine # [ 84.675164] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2422machine # [ 84.783176] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2423machine # [ 84.789991] systemd[582]: Reload requested from client PID 5221 ('switch-to-confi')...2424machine # [ 84.793730] systemd[582]: Reloading...2425machine # [ 84.895304] systemd[582]: Reloading finished in 102 ms.2426machine # [ 84.912164] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2427machine # [ 84.922536] systemd[582]: Stopped Run user-specific NixOS activation.2428machine # [ 84.924978] systemd[582]: Stopping Run user-specific NixOS activation...2429machine # [ 84.927124] systemd[582]: Starting Run user-specific NixOS activation...2430machine # [ 84.959401] systemd[582]: Finished Run user-specific NixOS activation.2431machine # [ 84.962902] systemd[1]: Stopped target Reactivate sysinit units.2432machine # [ 84.967249] systemd[1]: Stopping Reactivate sysinit units...2433machine # [ 84.968722] systemd[1]: Reached target Reactivate sysinit units.2434machine # [ 84.969883] dbus-broker-launch[435]: Caught SIGHUP, trigger reload.2435machine # [ 84.970780] systemd[1]: Reloading D-Bus System Message Bus...2436machine # [ 84.974711] dbus-broker-launch[435]: Looking up NSS user entry for 'systemd-timesync'...2437machine # [ 84.977015] dbus-broker-launch[435]: NSS returned no entry for 'systemd-timesync'2438machine # [ 84.978081] dbus-broker-launch[435]: Invalid user-name in /nix/store/lwvd8zypc7d831h4k85sqslcsqwvyagn-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2439machine # [ 84.987891] systemd[1]: Reloaded D-Bus System Message Bus.2440machine # [ 84.990699] systemd[1]: Reached target Remote File Systems.2441machine # [ 84.995418] systemd[1]: Reached target Local File Systems.2442machine # [ 84.998768] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2443machine # [ 85.002859] systemd[1]: Starting Create SUID/SGID Wrappers...2444machine # [ 85.003797] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2445machine # [ 85.012559] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672446machine # [ 85.014795] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2447machine # [ 85.022244] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2448machine # [ 85.023600] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2449machine # [ 85.025016] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2450machine # [ 85.026302] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2451machine # [ 85.180001] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2452machine # [ 85.184552] systemd[1]: Finished Create SUID/SGID Wrappers.2453machine # [ 85.187369] systemd[1]: Starting linger-users.service...2454machine # [ 85.228812] systemd[1]: linger-users.service: Deactivated successfully.2455machine # [ 85.231931] systemd[1]: Finished linger-users.service.2456machine # [ 85.487289] nixos[5150]: finished switching to system configuration /nix/store/xb4dpqxqpi6c69x3ac9gbxvrhj30vv8f-nixos-system-machine-test2457machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/specialisation/generators/bin/switch-to-configuration test 2>&1, in 1.75 seconds)2458machine: must succeed: systemctl start simple-generated.service && systemctl is-active simple-generated.service2459machine # [ 85.533075] systemd[1]: Started simple-generated.service.2460machine: (finished: must succeed: systemctl start simple-generated.service && systemctl is-active simple-generated.service, in 0.06 seconds)2461machine: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/bin/switch-to-configuration test 2>&12462machine # [ 85.749994] nixos[5299]: switching to system configuration /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test2463machine # [ 85.752794] systemd[1]: Stopped target Local File Systems.2464machine # [ 85.754385] systemd[1]: Stopped target Remote File Systems.2465machine # [ 86.015864] systemd[1]: Reload requested from client PID 5299 ('.switch-to-conf') (unit backdoor.service)...2466machine # [ 86.018056] systemd[1]: Reloading...2467machine # [ 86.320230] systemd[1]: Reloading finished in 300 ms.2468machine # [ 86.405805] systemd[582]: Reexecution requested from client PID 5369 ('switch-to-confi')...2469machine # [ 86.410002] systemd[582]: Reexecuting.2470machine # [ 86.459032] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2471machine # [ 86.567204] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2472machine # [ 86.574019] systemd[582]: Reload requested from client PID 5369 ('switch-to-confi')...2473machine # [ 86.577709] systemd[582]: Reloading...2474machine # [ 86.678325] systemd[582]: Reloading finished in 101 ms.2475machine # [ 86.696182] systemd[582]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2476machine # [ 86.707884] systemd[582]: Stopped Run user-specific NixOS activation.2477machine # [ 86.709029] systemd[582]: Stopping Run user-specific NixOS activation...2478machine # [ 86.711293] systemd[582]: Starting Run user-specific NixOS activation...2479machine # [ 86.743125] systemd[582]: Finished Run user-specific NixOS activation.2480machine # [ 86.747120] systemd[1]: Stopped target Reactivate sysinit units.2481machine # [ 86.749946] systemd[1]: Stopping Reactivate sysinit units...2482machine # [ 86.750811] systemd[1]: Reached target Reactivate sysinit units.2483machine # [ 86.753293] systemd[1]: Reached target Remote File Systems.2484machine # [ 86.756672] systemd[1]: Reached target Local File Systems.2485machine # [ 86.762923] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2486machine # [ 86.771389] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2487machine # [ 86.776488] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2488machine # [ 86.778016] systemd[1]: Starting Create SUID/SGID Wrappers...2489machine # [ 86.778825] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2490machine # [ 86.781654] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672491machine # [ 86.783903] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2492machine # [ 86.786687] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2493machine # [ 86.789782] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2494machine # [ 86.935073] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2495machine # [ 86.938171] systemd[1]: Finished Create SUID/SGID Wrappers.2496machine # [ 86.942375] systemd[1]: Starting linger-users.service...2497machine # [ 86.984104] systemd[1]: linger-users.service: Deactivated successfully.2498machine # [ 86.987088] systemd[1]: Finished linger-users.service.2499machine # [ 87.241897] nixos[5299]: finished switching to system configuration /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test2500machine: (finished: must succeed: /nix/store/6zmqgf0z2f30nasw540h5sbpw6xnzpl3-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.69 seconds)2501machine: must succeed: systemctl is-active simple-generated.service2502machine: (finished: must succeed: systemctl is-active simple-generated.service, in 0.03 seconds)2503(finished: subtest: generators, in 3.53 seconds)2504(finished: run the VM test script, in 88.05 seconds)2505test script finished in 88.08s2506cleanup2507kill QemuMachine (pid 45)2508machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lq2fsnmg0q3y0vlxn91w8s8wvj1m6fld-python3-3.14.8/bin/python3.14)2509machine # [2026-10-20T03:09:35Z INFO virtiofsd] Client disconnected, shutting down2510machine # [2026-10-20T03:09:35Z INFO virtiofsd] Client disconnected, shutting down2511machine # [2026-10-20T03:09:35Z INFO virtiofsd] Client disconnected, shutting down2512(finished: cleanup, in 0.16 seconds)2513additionally exposed symbols:2514 machine, other,2515 vlan1,2516 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