vm-test-run-switch-test-units
checks.aarch64-linux.switchTest-units
· build #48
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7machine: waiting for unit multi-user.target8machine: waiting for the VM to finish booting9machine: starting vm10machine # Disk image does not exist, creating the virtualisation disk image...11machine: QEMU running (pid 45)12machine # Formatting '/build/vm-state-machine/tmp.LhRsDAnbD5', 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: aa128b2f-7bff-4ea1-ab97-4611d357589717machine # 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-09-29T03:50:00Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28machine # [2026-09-29T03:50:00Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29machine # [2026-09-29T03:50:00Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)30machine # [2026-09-29T03:50:00Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether31machine # [2026-09-29T03:50:00Z INFO virtiofsd] Waiting for vhost-user socket connection...32machine # [2026-09-29T03:50:00Z INFO virtiofsd] Waiting for vhost-user socket connection...33machine # [2026-09-29T03:50:00Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34machine # [2026-09-29T03:50:00Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35machine # [2026-09-29T03:50:00Z INFO virtiofsd] Waiting for vhost-user socket connection...36machine # [2026-09-29T03:50:00Z INFO virtiofsd] Client connected, servicing requests37machine # [2026-09-29T03:50:00Z INFO virtiofsd] Client connected, servicing requests38machine # [2026-09-29T03:50:00Z INFO virtiofsd] Client connected, servicing requests39machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]40machine # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 202641machine # [ 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 0x7fdee700-0x7fdf1e7f]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 s186776 r8192 d116328 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/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/init regInfo=/nix/.ro-store/3da8wm7ngq66bflr7blfm1x1nknxkifz-closure-info/registration console=ttyAMA0,115200n8 console=tty074machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/3da8wm7ngq66bflr7blfm1x1nknxkifz-closure-info/registration", will be passed to user space.75machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 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 74966 entries in 294 pages88machine # [ 0.000000] ftrace: allocated 294 pages with 4 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=384 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 @44ce0000 (indirect, esz 8, psz 64K, shr 1)109machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)110machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000111machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000112machine # [ 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.000030] arm-pv: using stolen time PV118machine # [ 0.000377] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)119machine # [ 0.000544] Console: colour dummy device 80x25120machine # [ 0.000553] printk: legacy console [tty0] enabled121machine # [ 0.000745] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)122machine # [ 0.000752] pid_max: default: 32768 minimum: 301123machine # [ 0.000837] LSM: initializing lsm=capability,landlock,yama,bpf,ima124machine # [ 0.000968] landlock: Up and running.125machine # [ 0.000972] Yama: becoming mindful.126machine # [ 0.001392] LSM support for eBPF active127machine # [ 0.001532] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)128machine # [ 0.001552] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)129machine # [ 0.003539] rcu: Hierarchical SRCU implementation.130machine # [ 0.003545] rcu: Max phase no-delay instances is 1000.131machine # [ 0.004820] fsl-mc MSI: its@8080000 domain created132machine # [ 0.004941] EFI services will not be available.133machine # [ 0.005010] smp: Bringing up secondary CPUs ...134machine # [ 0.005019] smp: Brought up 1 node, 1 CPU135machine # [ 0.005022] SMP: Total of 1 processors activated.136machine # [ 0.005025] CPU: All CPU(s) started at EL1137machine # [ 0.005038] CPU features: detected: Branch Target Identification138machine # [ 0.005043] CPU features: detected: ARMv8.4 Translation Table Level139machine # [ 0.005046] CPU features: detected: Instruction cache invalidation not required for I/D coherence140machine # [ 0.005050] CPU features: detected: Data cache clean to the PoU not required for I/D coherence141machine # [ 0.005053] CPU features: detected: Common not Private translations142machine # [ 0.005057] CPU features: detected: CRC32 instructions143machine # [ 0.005059] CPU features: detected: Data cache clean to Point of Deep Persistence144machine # [ 0.005063] CPU features: detected: Data cache clean to Point of Persistence145machine # [ 0.005066] CPU features: detected: Data independent timing control (DIT)146machine # [ 0.005069] CPU features: detected: E0PD147machine # [ 0.005071] CPU features: detected: Enhanced Counter Virtualization148machine # [ 0.005075] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)149machine # [ 0.005078] CPU features: detected: Enhanced Virtualization Traps150machine # [ 0.005081] CPU features: detected: Fine Grained Traps151machine # [ 0.005084] CPU features: detected: Generic authentication (architected QARMA5 algorithm)152machine # [ 0.005090] CPU features: detected: RCpc load-acquire (LDAPR)153machine # [ 0.005093] CPU features: detected: LSE atomic instructions154machine # [ 0.005096] CPU features: detected: Privileged Access Never155machine # [ 0.005098] CPU features: detected: PMUv3156machine # [ 0.005101] CPU features: detected: RAS Extension Support157machine # [ 0.005104] CPU features: detected: RASv1p1 Extension Support158machine # [ 0.005107] CPU features: detected: Random Number Generator159machine # [ 0.005110] CPU features: detected: Speculation barrier (SB)160machine # [ 0.005112] CPU features: detected: Stage-2 Force Write-Back161machine # [ 0.005115] CPU features: detected: TLB range maintenance instructions162machine # [ 0.005119] CPU features: detected: Speculative Store Bypassing Safe (SSBS)163machine # [ 0.005158] alternatives: applying system-wide alternatives164machine # [ 0.008208] CPU features: detected: BBM Level 2 without TLB conflict abort165machine # [ 0.008369] Memory: 893724K/1048576K available (24448K kernel code, 7094K rwdata, 26596K rodata, 4736K init, 1107K bss, 113540K reserved, 32768K cma-reserved)166machine # [ 0.008721] devtmpfs: initialized167machine # [ 0.010442] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)168machine # [ 0.010466] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).169machine # [ 0.010674] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL170machine # [ 0.010683] 0 pages in range for non-PLT usage171machine # [ 0.010684] 508224 pages in range for PLT usage172machine # [ 0.010812] pinctrl core: initialized pinctrl subsystem173machine # [ 0.011758] DMI not present or invalid.174machine # [ 0.014997] NET: Registered PF_NETLINK/PF_ROUTE protocol family175machine # [ 0.017397] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations176machine # [ 0.017546] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations177machine # [ 0.017708] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations178machine # [ 0.017731] audit: initializing netlink subsys (disabled)179machine # [ 0.018295] thermal_sys: Registered thermal governor 'fair_share'180machine # [ 0.018298] thermal_sys: Registered thermal governor 'bang_bang'181machine # [ 0.018301] thermal_sys: Registered thermal governor 'step_wise'182machine # [ 0.018304] thermal_sys: Registered thermal governor 'user_space'183machine # [ 0.018307] thermal_sys: Registered thermal governor 'power_allocator'184machine # [ 0.018344] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1185machine # [ 0.018352] cpuidle: using governor ladder186machine # [ 0.018358] cpuidle: using governor menu187machine # [ 0.018556] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.188machine # [ 0.018573] ASID allocator initialised with 65536 entries189machine # [ 0.019899] Serial: AMBA PL011 UART driver190machine # [ 0.025651] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1191machine # [ 0.025813] printk: console [ttyAMA0] enabled192machine # [ 0.156047] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages193machine # [ 0.156058] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page194machine # [ 0.156061] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages195machine # [ 0.156064] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page196machine # [ 0.156067] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages197machine # [ 0.156070] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page198machine # [ 0.156073] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages199machine # [ 0.156075] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page200machine # [ 0.157264] fbcon: Taking over console201machine # [ 0.157273] ACPI: Interpreter disabled.202machine # [ 0.167451] iommu: Default domain type: Translated203machine # [ 0.167457] iommu: DMA domain TLB invalidation policy: strict mode204machine # [ 0.167868] SCSI subsystem initialized205machine # [ 0.170003] usbcore: registered new interface driver usbfs206machine # [ 0.170032] usbcore: registered new interface driver hub207machine # [ 0.170048] usbcore: registered new device driver usb208machine # [ 0.170339] pps_core: LinuxPPS API ver. 1 registered209machine # [ 0.170342] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>210machine # [ 0.170350] PTP clock support registered211machine # [ 0.170400] EDAC MC: Ver: 3.0.0212machine # [ 0.170554] scmi_core: SCMI protocol bus registered213machine # [ 0.177619] FPGA manager framework214machine # [ 0.178170] vgaarb: loaded215machine # [ 0.178443] clocksource: Switched to clocksource arch_sys_counter216machine # [ 0.187515] VFS: Disk quotas dquot_6.6.0217machine # [ 0.187551] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)218machine # [ 0.189937] netfs: FS-Cache loaded219machine # [ 0.190057] pnp: PnP ACPI: disabled220machine # [ 0.195649] NET: Registered PF_INET protocol family221machine # [ 0.195810] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)222machine # [ 0.226276] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)223machine # [ 0.226311] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)224machine # [ 0.226334] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)225machine # [ 0.226370] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)226machine # [ 0.226443] TCP: Hash tables configured (established 8192 bind 8192)227machine # [ 0.231587] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)228machine # [ 0.231618] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)229machine # [ 0.231684] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)230machine # [ 0.231760] NET: Registered PF_UNIX/PF_LOCAL protocol family231machine # [ 0.231784] NET: Registered PF_XDP protocol family232machine # [ 0.231801] PCI: CLS 0 bytes, default 64233machine # [ 0.232067] Trying to unpack rootfs image as initramfs...234machine # [ 0.246480] kvm [1]: HYP mode not available235machine # [ 0.347136] Initialise system trusted keyrings236machine # [ 0.347280] workingset: timestamp_bits=42 max_order=18 bucket_order=0237machine # [ 0.347666] squashfs: version 4.0 (2009/01/31) Phillip Lougher238machine # [ 0.347710] 9p: Installing v9fs 9p2000 file system support239machine # [ 0.375772] Key type asymmetric registered240machine # [ 0.375790] Asymmetric key parser 'x509' registered241machine # [ 0.375869] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)242machine # [ 0.376009] io scheduler mq-deadline registered243machine # [ 0.376013] io scheduler kyber registered244machine # [ 0.388401] pl061_gpio 9030000.pl061: PL061 GPIO chip registered245machine # [ 0.389014] ledtrig-cpu: registered to indicate activity on CPUs246machine # [ 0.389416] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:247machine # [ 0.389430] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000248machine # [ 0.389440] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000249machine # [ 0.389445] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000250machine # [ 0.389463] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits251machine # [ 0.389486] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]252machine # [ 0.389597] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00253machine # [ 0.389604] pci_bus 0000:00: root bus resource [bus 00-ff]254machine # [ 0.389607] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]255machine # [ 0.389611] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]256machine # [ 0.389614] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]257machine # [ 0.389680] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint258machine # [ 0.390142] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint259machine # [ 0.390333] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]260machine # [ 0.390345] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]261machine # [ 0.390372] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]262machine # [ 0.390384] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]263machine # [ 0.414994] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint264machine # [ 0.415195] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]265machine # [ 0.415208] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]266machine # [ 0.415233] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]267machine # [ 0.415721] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint268machine # [ 0.415916] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]269machine # [ 0.415929] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]270machine # [ 0.415955] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]271machine # [ 0.416456] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint272machine # [ 0.416645] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]273machine # [ 0.416656] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]274machine # [ 0.416682] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275machine # [ 0.416694] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]276machine # [ 0.417176] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint277machine # [ 0.417369] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]278machine # [ 0.417395] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279machine # [ 0.417877] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint280machine # [ 0.418069] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.418095] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.435153] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint283machine # [ 0.435334] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]284machine # [ 0.435587] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint285machine # [ 0.435776] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]286machine # [ 0.435802] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]287machine # [ 0.436278] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint288machine # [ 0.436475] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]289machine # [ 0.436501] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]290machine # [ 0.436981] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint291machine # [ 0.437174] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]292machine # [ 0.437200] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]293machine # [ 0.437681] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint294machine # [ 0.437956] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]295machine # [ 0.437969] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]296machine # [ 0.437997] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]297machine # [ 0.458556] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint298machine # [ 0.458757] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]299machine # [ 0.458769] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]300machine # [ 0.458795] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]301machine # [ 0.459563] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned302machine # [ 0.459571] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned303machine # [ 0.459574] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned304machine # [ 0.459621] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned305machine # [ 0.459665] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned306machine # [ 0.459712] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned307machine # [ 0.459756] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned308machine # [ 0.459800] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned309machine # [ 0.459845] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned310machine # [ 0.459892] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned311machine # [ 0.459936] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned312machine # [ 0.459980] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned313machine # [ 0.460099] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned314machine # [ 0.460142] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned315machine # [ 0.460160] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned316machine # [ 0.460177] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned317machine # [ 0.460195] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned318machine # [ 0.460212] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned319machine # [ 0.460230] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned320machine # [ 0.460247] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned321machine # [ 0.460266] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned322machine # [ 0.460288] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned323machine # [ 0.460306] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned324machine # [ 0.460324] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned325machine # [ 0.460343] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned326machine # [ 0.460360] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned327machine # [ 0.460377] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned328machine # [ 0.460393] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned329machine # [ 0.460410] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned330machine # [ 0.460427] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned331machine # [ 0.460445] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned332machine # [ 0.460467] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]333machine # [ 0.460475] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]334machine # [ 0.460478] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]335machine # [ 0.461332] pci 0000:00:07.0: enabling device (0000 -> 0002)336machine # [ 0.524850] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)337machine # [ 0.528195] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)338machine # [ 0.538580] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)339machine # [ 0.540762] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)340machine # [ 0.544492] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)341machine # [ 0.554697] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)342machine # [ 0.556624] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)343machine # [ 0.560171] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)344machine # [ 0.562046] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)345machine # [ 0.572086] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)346machine # [ 0.575618] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)347machine # [ 0.588845] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled348machine # [ 0.591482] msm_serial: driver initialized349machine # [ 0.591643] SuperH (H)SCI(F) driver initialized350machine # [ 0.591701] STM32 USART driver initialized351machine # [ 0.631515] loop: module loaded352machine # [ 0.631704] virtio_blk virtio2: 1/0/0 default/read/poll queues353machine # [ 0.632469] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)354machine # [ 0.637597] megasas: 07.734.00.00-rc1355machine # [ 0.638296] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]356machine # [ 0.642252] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000357machine # [ 0.642270] Intel/Sharp Extended Query Table at 0x0031358machine # [ 0.646193] Using buffer write method359machine # [ 0.646226] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]360machine # [ 0.655814] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000361machine # [ 0.655874] Intel/Sharp Extended Query Table at 0x0031362machine # [ 0.657670] Using buffer write method363machine # [ 0.657688] Concatenating MTD devices:364machine # [ 0.657690] (0): "0.flash"365machine # [ 0.657693] (1): "0.flash"366machine # [ 0.657695] into device "0.flash"367machine # [ 0.949064] Freeing initrd memory: 26428K368machine # [ 0.955540] tun: Universal TUN/TAP device driver, 1.6369machine # [ 0.959517] thunder_xcv, ver 1.0370machine # [ 0.959549] thunder_bgx, ver 1.0371machine # [ 0.959568] nicpf, ver 1.0372machine # [ 0.960139] e1000: Intel(R) PRO/1000 Network Driver373machine # [ 0.960143] e1000: Copyright (c) 1999-2006 Intel Corporation.374machine # [ 0.960168] e1000e: Intel(R) PRO/1000 Network Driver375machine # [ 0.960177] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.376machine # [ 0.960205] igb: Intel(R) Gigabit Ethernet Network Driver377machine # [ 0.960209] igb: Copyright (c) 2007-2014 Intel Corporation.378machine # [ 0.960229] igbvf: Intel(R) Gigabit Virtual Function Network Driver379machine # [ 0.960232] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.380machine # [ 0.960369] sky2: driver version 1.30381machine # [ 0.962211] usbcore: registered new interface driver usb-storage382machine # [ 0.962320] usbcore: registered new interface driver usbserial_generic383machine # [ 0.962331] usbserial: USB Serial support registered for generic384machine # [ 0.963293] ehci-pci 0000:00:07.0: EHCI Host Controller385machine # [ 0.963313] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1386machine # [ 0.963606] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000387machine # [ 0.976275] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00388machine # [ 0.976598] hub 1-0:1.0: USB hub found389machine # [ 0.976613] hub 1-0:1.0: 6 ports detected390machine # [ 0.977558] hv_vmbus: registering driver hyperv_keyboard391machine # [ 0.978434] rtc-pl031 9010000.pl031: registered as rtc0392machine # [ 0.981802] rtc-pl031 9010000.pl031: setting system clock to 2026-09-29T03:50:02 UTC (1790653802)393machine # [ 0.982127] i2c_dev: i2c /dev entries driver394machine # [ 0.987246] sdhci: Secure Digital Host Controller Interface driver395machine # [ 0.987252] sdhci: Copyright(c) Pierre Ossman396machine # [ 0.987541] Synopsys Designware Multimedia Card Interface Driver397machine # [ 0.987922] sdhci-pltfm: SDHCI platform and OF driver helper398machine # [ 0.989495] hid: raw HID events driver (C) Jiri Kosina399machine # [ 0.989747] usbcore: registered new interface driver usbhid400machine # [ 0.989756] usbhid: USB HID core driver401machine # [ 0.995841] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available402machine # [ 0.997413] drop_monitor: Initializing network drop monitor service403machine # [ 0.997551] NET: Registered PF_INET6 protocol family404machine # [ 0.998136] Segment Routing with IPv6405machine # [ 0.998148] In-situ OAM (IOAM) with IPv6406machine # [ 0.998175] NET: Registered PF_PACKET protocol family407machine # [ 0.998227] 9pnet: Installing 9P2000 support408machine # [ 0.998264] Key type dns_resolver registered409machine # [ 1.009978] registered taskstats version 1410machine # [ 1.010121] Loading compiled-in X.509 certificates411machine # [ 1.018935] Demotion targets for Node 0: null412machine # [ 1.019051] Key type .fscrypt registered413machine # [ 1.019059] Key type fscrypt-provisioning registered414machine # [ 1.019154] ima: No TPM chip found, activating TPM-bypass!415machine # [ 1.019172] ima: Allocated hash algorithm: sha1416machine # [ 1.019192] ima: No architecture policies found417machine # [ 1.019841] input: gpio-keys as /devices/platform/gpio-keys/input/input0418machine # [ 1.042346] clk: Disabling unused clocks419machine # [ 1.042365] PM: genpd: Disabling unused power domains420machine # [ 1.046768] Freeing unused kernel memory: 4736K421machine # [ 1.046986] Run /init as init process422machine # [ 1.065044] systemd[1]: Successfully made /usr/ read-only.423machine # [ 1.065466] systemd[1]: systemd 263-583679f 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)424machine # [ 1.065481] systemd[1]: Detected virtualization qemu.425machine # [ 1.065519] systemd[1]: Detected architecture arm64.426machine # [ 1.065525] systemd[1]: Running in initrd.427machine # [ 1.065799] systemd[1]: Initializing machine ID from random generator.428machine # [ 1.065894] systemd[1]: Hostname set to <machine>.429machine # [ 1.168267] systemd[1]: bpf-restrict-fs: LSM BPF program attached430machine # [ 1.221372] systemd[1]: Queued start job for default target Initrd Default Target.431machine # [ 1.222527] usb 1-1: new high-speed USB device number 2 using ehci-pci432machine # [ 1.227055] systemd[1]: Created slice Slice /system/modprobe.433machine # [ 1.227234] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.434machine # [ 1.227261] systemd[1]: Expecting device /dev/disk/by-label/nixos...435machine # [ 1.227279] systemd[1]: Reached target Path Units.436machine # [ 1.227293] systemd[1]: Reached target Slice Units.437machine # [ 1.227307] systemd[1]: Reached target Swaps.438machine # [ 1.227319] systemd[1]: Reached target Timer Units.439machine # [ 1.227446] systemd[1]: Listening on D-Bus System Message Bus Socket.440machine # [ 1.227559] systemd[1]: Listening on Journal Socket (/dev/log).441machine # [ 1.227667] systemd[1]: Listening on Journal Sockets.442machine # [ 1.227734] systemd[1]: Listening on udev Kernel Socket.443machine # [ 1.227748] systemd[1]: Reached target Socket Units.444machine # [ 1.229095] systemd[1]: Starting Create List of Static Device Nodes...445machine # [ 1.229148] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs446machine # [ 1.249304] systemd[1]: Mounting Kernel Configuration File System...447machine # [ 1.270644] systemd[1]: Starting Journal Service...448machine # [ 1.281138] systemd[1]: Starting Load Kernel Modules...449machine # [ 1.281249] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os450machine # [ 1.286930] systemd[1]: Starting Coldplug All udev Devices...451machine # [ 1.299971] systemd[1]: Finished Create List of Static Device Nodes.452machine # [ 1.300626] systemd[1]: Mounted Kernel Configuration File System.453machine # [ 1.315023] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...454machine # [ 1.344759] systemd-journald[71]: Collecting audit messages is disabled.455machine # [ 1.360993] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.456machine # [ 1.377451] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev457machine # [ 1.384857] 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/input1458machine # [ 1.389140] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.459machine # [ 1.391839] systemd[1]: Starting Create Static Device Nodes in /dev...460machine # [ 1.400189] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0461machine # [ 1.400434] [drm] features: -virgl +edid -resource_blob -host_visible462machine # [ 1.400437] [drm] features: -context_init463machine # [ 1.401185] [drm] number of scanouts: 1464machine # [ 1.401196] [drm] number of cap sets: 0465machine # [ 1.427013] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic466machine # [ 1.427028] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0467machine # [ 1.455279] Console: switching to colour frame buffer device 160x50468machine # [ 1.461572] systemd[1]: Finished Create Static Device Nodes in /dev.469machine # [ 1.461949] systemd[1]: Reached target Preparation for Local File Systems.470machine # [ 1.461969] systemd[1]: Reached target Local File Systems.471machine # [ 1.466757] systemd[1]: Starting Rule-based Manager for Device Events and Files...472machine # [ 1.479129] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device473machine # [ 1.482648] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0474machine # [ 1.502738] systemd[1]: Finished Load Kernel Modules.475machine # [ 1.504621] systemd[1]: Starting Apply Kernel Variables...476machine # [ 1.528547] systemd-modules-load[72]: Inserted module 'dm_mod'477machine # [ 1.544303] systemd[1]: Started Journal Service.478machine # [ 1.533387] systemd-modules-load[72]: Module 'virtio_balloon' is built in479machine # [ 1.545340] systemd-modules-load[72]: Module 'virtio_console' is built in480machine # [ 1.551749] systemd-modules-load[72]: Inserted module 'virtio_gpu'481machine # [ 1.557187] systemd-modules-load[72]: Module 'virtio_rng' is built in482machine # [ 1.558263] systemd[1]: Starting Create System Files and Directories...483machine # [ 1.569207] systemd[1]: Finished Apply Kernel Variables.484machine # [ 1.572582] systemd-udevd[78]: Using default interface naming scheme 'v261'.485machine # [ 1.602597] usb 1-2: new high-speed USB device number 3 using ehci-pci486machine # [ 1.608569] systemd[1]: Finished Create System Files and Directories.487machine # [ 1.620609] systemd[1]: Started Rule-based Manager for Device Events and Files.488machine # [ 1.711214] systemd[1]: Starting Virtual Console Setup...489machine # [ 1.763641] 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/input2490machine # [ 1.763923] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0491machine # [ 1.805230] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.492machine # [ 1.812394] systemd[1]: Finished Virtual Console Setup.493machine # [ 2.482748] systemd[1]: Finished Coldplug All udev Devices.494machine # [ 2.483959] systemd[1]: Reached target System Initialization.495machine # [ 2.488182] systemd[1]: Reached target Basic System.496machine # [ 2.657871] (udev-worker)[95]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.497machine # [ 2.661898] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.498machine # [ 2.717035] systemd[1]: Found device /dev/disk/by-label/nixos.499machine # [ 2.719443] systemd[1]: Reached target Initrd Root Device.500machine # [ 2.723288] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...501machine # [ 2.775991] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks502machine # [ 2.784105] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.503machine # [ 2.795456] systemd[1]: Mounting /sysroot...504machine # [ 2.831353] systemd[1]: Mounted /sysroot.505machine # [ 2.843000] EXT4-fs (vda): mounted filesystem aa128b2f-7bff-4ea1-ab97-4611d3575897 r/w with ordered data mode. Quota mode: none.506machine # [ 2.836891] systemd[1]: Reached target Initrd Root File System.507machine # [ 2.840499] systemd[1]: Starting Mountpoints Configured in the Real Root...508machine # [ 2.866830] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.509machine # [ 2.872116] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...510machine # [ 2.877210] systemd[1]: Reloading...511machine # [ 3.068119] systemd[1]: Reloading finished in 192 ms.512machine # [ 3.091248] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...513machine # [ 3.095596] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...514machine # [ 3.103108] systemd[1]: initrd-parse-etc.service: Deactivated successfully.515machine # [ 3.106396] systemd[1]: Finished Mountpoints Configured in the Real Root.516machine # [ 3.108792] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.517machine # [ 3.294632] systemd[1]: Mounting /sysroot/nix/.ro-store...518machine # [ 3.312257] systemd[1]: Mounting /sysroot/nix/.rw-store...519machine # [ 3.316530] systemd[1]: Mounting /sysroot/run...520machine # [ 3.334687] systemd[1]: Mounting /sysroot/tmp/shared...521machine # [ 3.371717] systemd[1]: Mounting /sysroot/tmp/xchg...522machine # [ 3.431384] fuse: init (API version 7.45)523machine # [ 3.438612] virtiofs virtio6: discovered new tag: nix-store524machine # [ 3.439487] virtiofs virtio6: virtio_fs_setup_dax: No cache capability525machine # [ 3.435124] systemd[1]: Mounted /sysroot/nix/.rw-store.526machine # [ 3.456571] virtiofs virtio7: discovered new tag: shared527machine # [ 3.457379] virtiofs virtio7: virtio_fs_setup_dax: No cache capability528machine # [ 3.467621] virtiofs virtio8: discovered new tag: xchg529machine # [ 3.468453] virtiofs virtio8: virtio_fs_setup_dax: No cache capability530machine # [ 3.458688] systemd[1]: Mounted /sysroot/run.531machine # [ 3.474718] systemd[1]: Starting rw-sysroot-nix-store.service...532machine # [ 3.486348] systemd[1]: Mounted /sysroot/nix/.ro-store.533machine # [ 3.494863] systemd[1]: Mounted /sysroot/tmp/shared.534machine # [ 3.497816] systemd[1]: Mounted /sysroot/tmp/xchg.535machine # [ 3.507802] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.536machine # [ 3.509672] systemd[1]: Finished rw-sysroot-nix-store.service.537machine # [ 4.036813] (udev-worker)[98]: mtd0ro: Failed to find and pin callout binary "/nix/store/xv59xsz850c5vh4bpnm18knf45vr4kwl-systemd-263-583679f/lib/udev/mtd_probe": No such file or directory538machine # [ 4.047043] (udev-worker)[98]: 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 directory539machine # [ 4.083428] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.540machine # [ 4.086094] systemd[1]: Stopped Virtual Console Setup.541machine # [ 4.088163] systemd[1]: Stopping Virtual Console Setup...542machine # [ 4.092356] systemd[1]: Starting Virtual Console Setup...543machine # [ 4.114672] systemd-vconsole-setup[152]: Configuration of first virtual console was skipped, ignoring remaining ones.544machine # [ 4.117623] systemd[1]: Finished Virtual Console Setup.545machine # [ 4.292321] systemd[1]: Mounting /sysroot/nix/store...546machine # [ 4.336957] systemd[1]: Mounted /sysroot/nix/store.547machine # [ 4.340224] systemd[1]: Reached target Initrd File Systems.548machine # [ 4.343836] systemd[1]: Starting Find NixOS closure...549machine # [ 4.352536] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...550machine # [ 4.393538] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.551machine # [ 4.394980] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.552machine # [ 4.401632] systemd[1]: Finished Find NixOS closure.553machine # [ 4.404221] systemd[1]: Reached target Initrd Default Target.554machine # [ 4.407020] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...555machine # [ 4.435427] systemd[1]: Stopped target Initrd Default Target.556machine # [ 4.437258] systemd[1]: Stopped target Basic System.557machine # [ 4.440522] systemd[1]: Stopped target Initrd Root Device.558machine # [ 4.441560] systemd[1]: Stopped target Path Units.559machine # [ 4.442691] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.560machine # [ 4.445720] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.561machine # [ 4.447895] systemd[1]: Stopped target Slice Units.562machine # [ 4.452387] systemd[1]: Stopped target Socket Units.563machine # [ 4.453372] systemd[1]: Stopped target System Initialization.564machine # [ 4.454258] systemd[1]: Stopped target Swaps.565machine # [ 4.455016] systemd[1]: Stopped target Timer Units.566machine # [ 4.455785] systemd[1]: dbus.socket: Deactivated successfully.567machine # [ 4.461425] systemd[1]: Closed D-Bus System Message Bus Socket.568machine # [ 4.464915] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.569machine # [ 4.469257] systemd[1]: Stopped Find NixOS closure.570machine # [ 4.473083] systemd[1]: Starting rw-sysroot-nix-store.service...571machine # [ 4.476229] systemd[1]: systemd-sysctl.service: Deactivated successfully.572machine # [ 4.482460] systemd[1]: Stopped Apply Kernel Variables.573machine # [ 4.484599] systemd[1]: systemd-modules-load.service: Deactivated successfully.574machine # [ 4.487281] systemd[1]: Stopped Load Kernel Modules.575machine # [ 4.494792] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.576machine # [ 4.497908] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.577machine # [ 4.501642] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.578machine # [ 4.506757] systemd[1]: Stopped Create System Files and Directories.579machine # [ 4.512462] systemd[1]: Stopped target Local File Systems.580machine # [ 4.513347] systemd[1]: Stopped target Preparation for Local File Systems.581machine # [ 4.514464] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.582machine # [ 4.515524] systemd[1]: Stopped Coldplug All udev Devices.583machine # [ 4.517432] systemd[1]: Stopping Rule-based Manager for Device Events and Files...584machine # [ 4.518539] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.585machine # [ 4.519623] systemd[1]: Stopped Virtual Console Setup.586machine # [ 4.525627] systemd[1]: initrd-cleanup.service: Deactivated successfully.587machine # [ 4.527721] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.588machine # [ 4.532605] systemd[1]: systemd-udevd.service: Deactivated successfully.589machine # [ 4.533623] systemd[1]: Stopped Rule-based Manager for Device Events and Files.590machine # [ 4.534667] systemd[1]: systemd-udevd.service: Consumed 1.556s CPU time over 3.045s wall clock time, 22.1M memory peak.591machine # [ 4.540237] systemd[1]: Starting Cleanup udev Database...592machine # [ 4.541179] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.593machine # [ 4.542498] systemd[1]: Stopped Create Static Device Nodes in /dev.594machine # [ 4.543457] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.595machine # [ 4.548577] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.596machine # [ 4.552452] systemd[1]: kmod-static-nodes.service: Deactivated successfully.597machine # [ 4.553637] systemd[1]: Stopped Create List of Static Device Nodes.598machine # [ 4.554612] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.599machine # [ 4.560111] systemd[1]: Finished rw-sysroot-nix-store.service.600machine # [ 4.579650] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.601machine # [ 4.582900] systemd[1]: Finished Cleanup udev Database.602machine # [ 4.588149] systemd[1]: Reached target Switch Root.603machine # [ 4.589075] systemd[1]: Starting NixOS Activation...604machine # [ 4.672629] initrd-nixos-activation-start[175]: booting system configuration /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test605machine # [ 4.711139] initrd-nixos-activation-start[175]: running activation script...606machine # [ 4.957163] initrd-nixos-activation-start[198]: setting up /etc...607machine # [ 5.078775] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.608machine # [ 5.082099] systemd[1]: Finished NixOS Activation.609machine # [ 5.083585] systemd[1]: Starting Switch Root...610machine # [ 5.109936] systemd[1]: Switching root.611machine # [ 5.296126] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).612machine # [ 5.458022] systemd[1]: systemd 263-583679f 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)613machine # [ 5.466539] systemd[1]: Detected virtualization qemu.614machine # [ 5.467241] systemd[1]: Detected architecture arm64.615machine # [ 5.467776] systemd[1]: Detected first boot.616machine # [ 5.469718] systemd[1]: Initializing machine ID from random generator.617machine # [ 5.598432] systemd[1]: bpf-restrict-fs: LSM BPF program attached618machine # [ 5.750775] systemd[1]: Applying preset policy.619machine # [ 5.946794] systemd[1]: Populated /etc with preset unit settings.620machine # [ 6.136568] systemd[1]: initrd-switch-root.service: Deactivated successfully.621machine # [ 6.137380] systemd[1]: Stopped initrd-switch-root.service.622machine # [ 6.141980] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.623machine # [ 6.145999] systemd[1]: Created slice Slice /system/getty.624machine # [ 6.149007] systemd[1]: Created slice User and Session Slice.625machine # [ 6.149573] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.626machine # [ 6.150193] systemd[1]: Started Forward Password Requests to Wall Directory Watch.627machine # [ 6.153604] systemd[1]: Expecting device /dev/hvc0...628machine # [ 6.154203] systemd[1]: Expecting device /dev/ttyAMA0...629machine # [ 6.156338] systemd[1]: Reached target Local Encrypted Volumes.630machine # [ 6.156978] systemd[1]: Stopped target initrd-fs.target.631machine # [ 6.157609] systemd[1]: Stopped target initrd-root-fs.target.632machine # [ 6.158248] systemd[1]: Stopped target initrd-switch-root.target.633machine # [ 6.161883] systemd[1]: Reached target Virtual Machines and Containers.634machine # [ 6.164104] systemd[1]: Reached target Path Units.635machine # [ 6.164503] systemd[1]: Reached target Remote File Systems.636machine # [ 6.165154] systemd[1]: Reached target Slice Units.637machine # [ 6.165805] systemd[1]: Reached target Swaps.638machine # [ 6.170967] systemd[1]: Listening on Query the User Interactively for a Password.639machine # [ 6.172757] systemd[1]: Listening on Process Core Dump Socket.640machine # [ 6.174002] systemd[1]: Listening on Credential Encryption/Decryption.641machine # [ 6.178382] systemd[1]: Listening on Factory Reset Management.642machine # [ 6.179782] systemd[1]: Listening on Hostname Service Socket.643machine # [ 6.183634] systemd[1]: Starting Journal Log Access Socket...644machine # [ 6.184934] systemd[1]: Listening on Journal Audit Socket.645machine # [ 6.188698] systemd[1]: Listening on Console Output Muting Service Socket.646machine # [ 6.191523] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.647machine # [ 6.191994] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os648machine # [ 6.192636] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki649machine # [ 6.199744] systemd[1]: Listening on Disk Repartitioning Service Socket.650machine # [ 6.200322] systemd[1]: Listening on udev Varlink Socket.651machine # [ 6.204842] systemd[1]: Mounting Huge Pages File System...652machine # [ 6.209169] systemd[1]: Mounting POSIX Message Queue File System...653machine # [ 6.219224] systemd[1]: Mounting Kernel Debug File System...654machine # [ 6.232787] systemd[1]: Mounting Kernel Trace File System...655machine # [ 6.247887] systemd[1]: Starting Create List of Static Device Nodes...656machine # [ 6.249335] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs657machine # [ 6.266115] systemd[1]: Mounting Kernel Configuration File System...658machine # [ 6.270073] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm659machine # [ 6.279128] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore660machine # [ 6.279505] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse661machine # [ 6.313274] systemd[1]: Mounting FUSE Control File System...662machine # [ 6.313710] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67663machine # [ 6.339160] systemd[1]: Starting Journal Service...664machine # [ 6.353338] systemd[1]: Starting Load Kernel Modules...665machine # [ 6.385509] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...666machine # [ 6.404959] systemd[1]: Starting Remount Root and Kernel File Systems...667machine # [ 6.405364] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os668machine # [ 6.416045] systemd-journald[269]: Collecting audit messages is enabled.669machine # [ 6.410185] systemd[1]: Queued start job for default target Multi-User System.670machine # [ 6.416639] systemd[1]: systemd-journald.service: Deactivated successfully.671machine # [ 6.433652] systemd[1]: Starting Coldplug All udev Devices...672machine # [ 6.422280] systemd-modules-load[270]: Module 'atkbd' is built in673machine # [ 6.441134] systemd[1]: Started Journal Service.674machine # [ 6.430612] systemd-modules-load[270]: Module 'loop' is built in675machine # [ 6.437564] systemd[1]: Listening on Journal Log Access Socket.676machine # [ 6.442862] systemd[1]: Mounted Huge Pages File System.677machine # [ 6.443758] systemd[1]: Mounted POSIX Message Queue File System.678machine # [ 6.451421] systemd[1]: Mounted Kernel Debug File System.679machine # [ 6.454572] systemd[1]: Mounted Kernel Trace File System.680machine # [ 6.455419] systemd[1]: Finished Create List of Static Device Nodes.681machine # [ 6.460817] systemd[1]: Finished Load Kernel Modules.682machine # [ 6.493637] systemd[1]: Mounted FUSE Control File System.683machine # [ 6.500902] systemd[1]: Mounted Kernel Configuration File System.684machine # [ 6.516848] EXT4-fs (vda): re-mounted aa128b2f-7bff-4ea1-ab97-4611d3575897.685machine # [ 6.512953] systemd[1]: Starting Firewall...686machine # [ 6.521676] systemd[1]: Starting Apply Kernel Variables...687machine # [ 6.530135] systemd-oomd[272]: No swap; memory pressure usage will be degraded688machine # [ 6.531618] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...689machine # [ 6.536622] systemd[1]: Finished Remount Root and Kernel File Systems.690machine # [ 6.537625] systemd[1]: Listening on Disk Image Download Service Socket.691machine # [ 6.556263] systemd[1]: Starting Flush Journal to Persistent Storage...692machine # [ 6.557501] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore693machine # [ 6.579254] systemd[1]: Starting Load/Save OS Random Seed...694machine # [ 6.588645] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os695machine # [ 6.589999] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.696machine # [ 6.678446] systemd-journald[269]: Received client request to flush runtime journal.697machine # [ 6.752629] systemd[1]: Finished Load/Save OS Random Seed.698machine # [ 6.753645] systemd[1]: Reached target First Boot Complete.699machine # [ 6.754495] systemd[1]: Finished Apply Kernel Variables.700machine # [ 6.764797] systemd[1]: Finished Flush Journal to Persistent Storage.701machine # [ 6.782615] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.702machine # [ 6.784806] systemd[1]: Starting Create Static Device Nodes in /dev...703machine # [ 6.953026] systemd[1]: Finished Create Static Device Nodes in /dev.704machine # [ 6.956146] systemd[1]: Reached target Preparation for Local File Systems.705machine # [ 6.960204] systemd[1]: Starting Rule-based Manager for Device Events and Files...706machine # [ 7.111026] systemd-udevd[309]: Using default interface naming scheme 'v261'.707machine # [ 7.129319] systemd[1]: Mounting /run/wrappers...708machine # [ 7.192936] systemd[1]: Mounted /run/wrappers.709machine # [ 7.193802] systemd[1]: Reached target Local File Systems.710machine # [ 7.200193] systemd[1]: Listening on Boot Loader Control Service Socket.711machine # [ 7.204539] systemd[1]: Starting Create SUID/SGID Wrappers...712machine # [ 7.205535] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.713machine # [ 7.216174] systemd[1]: Starting Save Transient machine-id to Disk...714machine # [ 7.224217] systemd[1]: Starting Create System Files and Directories...715machine # [ 7.250505] systemd[1]: Started Rule-based Manager for Device Events and Files.716machine # [ 7.375829] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.717machine # [ 7.388156] systemd[1]: Finished Save Transient machine-id to Disk.718machine # [ 7.443807] systemd[1]: Finished Create System Files and Directories.719machine # [ 7.453156] systemd[1]: Starting Rebuild Journal Catalog...720machine # [ 7.468354] systemd[1]: Starting Record System Boot/Shutdown in UTMP...721machine # [ 7.569567] systemd[1]: Finished Record System Boot/Shutdown in UTMP.722machine # [ 7.602867] systemd[1]: Finished Rebuild Journal Catalog.723machine # [ 7.608724] systemd[1]: Starting Update is Completed...724machine # [ 7.672571] systemd[1]: Finished Update is Completed.725machine # [ 8.121234] systemd[1]: Finished Coldplug All udev Devices.726machine # [ 8.185307] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.727machine # [ 8.189498] systemd[1]: Finished Create SUID/SGID Wrappers.728machine # [ 8.195171] systemd[1]: Reached target System Initialization.729machine # [ 8.197347] systemd[1]: Started Discard unused filesystem blocks once a week.730machine # [ 8.198437] systemd[1]: Started Daily Cleanup of Temporary Directories.731machine # [ 8.199434] systemd[1]: Reached target Timer Units.732machine # [ 8.206351] systemd[1]: Listening on D-Bus System Message Bus Socket.733machine # [ 8.207339] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.734machine # [ 8.215925] systemd[1]: Reached target Socket Units.735machine # [ 8.221416] systemd[1]: Reached target Basic System.736machine # [ 8.222244] systemd[1]: Starting Import lastlog data into lastlog2 database...737machine # [ 8.223452] systemd[1]: Starting Name Service Cache Daemon (nsncd)...738machine # [ 8.229827] systemd[1]: Starting Post-Boot Actions...739machine # [ 8.235739] systemd[1]: Started Reset console on configuration changes.740machine # [ 8.250163] systemd[1]: Starting resolvconf update...741machine # [ 8.340756] systemd[1]: Starting D-Bus System Message Bus...742machine # [ 8.341831] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs743machine # [ 8.343502] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse744machine # [ 8.393805] nsncd[428]: Sep 29 03:50:09.925 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"745machine # [ 8.411582] systemd[1]: Started Name Service Cache Daemon (nsncd).746machine # [ 8.422892] systemd[1]: Reached target Host and Network Name Lookups.747machine # [ 8.423907] systemd[1]: Reached target User and Group Name Lookups.748machine # [ 8.433630] systemd[1]: Starting User Login Management...749machine # [ 8.434521] systemd[1]: Finished Post-Boot Actions.750machine # [ 8.542101] systemd[1]: Finished Import lastlog data into lastlog2 database.751machine # [ 8.560506] dbus-broker-launch[438]: Looking up NSS user entry for 'systemd-timesync'...752machine # [ 8.570609] dbus-broker-launch[438]: NSS returned no entry for 'systemd-timesync'753machine # [ 8.571800] dbus-broker-launch[438]: Invalid user-name in /nix/store/n9gdx9aa5f6rhwnpm4q6k6k9ys4c0vjz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"754machine # [ 8.630364] systemd[1]: Started D-Bus System Message Bus.755machine # [ 8.677071] dbus-broker-launch[438]: Ready756machine # [ 8.703648] systemd-logind[453]: New seat seat0.757machine # [ 8.715957] systemd[1]: Started User Login Management.758machine # [ 8.723230] systemd[1]: Starting linger-users.service...759machine # [ 8.786818] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.760machine # [ 8.807030] systemd[1]: Stopped target Host and Network Name Lookups.761machine # [ 8.812283] systemd[1]: Stopping Host and Network Name Lookups...762machine # [ 8.813422] systemd[1]: Stopped target User and Group Name Lookups.763machine # [ 8.814361] systemd[1]: Stopping User and Group Name Lookups...764machine # [ 8.815225] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...765machine # [ 8.835274] systemd[1]: nscd.service: Deactivated successfully.766machine # [ 8.840827] systemd[1]: Stopped Name Service Cache Daemon (nsncd).767machine # [ 8.917231] systemd[1]: Created slice Slice /user/1001.768machine # [ 8.918247] systemd[1]: Starting Name Service Cache Daemon (nsncd)...769machine # [ 8.977239] systemd[1]: Starting User Runtime Directory /run/user/1001...770machine # [ 8.978444] systemd[1]: linger-users.service: Deactivated successfully.771machine # [ 8.979531] systemd[1]: Finished linger-users.service.772machine # [ 9.033651] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.773machine # [ 9.054516] systemd[1]: Started backdoor.service.774machine # [ 9.072837] (udev-worker)[329]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.775machine # [ 9.134497] (udev-worker)[329]: Network interface NamePolicy= disabled on kernel command line.776machine # [ 9.135953] systemd[1]: Finished User Runtime Directory /run/user/1001.777machine # [ 9.200598] systemd[1]: Starting User Manager for UID 1001...778machine # [ 9.225622] (systemd)[544]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)779machine # [ 9.268604] systemd-logind[453]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.780machine # [ 9.333645] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy781machine # [ 9.636685] nsncd[531]: Sep 29 03:50:11.031 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"782machine # connecting to host...783machine # [ 9.678473] systemd[1]: Condition check resulted in Virtio network device being skipped.784machine # [ 9.679785] systemd[1]: Finished resolvconf update.785machine # [ 9.696260] systemd-logind[453]: Watching system buttons on /dev/input/event0 (gpio-keys)786machine # [ 9.697651] systemd[1]: Starting DHCP Client...787machine # [ 9.698337] systemd[1]: Started Name Service Cache Daemon (nsncd).788machine # [ 9.699245] systemd[1]: Reached target Host and Network Name Lookups.789machine # [ 9.730617] mousedev: PS/2 mouse device common for all mice790machine # [ 9.769224] systemd[1]: Reached target User and Group Name Lookups.791machine # [ 9.770351] systemd[544]: Queued start job for default target Main User Target.792machine # [ 9.771547] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy793machine: Guest shell says: b'Spawning backdoor root shell...\n'794machine: connected to guest root shell795machine # [ 9.828272] systemd[544]: Created slice User Application Slice.796machine: (connecting took 10.08 seconds)797machine # [ 9.829260] systemd[544]: Started Daily Cleanup of User's Temporary Directories.798machine: (finished: waiting for the VM to finish booting, in 10.59 seconds)799machine # [ 9.830255] systemd[544]: Reached target Paths.800machine # [ 9.830897] systemd[544]: Reached target Timers.801machine # [ 9.875853] systemd[544]: Starting D-Bus User Message Bus Socket...802machine # [ 9.894091] systemd[544]: Starting Create User Files and Directories...803machine # [ 9.895128] systemd[544]: Finished Create User Files and Directories.804machine # [ 9.931003] systemd[544]: Listening on D-Bus User Message Bus Socket.805machine # [ 9.943781] systemd[544]: Reached target Sockets.806machine # [ 9.956767] systemd[544]: Reached target Basic System.807machine # [ 9.957579] systemd[1]: Started User Manager for UID 1001.808machine # [ 9.958378] systemd[544]: Starting Run user-specific NixOS activation...809machine # [ 9.959346] systemd[544]: Finished Run user-specific NixOS activation.810machine # [ 10.009446] systemd[544]: Reached target Main User Target.811machine # [ 10.010370] systemd[544]: Startup finished in 499ms.812machine # [ 10.011352] systemd-logind[453]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)813machine # [ 10.123175] systemd[1]: Finished Firewall.814machine # [ 10.136332] systemd[1]: Reached target Preparation for Network.815machine # [ 10.137348] systemd[1]: Starting Address configuration of eth1...816machine # [ 10.157136] systemd[1]: Starting Extra networking commands....817machine # [ 10.485303] network-addresses-eth1-start[602]: adding address 192.168.1.1/24... done818machine # [ 10.506130] dhcpcd[596]: dhcpcd-10.3.2 starting819machine # [ 10.530143] dhcpcd[633]: dev: loaded udev820machine # [ 10.560122] network-addresses-eth1-start[602]: adding address 2001:db8:1::1/64... done821machine # [ 10.587187] 8021q: 802.1Q VLAN Support v1.8822machine # [ 10.587553] 8021q: adding VLAN 0 to HW filter on device eth1823machine # [ 10.697627] cfg80211: Loading compiled-in X.509 certificates for regulatory database824machine # [ 10.718574] systemd[1]: Finished Address configuration of eth1.825machine # [ 10.757721] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'826machine # [ 10.758219] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'827machine # [ 10.761676] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2828machine # [ 10.761989] cfg80211: failed to load regulatory.db829machine # [ 11.005632] dhcpcd[668]: /nix/store/1ki7bgdr0niilkg9jbkpcdaggbv0kb8a-openresolv-3.17.4/sbin/.resolvconf-wrapped: line 1250: kill: (611) - Operation not permitted830machine # [ 11.007624] dhcpcd[668]: .resolvconf-wrapped: clearing stale lock pid 611831machine # [ 11.104570] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3832machine # [ 11.278877] systemd[1]: Finished Extra networking commands..833machine # [ 11.279883] systemd[1]: Reached target Network.834machine # [ 11.295430] 8021q: adding VLAN 0 to HW filter on device eth0835machine # [ 11.303739] systemd[1]: Starting Permit User Sessions...836machine # [ 11.328379] dhcpcd[633]: libudev: received NULL device837machine # [ 11.329259] dhcpcd[633]: eth0: waiting for carrier838machine # [ 11.329993] dhcpcd[633]: libudev: received NULL device839machine # [ 11.330873] dhcpcd[633]: eth0: carrier acquired840machine # [ 11.409225] dhcpcd[633]: DUID 00:01:00:01:32:4d:f1:f4:52:54:00:12:34:56841machine # [ 11.410337] dhcpcd[633]: eth0: IAID 00:12:34:56842machine # [ 11.411017] dhcpcd[633]: eth0: adding address fe80::5054:ff:fe12:3456843machine # [ 11.545807] systemd[1]: Starting Virtual Console Setup...844machine # [ 11.568868] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.845machine # [ 11.585472] systemd[1]: Finished Permit User Sessions.846machine # [ 11.586386] systemd[1]: Started Getty on tty1.847machine # [ 11.587061] systemd[1]: Reached target Login Prompts.848machine # [ 11.626851] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.849machine # [ 11.640379] systemd[1]: Stopped Virtual Console Setup.850machine # [ 11.647650] systemd[1]: Starting Virtual Console Setup...851machine # [ 11.655749] systemd-logind[453]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)852machine # [ 11.760942] systemd-vconsole-setup[711]: Configuration of first virtual console was skipped, ignoring remaining ones.853machine # [ 11.768214] systemd[1]: Finished Virtual Console Setup.854machine # [ 12.865671] dhcpcd[633]: eth0: soliciting a DHCP lease855machine # [ 12.868819] dhcpcd[633]: eth0: offered 10.0.2.15 from 10.0.2.2856machine # [ 12.876615] dhcpcd[633]: eth0: probing address 10.0.2.15/24857machine # [ 13.403682] dhcpcd[633]: eth0: soliciting an IPv6 router858machine # [ 13.406497] dhcpcd[633]: eth0: Router Advertisement from fe80::2859machine # [ 13.408140] dhcpcd[633]: eth0: adding address fec0::5054:ff:fe12:3456/64860machine # [ 13.409865] dhcpcd[633]: eth0: adding route to fec0::/64861machine # [ 13.411147] dhcpcd[633]: eth0: adding default route via fe80::2862machine # [ 18.006472] dhcpcd[633]: eth0: leased 10.0.2.15 for 86400 seconds863machine # [ 18.006732] dhcpcd[633]: eth0: adding route to 10.0.2.0/24864machine # [ 18.006814] dhcpcd[633]: eth0: adding default route via 10.0.2.2865machine # [ 18.177838] systemd[1]: Started DHCP Client.866machine # [ 18.179853] systemd[1]: Reached target Multi-User System.867machine # [ 18.181189] systemd[1]: Startup finished in 1.037s (kernel) + 4.320s (initrd) + 12.821s (userspace) = 18.179s.868machine: (finished: waiting for unit multi-user.target, in 19.95 seconds)869machine: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/bin/switch-to-configuration test870machine # Checking switch inhibitors... done871machine # [ 19.388159] nixos[839]: switching to system configuration /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test872machine # [ 19.391065] systemd[1]: Stopped target Remote File Systems.873machine # activating the configuration...874machine # [ 19.394366] systemd[1]: Stopped target Local File Systems.875machine # setting up /etc...876machine # [ 19.671839] systemd[1]: Reload requested from client PID 839 ('.switch-to-conf') (unit backdoor.service)...877machine # [ 19.674099] systemd[1]: Reloading...878machine # [ 19.979407] systemd[1]: Reloading finished in 303 ms.879machine # reloading user units for usertest...880machine # [ 20.031071] systemd[544]: Created slice User Core Session Slice.881machine # [ 20.034150] systemd[544]: Starting D-Bus User Message Bus...882machine # [ 20.066364] dbus-broker-launch[909]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/n9gdx9aa5f6rhwnpm4q6k6k9ys4c0vjz-system-path/share/dbus-1/services/org.freedesktop.import1.service'883machine # [ 20.069712] dbus-broker-launch[909]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/n9gdx9aa5f6rhwnpm4q6k6k9ys4c0vjz-system-path/share/dbus-1/services/org.freedesktop.machine1.service'884machine # [ 20.072718] dbus-broker-launch[909]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/n9gdx9aa5f6rhwnpm4q6k6k9ys4c0vjz-system-path/share/dbus-1/services/org.freedesktop.portable1.service'885machine # [ 20.075645] dbus-broker-launch[909]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/n9gdx9aa5f6rhwnpm4q6k6k9ys4c0vjz-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'886machine # [ 20.080341] dbus-broker-launch[909]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored887machine # [ 20.082104] dbus-broker-launch[909]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored888machine # [ 20.083804] systemd[544]: Started D-Bus User Message Bus.889machine # [ 20.088374] dbus-broker-launch[909]: Ready890machine # [ 20.127009] systemd[544]: Reexecution requested from client PID 908 ('switch-to-confi')...891machine # [ 20.131126] systemd[544]: Reexecuting.892machine # [ 20.191370] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy893machine # [ 20.304696] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy894machine # [ 20.311826] systemd[544]: Reload requested from client PID 908 ('switch-to-confi')...895machine # [ 20.315364] systemd[544]: Reloading...896machine # [ 20.418597] systemd[544]: Reloading finished in 104 ms.897machine # [ 20.441807] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy898machine # restarting the following user units: nixos-activation.service899machine # [ 20.450769] systemd[544]: Stopped Run user-specific NixOS activation.900machine # [ 20.453340] systemd[544]: Stopping Run user-specific NixOS activation...901machine # [ 20.455557] systemd[544]: Starting Run user-specific NixOS activation...902machine # [ 20.487817] systemd[544]: Finished Run user-specific NixOS activation.903machine # restarting sysinit-reactivation.target904machine # [ 20.499317] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....905machine # [ 20.592524] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..906machine # [ 20.596939] systemd[1]: Reached target Reactivate sysinit units.907machine # [ 20.600348] systemd[1]: Reached target Remote File Systems.908machine # [ 20.604856] systemd[1]: Starting linger-users.service...909machine # [ 20.616534] systemd[1]: Reached target Local File Systems.910machine # [ 20.629259] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore911machine # [ 20.630974] systemd[1]: Starting Create SUID/SGID Wrappers...912machine # [ 20.631848] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.913machine # [ 20.644737] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67914machine # [ 20.647188] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore915machine # [ 20.650381] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os916machine # [ 20.651747] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os917machine # [ 20.660943] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os918machine # [ 20.662419] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki919machine # [ 20.712572] systemd[1]: linger-users.service: Deactivated successfully.920machine # [ 20.713731] systemd[1]: Finished linger-users.service.921machine # [ 20.877785] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.922machine # [ 20.881571] systemd[1]: Finished Create SUID/SGID Wrappers.923machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service924machine # [ 21.140492] nixos[839]: finished switching to system configuration /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test925machine: (finished: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/bin/switch-to-configuration test, in 1.96 seconds)926machine: must succeed: rm /etc/NIXOS927machine: (finished: must succeed: rm /etc/NIXOS, in 0.02 seconds)928machine: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/y9v5acm6cxjwf9wq5327x1qz6yz5rk47-nixos-system-other-test/bin/switch-to-configuration test929machine # this should succeed (config: /nix/store/y9v5acm6cxjwf9wq5327x1qz6yz5rk47-nixos-system-other-test, action: test)930machine # Checking switch inhibitors... done931machine # [ 21.379557] nixos[987]: switching to system configuration /nix/store/y9v5acm6cxjwf9wq5327x1qz6yz5rk47-nixos-system-other-test932machine # [ 21.382271] systemd[1]: Stopped target Local File Systems.933machine # activating the configuration...934machine # [ 21.384954] systemd[1]: Stopped target Remote File Systems.935machine # removing user ‘usertest’936machine # setting up /etc...937machine # [ 21.663677] systemd[1]: Reload requested from client PID 987 ('.switch-to-conf') (unit backdoor.service)...938machine # [ 21.665976] systemd[1]: Reloading...939machine # [ 21.978582] systemd[1]: Reloading finished in 311 ms.940machine # reloading user units for usertest...941machine # [ 22.067796] systemd[544]: Reexecution requested from client PID 1058 ('switch-to-confi')...942machine # [ 22.072820] systemd[544]: Reexecuting.943machine # [ 22.132149] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy944machine # [ 22.249477] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy945machine # [ 22.256005] systemd[544]: Reload requested from client PID 1058 ('switch-to-confi')...946machine # [ 22.259767] systemd[544]: Reloading...947machine # [ 22.365279] systemd[544]: Reloading finished in 105 ms.948machine # [ 22.380193] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy949machine # restarting the following user units: nixos-activation.service950machine # [ 22.391966] systemd[544]: Stopped Run user-specific NixOS activation.951machine # [ 22.393077] systemd[544]: Stopping Run user-specific NixOS activation...952machine # [ 22.396152] systemd[544]: Starting Run user-specific NixOS activation...953machine # [ 22.423950] systemd[544]: Finished Run user-specific NixOS activation.954machine # restarting sysinit-reactivation.target955machine # [ 22.429125] systemd[1]: Stopped target Reactivate sysinit units.956machine # [ 22.430171] systemd[1]: Stopping Reactivate sysinit units...957machine # [ 22.431030] systemd[1]: Reached target Reactivate sysinit units.958machine # reloading the following units: dbus-broker.service959machine # [ 22.435613] dbus-broker-launch[438]: Caught SIGHUP, trigger reload.960machine # [ 22.438121] systemd[1]: Reloading D-Bus System Message Bus...961machine # [ 22.442890] dbus-broker-launch[438]: Looking up NSS user entry for 'systemd-timesync'...962machine # [ 22.445443] dbus-broker-launch[438]: NSS returned no entry for 'systemd-timesync'963machine # [ 22.446552] dbus-broker-launch[438]: Invalid user-name in /nix/store/yjpadarjghmqr4jbyr5h20nranfk3nc8-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"964machine # [ 22.461922] systemd[1]: Reloaded D-Bus System Message Bus.965machine # restarting the following units: network-addresses-eth1.service966machine # [ 22.468951] systemd[1]: Stopping Address configuration of eth1...967machine # [ 22.499313] network-addresses-eth1-pre-stop[1070]: deleting address 192.168.1.1/24... done968machine # [ 22.506095] network-addresses-eth1-pre-stop[1070]: deleting address 2001:db8:1::1/64... done969machine # [ 22.513193] systemd[1]: network-addresses-eth1.service: Deactivated successfully.970machine # [ 22.515347] systemd[1]: Stopped Address configuration of eth1.971machine # [ 22.520676] systemd[1]: Starting Address configuration of eth1...972machine # [ 22.561652] network-addresses-eth1-start[1075]: adding address 192.168.1.2/24... done973machine # [ 22.568161] network-addresses-eth1-start[1075]: adding address 2001:db8:1::2/64... done974machine # [ 22.577292] systemd[1]: Finished Address configuration of eth1.975machine # [ 22.580557] systemd[1]: Reached target Remote File Systems.976machine # [ 22.583935] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os977machine # [ 22.587941] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki978machine # [ 22.595412] systemd[1]: Reached target Local File Systems.979machine # [ 22.599915] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore980machine # [ 22.608572] systemd[1]: Starting Create SUID/SGID Wrappers...981machine # [ 22.609527] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.982machine # [ 22.610792] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67983machine # [ 22.615527] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore984machine # [ 22.617442] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os985machine # [ 22.618781] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os986machine # [ 22.793286] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.987machine # [ 22.798244] systemd[1]: Finished Create SUID/SGID Wrappers.988machine # [ 22.800558] systemd[1]: Starting linger-users.service...989machine # [ 22.831994] linger-users-start[1147]: id: ‘usertest’: no such user990machine # [ 22.834504] linger-users-start[1145]: Removing linger for missing user usertest991machine # [ 22.841692] systemd[1]: linger-users.service: Deactivated successfully.992machine # [ 22.844149] systemd[1]: Finished linger-users.service.993machine # [ 23.102082] nixos[987]: finished switching to system configuration /nix/store/y9v5acm6cxjwf9wq5327x1qz6yz5rk47-nixos-system-other-test994machine: (finished: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/y9v5acm6cxjwf9wq5327x1qz6yz5rk47-nixos-system-other-test/bin/switch-to-configuration test, in 1.94 seconds)995machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1996machine # [ 23.319787] nixos[1153]: switching to system configuration /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test997machine # [ 23.322494] systemd[1]: Stopped target Remote File Systems.998machine # [ 23.325619] systemd[1]: Stopped target Local File Systems.999machine # [ 23.591188] systemd[1]: Reload requested from client PID 1153 ('.switch-to-conf') (unit backdoor.service)...1000machine # [ 23.593525] systemd[1]: Reloading...1001machine # [ 23.901732] systemd[1]: Reloading finished in 305 ms.1002machine # [ 23.984758] systemd[544]: Reexecution requested from client PID 1222 ('switch-to-confi')...1003machine # [ 23.987788] systemd[544]: Reexecuting.1004machine # [ 24.055826] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1005machine # [ 24.183893] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1006machine # [ 24.192202] systemd[544]: Reload requested from client PID 1222 ('switch-to-confi')...1007machine # [ 24.194480] systemd[544]: Reloading...1008machine # [ 24.301882] systemd[544]: Reloading finished in 105 ms.1009machine # [ 24.316184] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1010machine # [ 24.325639] systemd[544]: Stopped Run user-specific NixOS activation.1011machine # [ 24.328101] systemd[544]: Stopping Run user-specific NixOS activation...1012machine # [ 24.330246] systemd[544]: Starting Run user-specific NixOS activation...1013machine # [ 24.361731] systemd[544]: Finished Run user-specific NixOS activation.1014machine # [ 24.365892] systemd[1]: Stopped target Reactivate sysinit units.1015machine # [ 24.370286] systemd[1]: Stopping Reactivate sysinit units...1016machine # [ 24.371614] systemd[1]: Reached target Reactivate sysinit units.1017machine # [ 24.372991] systemd[1]: Reloading D-Bus System Message Bus...1018machine # [ 24.374175] dbus-broker-launch[438]: Caught SIGHUP, trigger reload.1019machine # [ 24.378114] dbus-broker-launch[438]: Looking up NSS user entry for 'systemd-timesync'...1020machine # [ 24.380436] dbus-broker-launch[438]: NSS returned no entry for 'systemd-timesync'1021machine # [ 24.381602] dbus-broker-launch[438]: Invalid user-name in /nix/store/n9gdx9aa5f6rhwnpm4q6k6k9ys4c0vjz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1022machine # [ 24.395742] systemd[1]: Reloaded D-Bus System Message Bus.1023machine # [ 24.401689] systemd[1]: Stopping Address configuration of eth1...1024machine # [ 24.434145] network-addresses-eth1-pre-stop[1235]: deleting address 192.168.1.2/24... done1025machine # [ 24.439744] network-addresses-eth1-pre-stop[1235]: deleting address 2001:db8:1::2/64... done1026machine # [ 24.448149] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1027machine # [ 24.450327] systemd[1]: Stopped Address configuration of eth1.1028machine # [ 24.454411] systemd[1]: Starting Address configuration of eth1...1029machine # [ 24.497711] network-addresses-eth1-start[1240]: adding address 192.168.1.1/24... done1030machine # [ 24.504308] network-addresses-eth1-start[1240]: adding address 2001:db8:1::1/64... done1031machine # [ 24.513206] systemd[1]: Finished Address configuration of eth1.1032machine # [ 24.517406] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1033machine # [ 24.519967] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1034machine # [ 24.525115] systemd[1]: Reached target Remote File Systems.1035machine # [ 24.527148] systemd[1]: Starting linger-users.service...1036machine # [ 24.530682] systemd[1]: Reached target Local File Systems.1037machine # [ 24.542826] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1038machine # [ 24.550773] systemd[1]: Starting Create SUID/SGID Wrappers...1039machine # [ 24.551721] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1040machine # [ 24.560135] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671041machine # [ 24.562505] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1042machine # [ 24.571148] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1043machine # [ 24.575721] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1044machine # [ 24.599692] systemd[1]: linger-users.service: Deactivated successfully.1045machine # [ 24.601776] systemd[1]: Finished linger-users.service.1046machine # [ 24.737333] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1047machine # [ 24.740939] systemd[1]: Finished Create SUID/SGID Wrappers.1048machine # [ 24.995024] nixos[1153]: finished switching to system configuration /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test1049machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.89 seconds)1050subtest: socket-activated services1051machine: must fail: [ -S /run/test.sock ]1052machine: (finished: must fail: [ -S /run/test.sock ], in 0.02 seconds)1053machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&11054machine # [ 25.228660] nixos[1312]: switching to system configuration /nix/store/ajqd35kd5r93qyhi9r8150k2hmpvkxl8-nixos-system-machine-test1055machine # [ 25.231446] systemd[1]: Stopped target Local File Systems.1056machine # [ 25.234379] systemd[1]: Stopped target Remote File Systems.1057machine # [ 25.505443] systemd[1]: Reload requested from client PID 1312 ('.switch-to-conf') (unit backdoor.service)...1058machine # [ 25.507497] systemd[1]: Reloading...1059machine # [ 25.819385] systemd[1]: Reloading finished in 310 ms.1060machine # [ 25.904442] systemd[544]: Reexecution requested from client PID 1381 ('switch-to-confi')...1061machine # [ 25.908092] systemd[544]: Reexecuting.1062machine # [ 25.963332] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1063machine # [ 26.073864] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1064machine # [ 26.080539] systemd[544]: Reload requested from client PID 1381 ('switch-to-confi')...1065machine # [ 26.084141] systemd[544]: Reloading...1066machine # [ 26.186210] systemd[544]: Reloading finished in 102 ms.1067machine # [ 26.200186] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1068machine # [ 26.211393] systemd[544]: Stopped Run user-specific NixOS activation.1069machine # [ 26.212718] systemd[544]: Stopping Run user-specific NixOS activation...1070machine # [ 26.214963] systemd[544]: Starting Run user-specific NixOS activation...1071machine # [ 26.246633] systemd[544]: Finished Run user-specific NixOS activation.1072machine # [ 26.250799] systemd[1]: Stopped target Reactivate sysinit units.1073machine # [ 26.256779] systemd[1]: Stopping Reactivate sysinit units...1074machine # [ 26.257706] systemd[1]: Reached target Reactivate sysinit units.1075machine # [ 26.258555] systemd[1]: Reached target Local File Systems.1076machine # [ 26.259342] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1077machine # [ 26.263323] systemd[1]: Starting Create SUID/SGID Wrappers...1078machine # [ 26.267562] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1079machine # [ 26.272584] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671080machine # [ 26.274949] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1081machine # [ 26.284398] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1082machine # [ 26.285767] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1083machine # [ 26.287010] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1084machine # [ 26.291202] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1085machine # [ 26.294449] systemd[1]: Reached target Remote File Systems.1086machine # [ 26.443939] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1087machine # [ 26.448916] systemd[1]: Finished Create SUID/SGID Wrappers.1088machine # [ 26.449792] systemd[1]: Listening on socket-activated.socket.1089machine # [ 26.452682] systemd[1]: Starting linger-users.service...1090machine # [ 26.493399] systemd[1]: linger-users.service: Deactivated successfully.1091machine # [ 26.496424] systemd[1]: Finished linger-users.service.1092machine # [ 26.752817] nixos[1312]: finished switching to system configuration /nix/store/ajqd35kd5r93qyhi9r8150k2hmpvkxl8-nixos-system-machine-test1093machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&1, in 1.74 seconds)1094machine: must succeed: [ -S /run/test.sock ]1095machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1096machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/simple-socket-service-modified/bin/switch-to-configuration test 2>&11097machine # [ 26.987183] nixos[1455]: switching to system configuration /nix/store/i1hlz4h8d58g3fb9x9jqapdw24c2760v-nixos-system-machine-test1098machine # [ 26.990239] systemd[1]: Stopped target Remote File Systems.1099machine # [ 26.992686] systemd[1]: Stopped target Local File Systems.1100machine # [ 27.261088] systemd[1]: Reload requested from client PID 1455 ('.switch-to-conf') (unit backdoor.service)...1101machine # [ 27.263051] systemd[1]: Reloading...1102machine # [ 27.611033] systemd[1]: Reloading finished in 346 ms.1103machine # [ 27.715625] systemd[544]: Reexecution requested from client PID 1524 ('switch-to-confi')...1104machine # [ 27.718825] systemd[544]: Reexecuting.1105machine # [ 27.779443] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1106machine # [ 27.894401] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1107machine # [ 27.901100] systemd[544]: Reload requested from client PID 1524 ('switch-to-confi')...1108machine # [ 27.903579] systemd[544]: Reloading...1109machine # [ 28.008626] systemd[544]: Reloading finished in 104 ms.1110machine # [ 28.024176] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1111machine # [ 28.036081] systemd[544]: Stopped Run user-specific NixOS activation.1112machine # [ 28.037212] systemd[544]: Stopping Run user-specific NixOS activation...1113machine # [ 28.039860] systemd[544]: Starting Run user-specific NixOS activation...1114machine # [ 28.067277] systemd[544]: Finished Run user-specific NixOS activation.1115machine # [ 28.071329] systemd[1]: Stopped target Reactivate sysinit units.1116machine # [ 28.073137] systemd[1]: Stopping Reactivate sysinit units...1117machine # [ 28.074006] systemd[1]: Reached target Reactivate sysinit units.1118machine # [ 28.076736] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1119machine # [ 28.078142] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1120machine # [ 28.081769] systemd[1]: Reached target Local File Systems.1121machine # [ 28.084853] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1122machine # [ 28.089164] systemd[1]: Starting Create SUID/SGID Wrappers...1123machine # [ 28.090083] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1124machine # [ 28.091330] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671125machine # [ 28.101342] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1126machine # [ 28.103028] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1127machine # [ 28.118697] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1128machine # [ 28.121701] systemd[1]: Reached target Remote File Systems.1129machine # [ 28.263706] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1130machine # [ 28.269212] systemd[1]: Finished Create SUID/SGID Wrappers.1131machine # [ 28.271262] systemd[1]: Starting linger-users.service...1132machine # [ 28.312362] systemd[1]: linger-users.service: Deactivated successfully.1133machine # [ 28.315309] systemd[1]: Finished linger-users.service.1134machine # [ 28.570046] nixos[1455]: finished switching to system configuration /nix/store/i1hlz4h8d58g3fb9x9jqapdw24c2760v-nixos-system-machine-test1135machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/simple-socket-service-modified/bin/switch-to-configuration test 2>&1, in 1.80 seconds)1136machine: must succeed: [ -S /run/test.sock ]1137machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1138machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1139machine # [ 28.626538] systemd[1]: Started A socket-activated service.1140machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.65 seconds)1141machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&11142machine # [ 29.442139] nixos[1603]: switching to system configuration /nix/store/ajqd35kd5r93qyhi9r8150k2hmpvkxl8-nixos-system-machine-test1143machine # [ 29.444961] systemd[1]: Stopped target Local File Systems.1144machine # [ 29.447178] systemd[1]: Stopped target Remote File Systems.1145machine # [ 29.706598] systemd[1]: Reload requested from client PID 1603 ('.switch-to-conf') (unit backdoor.service)...1146machine # [ 29.708869] systemd[1]: Reloading...1147machine # [ 30.007240] systemd[1]: Reloading finished in 297 ms.1148machine # [ 30.094963] systemd[544]: Reexecution requested from client PID 1673 ('switch-to-confi')...1149machine # [ 30.097330] systemd[544]: Reexecuting.1150machine # [ 30.146860] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1151machine # [ 30.254111] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1152machine # [ 30.260838] systemd[544]: Reload requested from client PID 1673 ('switch-to-confi')...1153machine # [ 30.263260] systemd[544]: Reloading...1154machine # [ 30.366712] systemd[544]: Reloading finished in 102 ms.1155machine # [ 30.384423] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1156machine # [ 30.394315] systemd[544]: Stopped Run user-specific NixOS activation.1157machine # [ 30.396879] systemd[544]: Stopping Run user-specific NixOS activation...1158machine # [ 30.399101] systemd[544]: Starting Run user-specific NixOS activation...1159machine # [ 30.431334] systemd[544]: Finished Run user-specific NixOS activation.1160machine # [ 30.434991] systemd[1]: Stopped target Reactivate sysinit units.1161machine # [ 30.436942] systemd[1]: Stopping Reactivate sysinit units...1162machine # [ 30.437834] systemd[1]: Reached target Reactivate sysinit units.1163machine # [ 30.442810] systemd[1]: Stopping A socket-activated service...1164machine # [ 30.445601] systemd[1]: socket-activated.service: Deactivated successfully.1165machine # [ 30.448490] systemd[1]: Stopped A socket-activated service.1166machine # [ 30.452338] systemd[1]: Started A socket-activated service.1167machine # [ 30.462236] systemd[1]: Reached target Remote File Systems.1168machine # [ 30.466983] systemd[1]: Starting linger-users.service...1169machine # [ 30.470211] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1170machine # [ 30.471614] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1171machine # [ 30.485022] systemd[1]: Reached target Local File Systems.1172machine # [ 30.485888] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1173machine # [ 30.487571] systemd[1]: Starting Create SUID/SGID Wrappers...1174machine # [ 30.502491] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1175machine # [ 30.503911] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671176machine # [ 30.516590] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1177machine # [ 30.522909] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1178machine # [ 30.530881] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1179machine # [ 30.596720] systemd[1]: linger-users.service: Deactivated successfully.1180machine # [ 30.597809] systemd[1]: Finished linger-users.service.1181machine # [ 30.787944] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1182machine # [ 30.791669] systemd[1]: Finished Create SUID/SGID Wrappers.1183machine # [ 31.045504] nixos[1603]: finished switching to system configuration /nix/store/ajqd35kd5r93qyhi9r8150k2hmpvkxl8-nixos-system-machine-test1184machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&1, in 1.81 seconds)1185machine: must succeed: [ -S /run/test.sock ]1186machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1187machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1188machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.53 seconds)1189machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&11190machine # [ 31.812748] nixos[1753]: switching to system configuration /nix/store/7b8dy62f2h14m93fhqmr6wxrzwkr28c5-nixos-system-machine-test1191machine # [ 31.818147] systemd[1]: Stopped target Local File Systems.1192machine # [ 31.823112] systemd[1]: Stopped target Remote File Systems.1193machine # [ 31.824691] systemd[1]: Stopping A socket-activated service...1194machine # [ 31.826340] systemd[1]: socket-activated.service: Deactivated successfully.1195machine # [ 31.829361] systemd[1]: Stopped A socket-activated service.1196machine # [ 31.831546] systemd[1]: socket-activated.socket: Deactivated successfully.1197machine # [ 31.833472] systemd[1]: Closed socket-activated.socket.1198machine # [ 32.120448] systemd[1]: Reload requested from client PID 1753 ('.switch-to-conf') (unit backdoor.service)...1199machine # [ 32.122486] systemd[1]: Reloading...1200machine # [ 32.433411] systemd[1]: Reloading finished in 309 ms.1201machine # [ 32.515661] systemd[544]: Reexecution requested from client PID 1823 ('switch-to-confi')...1202machine # [ 32.520419] systemd[544]: Reexecuting.1203machine # [ 32.575096] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1204machine # [ 32.686309] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1205machine # [ 32.693002] systemd[544]: Reload requested from client PID 1823 ('switch-to-confi')...1206machine # [ 32.696086] systemd[544]: Reloading...1207machine # [ 32.799373] systemd[544]: Reloading finished in 103 ms.1208machine # [ 32.817691] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1209machine # [ 32.826444] systemd[544]: Stopped Run user-specific NixOS activation.1210machine # [ 32.829013] systemd[544]: Stopping Run user-specific NixOS activation...1211machine # [ 32.831084] systemd[544]: Starting Run user-specific NixOS activation...1212machine # [ 32.863513] systemd[544]: Finished Run user-specific NixOS activation.1213machine # [ 32.867923] systemd[1]: Stopped target Reactivate sysinit units.1214machine # [ 32.869867] systemd[1]: Stopping Reactivate sysinit units...1215machine # [ 32.870750] systemd[1]: Reached target Reactivate sysinit units.1216machine # [ 32.874054] systemd[1]: Listening on socket-activated.socket.1217machine # [ 32.880129] systemd[1]: Reached target Remote File Systems.1218machine # [ 32.884332] systemd[1]: Starting linger-users.service...1219machine # [ 32.888759] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1220machine # [ 32.890165] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1221machine # [ 32.900967] systemd[1]: Reached target Local File Systems.1222machine # [ 32.901897] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1223machine # [ 32.907411] systemd[1]: Starting Create SUID/SGID Wrappers...1224machine # [ 32.910326] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1225machine # [ 32.911660] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671226machine # [ 32.922645] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1227machine # [ 32.927783] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1228machine # [ 32.930994] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1229machine # [ 32.966681] systemd[1]: linger-users.service: Deactivated successfully.1230machine # [ 32.970730] systemd[1]: Finished linger-users.service.1231machine # [ 33.106236] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1232machine # [ 33.109815] systemd[1]: Finished Create SUID/SGID Wrappers.1233machine # [ 33.363734] nixos[1753]: finished switching to system configuration /nix/store/7b8dy62f2h14m93fhqmr6wxrzwkr28c5-nixos-system-machine-test1234machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&1, in 1.77 seconds)1235machine: must succeed: [ -S /run/test.sock ]1236machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1237machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1238machine # [ 33.418358] systemd[1]: Started A socket-activated service.1239machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.60 seconds)1240machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed-and-reloadtrigger/bin/switch-to-configuration test 2>&11241machine # [ 34.251232] nixos[1901]: switching to system configuration /nix/store/cnj547h9rlvp500bzaxb6m0rc3bqgai0-nixos-system-machine-test1242machine # [ 34.254383] systemd[1]: Stopped target Local File Systems.1243machine # [ 34.256661] systemd[1]: Stopped target Remote File Systems.1244machine # [ 34.526116] systemd[1]: Reload requested from client PID 1901 ('.switch-to-conf') (unit backdoor.service)...1245machine # [ 34.528199] systemd[1]: Reloading...1246machine # [ 34.839724] systemd[1]: Reloading finished in 310 ms.1247machine # [ 34.924560] systemd[544]: Reexecution requested from client PID 1972 ('switch-to-confi')...1248machine # [ 34.926027] systemd[544]: Reexecuting.1249machine # [ 34.983425] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1250machine # [ 35.095424] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1251machine # [ 35.102892] systemd[544]: Reload requested from client PID 1972 ('switch-to-confi')...1252machine # [ 35.107417] systemd[544]: Reloading...1253machine # [ 35.215477] systemd[544]: Reloading finished in 109 ms.1254machine # [ 35.232215] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1255machine # [ 35.241916] systemd[544]: Stopped Run user-specific NixOS activation.1256machine # [ 35.244675] systemd[544]: Stopping Run user-specific NixOS activation...1257machine # [ 35.246961] systemd[544]: Starting Run user-specific NixOS activation...1258machine # [ 35.280609] systemd[544]: Finished Run user-specific NixOS activation.1259machine # [ 35.285453] systemd[1]: Stopped target Reactivate sysinit units.1260machine # [ 35.286775] systemd[1]: Stopping Reactivate sysinit units...1261machine # [ 35.287691] systemd[1]: Reached target Reactivate sysinit units.1262machine # [ 35.292469] systemd[1]: Reloading A socket-activated service...1263machine # [ 35.318187] systemd[1]: Reloaded A socket-activated service.1264machine # [ 35.326372] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1265machine # [ 35.329319] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1266machine # [ 35.334119] systemd[1]: Reached target Remote File Systems.1267machine # [ 35.337066] systemd[1]: Starting linger-users.service...1268machine # [ 35.341823] systemd[1]: Reached target Local File Systems.1269machine # [ 35.351196] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1270machine # [ 35.360346] systemd[1]: Starting Create SUID/SGID Wrappers...1271machine # [ 35.361299] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1272machine # [ 35.362566] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671273machine # [ 35.369924] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1274machine # [ 35.372252] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1275machine # [ 35.373589] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1276machine # [ 35.439125] systemd[1]: linger-users.service: Deactivated successfully.1277machine # [ 35.444878] systemd[1]: Finished linger-users.service.1278machine # [ 35.569785] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1279machine # [ 35.573191] systemd[1]: Finished Create SUID/SGID Wrappers.1280machine # [ 35.828801] nixos[1901]: finished switching to system configuration /nix/store/cnj547h9rlvp500bzaxb6m0rc3bqgai0-nixos-system-machine-test1281machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed-and-reloadtrigger/bin/switch-to-configuration test 2>&1, in 1.84 seconds)1282machine: must succeed: [ -S /run/test.sock ]1283machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1284machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1285machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.53 seconds)1286machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/socket-activated-without-socket/bin/switch-to-configuration test 2>&11287machine # [ 36.591801] nixos[2051]: switching to system configuration /nix/store/4v7mm8yv7yk02dq21cc4z61g1w6k3add-nixos-system-machine-test1288machine # [ 36.596294] systemd[1]: Stopped target Local File Systems.1289machine # [ 36.598567] systemd[1]: Stopped target Remote File Systems.1290machine # [ 36.599458] systemd[1]: socket-activated.socket: Deactivated successfully.1291machine # [ 36.600514] systemd[1]: Closed socket-activated.socket.1292machine # [ 36.873130] systemd[1]: Reload requested from client PID 2051 ('.switch-to-conf') (unit backdoor.service)...1293machine # [ 36.875153] systemd[1]: Reloading...1294machine # [ 37.187689] systemd[1]: Reloading finished in 310 ms.1295machine # [ 37.271278] systemd[544]: Reexecution requested from client PID 2121 ('switch-to-confi')...1296machine # [ 37.275033] systemd[544]: Reexecuting.1297machine # [ 37.330790] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1298machine # [ 37.486189] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1299machine # [ 37.492858] systemd[544]: Reload requested from client PID 2121 ('switch-to-confi')...1300machine # [ 37.495289] systemd[544]: Reloading...1301machine # [ 37.602562] systemd[544]: Reloading finished in 106 ms.1302machine # [ 37.620266] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1303machine # [ 37.629791] systemd[544]: Stopped Run user-specific NixOS activation.1304machine # [ 37.632088] systemd[544]: Stopping Run user-specific NixOS activation...1305machine # [ 37.634275] systemd[544]: Starting Run user-specific NixOS activation...1306machine # [ 37.662852] systemd[544]: Finished Run user-specific NixOS activation.1307machine # [ 37.667268] systemd[1]: Stopped target Reactivate sysinit units.1308machine # [ 37.673634] systemd[1]: Stopping Reactivate sysinit units...1309machine # [ 37.674632] systemd[1]: Reached target Reactivate sysinit units.1310machine # [ 37.675529] systemd[1]: Stopping A socket-activated service...1311machine # [ 37.677328] systemd[1]: socket-activated.service: Deactivated successfully.1312machine # [ 37.679313] systemd[1]: Stopped A socket-activated service.1313machine # [ 37.683179] systemd[1]: Started A socket-activated service.1314machine # [ 37.694311] systemd[1]: Reached target Local File Systems.1315machine # [ 37.695188] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1316machine # [ 37.704351] systemd[1]: Starting Create SUID/SGID Wrappers...1317machine # [ 37.705249] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1318machine # [ 37.706514] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671319machine # [ 37.722242] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1320machine # [ 37.723955] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1321machine # [ 37.728542] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1322machine # [ 37.733899] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1323machine # [ 37.735322] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1324machine # [ 37.743404] systemd[1]: Reached target Remote File Systems.1325machine # [ 37.925812] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1326machine # [ 37.928857] systemd[1]: Finished Create SUID/SGID Wrappers.1327machine # [ 37.933330] systemd[1]: Starting linger-users.service...1328machine # [ 37.974699] systemd[1]: linger-users.service: Deactivated successfully.1329machine # [ 37.977131] systemd[1]: Finished linger-users.service.1330machine # [ 38.233882] nixos[2051]: finished switching to system configuration /nix/store/4v7mm8yv7yk02dq21cc4z61g1w6k3add-nixos-system-machine-test1331machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/socket-activated-without-socket/bin/switch-to-configuration test 2>&1, in 1.86 seconds)1332machine: must succeed: systemctl is-active socket-activated.service1333machine: (finished: must succeed: systemctl is-active socket-activated.service, in 0.03 seconds)1334machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&11335machine # [ 38.466659] nixos[2198]: switching to system configuration /nix/store/7b8dy62f2h14m93fhqmr6wxrzwkr28c5-nixos-system-machine-test1336machine # [ 38.469549] systemd[1]: Stopped target Local File Systems.1337machine # [ 38.474972] systemd[1]: Stopping A socket-activated service...1338machine # [ 38.476953] systemd[1]: Stopped target Remote File Systems.1339machine # [ 38.478766] systemd[1]: socket-activated.service: Deactivated successfully.1340machine # [ 38.482686] systemd[1]: Stopped A socket-activated service.1341machine # [ 38.738978] systemd[1]: Reload requested from client PID 2198 ('.switch-to-conf') (unit backdoor.service)...1342machine # [ 38.741282] systemd[1]: Reloading...1343machine # [ 39.038865] systemd[1]: Reloading finished in 296 ms.1344machine # [ 39.122645] systemd[544]: Reexecution requested from client PID 2268 ('switch-to-confi')...1345machine # [ 39.125648] systemd[544]: Reexecuting.1346machine # [ 39.182836] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1347machine # [ 39.288723] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1348machine # [ 39.295280] systemd[544]: Reload requested from client PID 2268 ('switch-to-confi')...1349machine # [ 39.297545] systemd[544]: Reloading...1350machine # [ 39.401920] systemd[544]: Reloading finished in 102 ms.1351machine # [ 39.416171] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1352machine # [ 39.425282] systemd[544]: Stopped Run user-specific NixOS activation.1353machine # [ 39.427679] systemd[544]: Stopping Run user-specific NixOS activation...1354machine # [ 39.430145] systemd[544]: Starting Run user-specific NixOS activation...1355machine # [ 39.459357] systemd[544]: Finished Run user-specific NixOS activation.1356machine # [ 39.463033] systemd[1]: Stopped target Reactivate sysinit units.1357machine # [ 39.465062] systemd[1]: Stopping Reactivate sysinit units...1358machine # [ 39.466008] systemd[1]: Reached target Reactivate sysinit units.1359machine # [ 39.468831] systemd[1]: Reached target Remote File Systems.1360machine # [ 39.473213] systemd[1]: Listening on socket-activated.socket.1361machine # [ 39.479054] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1362machine # [ 39.484949] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1363machine # [ 39.486343] systemd[1]: Reached target Local File Systems.1364machine # [ 39.487168] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1365machine # [ 39.495062] systemd[1]: Starting Create SUID/SGID Wrappers...1366machine # [ 39.495978] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1367machine # [ 39.499659] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671368machine # [ 39.502574] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1369machine # [ 39.505647] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1370machine # [ 39.508497] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1371machine # [ 39.653530] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1372machine # [ 39.658446] systemd[1]: Finished Create SUID/SGID Wrappers.1373machine # [ 39.660738] systemd[1]: Starting linger-users.service...1374machine # [ 39.700643] systemd[1]: linger-users.service: Deactivated successfully.1375machine # [ 39.703660] systemd[1]: Finished linger-users.service.1376machine # [ 39.958819] nixos[2198]: finished switching to system configuration /nix/store/7b8dy62f2h14m93fhqmr6wxrzwkr28c5-nixos-system-machine-test1377machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&1, in 1.69 seconds)1378machine: must succeed: [ -S /run/test.sock ]1379machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1380machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1381machine # [ 40.017011] systemd[1]: Started A socket-activated service.1382machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.61 seconds)1383(finished: subtest: socket-activated services, in 15.59 seconds)1384subtest: socket-activated services with Accept=yes1385machine: must fail: [ -S /run/accept-test.sock ]1386machine: (finished: must fail: [ -S /run/accept-test.sock ], in 0.02 seconds)1387machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/accept-socket/bin/switch-to-configuration test 2>&11388machine # [ 40.900774] nixos[2349]: switching to system configuration /nix/store/wczmayg89v3kgjhddyp6nd4scrya9sq6-nixos-system-machine-test1389machine # [ 40.905385] systemd[1]: Stopped target Local File Systems.1390machine # [ 40.911023] systemd[1]: Stopped target Remote File Systems.1391machine # [ 40.912422] systemd[1]: Stopping A socket-activated service...1392machine # [ 40.914375] systemd[1]: socket-activated.service: Deactivated successfully.1393machine # [ 40.919020] systemd[1]: Stopped A socket-activated service.1394machine # [ 40.921680] systemd[1]: socket-activated.socket: Deactivated successfully.1395machine # [ 40.923020] systemd[1]: Closed socket-activated.socket.1396machine # [ 41.228782] systemd[1]: Reload requested from client PID 2349 ('.switch-to-conf') (unit backdoor.service)...1397machine # [ 41.230530] systemd[1]: Reloading...1398machine # [ 41.565610] systemd[1]: Reloading finished in 333 ms.1399machine # [ 41.655875] systemd[544]: Reexecution requested from client PID 2420 ('switch-to-confi')...1400machine # [ 41.659085] systemd[544]: Reexecuting.1401machine # [ 41.710937] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1402machine # [ 41.817911] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1403machine # [ 41.824593] systemd[544]: Reload requested from client PID 2420 ('switch-to-confi')...1404machine # [ 41.827061] systemd[544]: Reloading...1405machine # [ 41.934722] systemd[544]: Reloading finished in 106 ms.1406machine # [ 41.952338] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1407machine # [ 41.963907] systemd[544]: Stopped Run user-specific NixOS activation.1408machine # [ 41.966860] systemd[544]: Stopping Run user-specific NixOS activation...1409machine # [ 41.969594] systemd[544]: Starting Run user-specific NixOS activation...1410machine # [ 42.005079] systemd[544]: Finished Run user-specific NixOS activation.1411machine # [ 42.009519] systemd[1]: Stopped target Reactivate sysinit units.1412machine # [ 42.013274] systemd[1]: Stopping Reactivate sysinit units...1413machine # [ 42.014375] systemd[1]: Reached target Reactivate sysinit units.1414machine # [ 42.015413] systemd[1]: Reached target Remote File Systems.1415machine # [ 42.020211] systemd[1]: Listening on accept-socket.socket.1416machine # [ 42.021191] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1417machine # [ 42.022543] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1418machine # [ 42.026032] systemd[1]: Reached target Local File Systems.1419machine # [ 42.032492] systemd[1]: Starting linger-users.service...1420machine # [ 42.045405] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1421machine # [ 42.054055] systemd[1]: Starting Create SUID/SGID Wrappers...1422machine # [ 42.055034] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1423machine # [ 42.060503] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671424machine # [ 42.062835] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1425machine # [ 42.072491] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1426machine # [ 42.073953] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1427machine # [ 42.117071] systemd[1]: linger-users.service: Deactivated successfully.1428machine # [ 42.119856] systemd[1]: Finished linger-users.service.1429machine # [ 42.248856] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1430machine # [ 42.252277] systemd[1]: Finished Create SUID/SGID Wrappers.1431machine # [ 42.507055] nixos[2349]: finished switching to system configuration /nix/store/wczmayg89v3kgjhddyp6nd4scrya9sq6-nixos-system-machine-test1432machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/accept-socket/bin/switch-to-configuration test 2>&1, in 1.90 seconds)1433machine: must succeed: [ -S /run/accept-test.sock ]1434machine: (finished: must succeed: [ -S /run/accept-test.sock ], in 0.02 seconds)1435machine: must succeed: socat EXEC:'sleep infinity' UNIX-CONNECT:/run/accept-test.sock >&2 &1436machine: (finished: must succeed: socat EXEC:'sleep infinity' UNIX-CONNECT:/run/accept-test.sock >&2 &, in 0.02 seconds)1437machine: waiting for success: systemctl list-units --no-legend --state=running 'accept-socket@*.service' | grep -m1 -o 'accept-socket@[^ ]*\.service'1438machine # [ 42.583513] systemd[1]: Created slice Slice /system/accept-socket.1439machine # [ 42.590028] systemd[1]: Started A per-connection socket-activated service (PID 2494/UID 0).1440machine: (finished: waiting for success: systemctl list-units --no-legend --state=running 'accept-socket@*.service' | grep -m1 -o 'accept-socket@[^ ]*\.service', in 0.07 seconds)1441machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/accept-socket-service-modified/bin/switch-to-configuration test 2>&11442machine # [ 42.833420] nixos[2506]: switching to system configuration /nix/store/avg1pxnp12vw854alybpm1sy1qprpvv2-nixos-system-machine-test1443machine # [ 42.837794] systemd[1]: Stopping A per-connection socket-activated service (PID 2494/UID 0)...1444machine # [ 42.841093] systemd[1]: accept-socket@0-1-2494_2495-0.service: Deactivated successfully.1445machine # [ 42.844500] systemd[1]: Stopped A per-connection socket-activated service (PID 2494/UID 0).1446machine # [ 42.849412] systemd[1]: Stopped target Local File Systems.1447machine # [ 42.851132] systemd[1]: Stopped target Remote File Systems.1448machine # [ 42.852007] systemd[1]: accept-socket.socket: Deactivated successfully.1449machine # [ 42.854841] systemd[1]: Closed accept-socket.socket.1450machine # [ 43.128161] systemd[1]: Reload requested from client PID 2506 ('.switch-to-conf') (unit backdoor.service)...1451machine # [ 43.130118] systemd[1]: Reloading...1452machine # [ 43.445725] systemd[1]: Reloading finished in 313 ms.1453machine # [ 43.531355] systemd[544]: Reexecution requested from client PID 2577 ('switch-to-confi')...1454machine # [ 43.533930] systemd[544]: Reexecuting.1455machine # [ 43.591231] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1456machine # [ 43.699415] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1457machine # [ 43.706552] systemd[544]: Reload requested from client PID 2577 ('switch-to-confi')...1458machine # [ 43.709158] systemd[544]: Reloading...1459machine # [ 43.813937] systemd[544]: Reloading finished in 103 ms.1460machine # [ 43.836532] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1461machine # [ 43.847645] systemd[544]: Stopped Run user-specific NixOS activation.1462machine # [ 43.850580] systemd[544]: Stopping Run user-specific NixOS activation...1463machine # [ 43.853292] systemd[544]: Starting Run user-specific NixOS activation...1464machine # [ 43.885440] systemd[544]: Finished Run user-specific NixOS activation.1465machine # [ 43.890257] systemd[1]: Stopped target Reactivate sysinit units.1466machine # [ 43.894647] systemd[1]: Stopping Reactivate sysinit units...1467machine # [ 43.895774] systemd[1]: Reached target Reactivate sysinit units.1468machine # [ 43.901166] systemd[1]: Reached target Local File Systems.1469machine # [ 43.902126] systemd[1]: Reached target Remote File Systems.1470machine # [ 43.906193] systemd[1]: Listening on accept-socket.socket.1471machine # [ 43.908589] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1472machine # [ 43.910273] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1473machine # [ 43.915992] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1474machine # [ 43.923965] systemd[1]: Starting Create SUID/SGID Wrappers...1475machine # [ 43.927270] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1476machine # [ 43.932391] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671477machine # [ 43.934804] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1478machine # [ 43.938412] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1479machine # [ 43.939889] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1480machine # [ 44.151775] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1481machine # [ 44.156681] systemd[1]: Finished Create SUID/SGID Wrappers.1482machine # [ 44.159747] systemd[1]: Starting linger-users.service...1483machine # [ 44.202138] systemd[1]: linger-users.service: Deactivated successfully.1484machine # [ 44.205627] systemd[1]: Finished linger-users.service.1485machine # [ 44.459848] nixos[2506]: finished switching to system configuration /nix/store/avg1pxnp12vw854alybpm1sy1qprpvv2-nixos-system-machine-test1486machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/accept-socket-service-modified/bin/switch-to-configuration test 2>&1, in 1.84 seconds)1487machine: must succeed: socat - UNIX-CONNECT:/run/accept-test.sock </dev/null1488machine # [ 44.501718] systemd[1]: Started A per-connection socket-activated service (PID 2649/UID 0).1489machine # [ 44.540681] systemd[1]: accept-socket@1-2-2649_2650-0.service: Deactivated successfully.1490machine: (finished: must succeed: socat - UNIX-CONNECT:/run/accept-test.sock </dev/null, in 0.08 seconds)1491(finished: subtest: socket-activated services with Accept=yes, in 3.96 seconds)1492subtest: mounts1493machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&11494machine # [ 44.741466] nixos[2656]: switching to system configuration /nix/store/n444psl9l1mknjscgqin4sn5j7lq1jz7-nixos-system-machine-test1495machine # [ 44.744099] systemd[1]: accept-socket.socket: Deactivated successfully.1496machine # [ 44.747569] systemd[1]: Closed accept-socket.socket.1497machine # [ 44.749208] systemd[1]: Stopped target Remote File Systems.1498machine # [ 44.750042] systemd[1]: Stopped target Local File Systems.1499machine # [ 45.021968] systemd[1]: Reload requested from client PID 2656 ('.switch-to-conf') (unit backdoor.service)...1500machine # [ 45.023948] systemd[1]: Reloading...1501machine # [ 45.369952] systemd[1]: Reloading finished in 344 ms.1502machine # [ 45.455670] systemd[544]: Reexecution requested from client PID 2725 ('switch-to-confi')...1503machine # [ 45.459594] systemd[544]: Reexecuting.1504machine # [ 45.515197] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1505machine # [ 45.622929] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1506machine # [ 45.631088] systemd[544]: Reload requested from client PID 2725 ('switch-to-confi')...1507machine # [ 45.634346] systemd[544]: Reloading...1508machine # [ 45.738270] systemd[544]: Reloading finished in 105 ms.1509machine # [ 45.753722] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1510machine # [ 45.762761] systemd[544]: Stopped Run user-specific NixOS activation.1511machine # [ 45.765796] systemd[544]: Stopping Run user-specific NixOS activation...1512machine # [ 45.768499] systemd[544]: Starting Run user-specific NixOS activation...1513machine # [ 45.805191] systemd[544]: Finished Run user-specific NixOS activation.1514machine # [ 45.809399] systemd[1]: Stopped target Reactivate sysinit units.1515machine # [ 45.811588] systemd[1]: Stopping Reactivate sysinit units...1516machine # [ 45.816912] systemd[1]: Reached target Reactivate sysinit units.1517machine # [ 45.817915] systemd[1]: Reached target Remote File Systems.1518machine # [ 45.820763] systemd[1]: Starting linger-users.service...1519machine # [ 45.830855] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1520machine # [ 45.839421] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1521machine # [ 45.848449] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1522machine # [ 45.850077] systemd[1]: Starting Create SUID/SGID Wrappers...1523machine # [ 45.850909] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671524machine # [ 45.859782] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1525machine # [ 45.864129] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1526machine # [ 45.870091] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1527machine # [ 45.881946] systemd[1]: Mounting Testmount...1528machine # [ 45.938692] systemd[1]: Mounted Testmount.1529machine # [ 45.941416] systemd[1]: linger-users.service: Deactivated successfully.1530machine # [ 45.947476] systemd[1]: Finished linger-users.service.1531machine # [ 45.949838] systemd[1]: Reached target Local File Systems.1532machine # [ 45.950736] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1533machine # [ 46.073411] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1534machine # [ 46.076770] systemd[1]: Finished Create SUID/SGID Wrappers.1535machine # [ 46.332822] nixos[2656]: finished switching to system configuration /nix/store/n444psl9l1mknjscgqin4sn5j7lq1jz7-nixos-system-machine-test1536machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&1, in 1.79 seconds)1537machine: must succeed: mount | grep 'on /testmount'1538machine: (finished: must succeed: mount | grep 'on /testmount', in 0.03 seconds)1539machine: must succeed: systemctl stop testmount.mount1540machine # [ 46.395906] systemd[1]: Unmounting Testmount...1541machine # [ 46.430965] systemd[1]: testmount.mount: Deactivated successfully.1542machine # [ 46.432832] systemd[1]: Unmounted Testmount.1543machine: (finished: must succeed: systemctl stop testmount.mount, in 0.08 seconds)1544machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&11545machine # [ 46.623302] nixos[2809]: switching to system configuration /nix/store/n444psl9l1mknjscgqin4sn5j7lq1jz7-nixos-system-machine-test1546machine # [ 46.628939] systemd[1]: Stopped target Local File Systems.1547machine # [ 46.630006] systemd[1]: Stopped target Remote File Systems.1548machine # [ 46.878931] systemd[1]: Reload requested from client PID 2809 ('.switch-to-conf') (unit backdoor.service)...1549machine # [ 46.881374] systemd[1]: Reloading...1550machine # [ 47.191977] systemd[1]: Reloading finished in 309 ms.1551machine # [ 47.278420] systemd[544]: Reexecution requested from client PID 2878 ('switch-to-confi')...1552machine # [ 47.282188] systemd[544]: Reexecuting.1553machine # [ 47.338610] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1554machine # [ 47.442350] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1555machine # [ 47.449490] systemd[544]: Reload requested from client PID 2878 ('switch-to-confi')...1556machine # [ 47.450913] systemd[544]: Reloading...1557machine # [ 47.554611] systemd[544]: Reloading finished in 102 ms.1558machine # [ 47.568184] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1559machine # [ 47.578362] systemd[544]: Stopped Run user-specific NixOS activation.1560machine # [ 47.580861] systemd[544]: Stopping Run user-specific NixOS activation...1561machine # [ 47.583047] systemd[544]: Starting Run user-specific NixOS activation...1562machine # [ 47.615096] systemd[544]: Finished Run user-specific NixOS activation.1563machine # [ 47.618999] systemd[1]: Stopped target Reactivate sysinit units.1564machine # [ 47.621954] systemd[1]: Stopping Reactivate sysinit units...1565machine # [ 47.622892] systemd[1]: Reached target Reactivate sysinit units.1566machine # [ 47.626558] systemd[1]: Reached target Remote File Systems.1567machine # [ 47.630542] systemd[1]: Starting linger-users.service...1568machine # [ 47.638520] systemd[1]: Mounting Testmount...1569machine # [ 47.639304] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1570machine # [ 47.656196] systemd[1]: Starting Create SUID/SGID Wrappers...1571machine # [ 47.657157] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671572machine # [ 47.659426] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1573machine # [ 47.674457] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1574machine # [ 47.675810] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1575machine # [ 47.687203] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1576machine # [ 47.695508] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1577machine # [ 47.710751] systemd[1]: Mounted Testmount.1578machine # [ 47.714495] systemd[1]: Reached target Local File Systems.1579machine # [ 47.715758] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1580machine # [ 47.743208] systemd[1]: linger-users.service: Deactivated successfully.1581machine # [ 47.746684] systemd[1]: Finished linger-users.service.1582machine # [ 47.874854] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1583machine # [ 47.878230] systemd[1]: Finished Create SUID/SGID Wrappers.1584machine # [ 48.133636] nixos[2809]: finished switching to system configuration /nix/store/n444psl9l1mknjscgqin4sn5j7lq1jz7-nixos-system-machine-test1585machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&1, in 1.69 seconds)1586machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/mountOptionsModified/bin/switch-to-configuration test 2>&11587machine # [ 48.359719] nixos[2951]: switching to system configuration /nix/store/rwadwa411rpc3sp5w596yim4xjj1cqwm-nixos-system-machine-test1588machine # [ 48.362629] systemd[1]: Stopped target Remote File Systems.1589machine # [ 48.365549] systemd[1]: Stopped target Local File Systems.1590machine # [ 48.639060] systemd[1]: Reload requested from client PID 2951 ('.switch-to-conf') (unit backdoor.service)...1591machine # [ 48.641303] systemd[1]: Reloading...1592machine # [ 48.962056] systemd[1]: Reloading finished in 319 ms.1593machine # [ 49.052245] systemd[544]: Reexecution requested from client PID 3020 ('switch-to-confi')...1594machine # [ 49.053769] systemd[544]: Reexecuting.1595machine # [ 49.111517] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1596machine # [ 49.227161] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1597machine # [ 49.234911] systemd[544]: Reload requested from client PID 3020 ('switch-to-confi')...1598machine # [ 49.239270] systemd[544]: Reloading...1599machine # [ 49.354129] systemd[544]: Reloading finished in 114 ms.1600machine # [ 49.368269] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1601machine # [ 49.378962] systemd[544]: Stopped Run user-specific NixOS activation.1602machine # [ 49.381685] systemd[544]: Stopping Run user-specific NixOS activation...1603machine # [ 49.383958] systemd[544]: Starting Run user-specific NixOS activation...1604machine # [ 49.415675] systemd[544]: Finished Run user-specific NixOS activation.1605machine # [ 49.421049] systemd[1]: Stopped target Reactivate sysinit units.1606machine # [ 49.422071] systemd[1]: Stopping Reactivate sysinit units...1607machine # [ 49.422921] systemd[1]: Reached target Reactivate sysinit units.1608machine # [ 49.426533] systemd[1]: Reloading Testmount...1609machine # [ 49.456775] systemd[1]: Reloaded Testmount.1610machine # [ 49.462967] systemd[1]: Reached target Local File Systems.1611machine # [ 49.470131] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1612machine # [ 49.471825] systemd[1]: Starting Create SUID/SGID Wrappers...1613machine # [ 49.484423] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1614machine # [ 49.486112] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671615machine # [ 49.491733] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1616machine # [ 49.494631] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1617machine # [ 49.496754] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1618machine # [ 49.499129] systemd[1]: Reached target Remote File Systems.1619machine # [ 49.501421] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1620machine # [ 49.502847] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1621machine # [ 49.655776] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1622machine # [ 49.660607] systemd[1]: Finished Create SUID/SGID Wrappers.1623machine # [ 49.663428] systemd[1]: Starting linger-users.service...1624machine # [ 49.706048] systemd[1]: linger-users.service: Deactivated successfully.1625machine # [ 49.709439] systemd[1]: Finished linger-users.service.1626machine # [ 49.964874] nixos[2951]: finished switching to system configuration /nix/store/rwadwa411rpc3sp5w596yim4xjj1cqwm-nixos-system-machine-test1627machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/mountOptionsModified/bin/switch-to-configuration test 2>&1, in 1.83 seconds)1628machine: must succeed: mount | grep 'on /testmount'1629machine: (finished: must succeed: mount | grep 'on /testmount', in 0.03 seconds)1630machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/mountModified/bin/switch-to-configuration test 2>&11631machine # [ 50.221046] nixos[3098]: switching to system configuration /nix/store/65wm68yr81g1q911dnmgqplr9nkclii7-nixos-system-machine-test1632machine # [ 50.223668] systemd[1]: Stopped target Local File Systems.1633machine # [ 50.226362] systemd[1]: Stopped target Remote File Systems.1634machine # [ 50.503219] systemd[1]: Reload requested from client PID 3098 ('.switch-to-conf') (unit backdoor.service)...1635machine # [ 50.505390] systemd[1]: Reloading...1636machine # [ 50.827771] systemd[1]: Reloading finished in 321 ms.1637machine # [ 50.913862] systemd[544]: Reexecution requested from client PID 3167 ('switch-to-confi')...1638machine # [ 50.918151] systemd[544]: Reexecuting.1639machine # [ 50.967203] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1640machine # [ 51.077286] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1641machine # [ 51.084253] systemd[544]: Reload requested from client PID 3167 ('switch-to-confi')...1642machine # [ 51.085669] systemd[544]: Reloading...1643machine # [ 51.199978] systemd[544]: Reloading finished in 112 ms.1644machine # [ 51.216314] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1645machine # [ 51.226438] systemd[544]: Stopped Run user-specific NixOS activation.1646machine # [ 51.229466] systemd[544]: Stopping Run user-specific NixOS activation...1647machine # [ 51.231969] systemd[544]: Starting Run user-specific NixOS activation...1648machine # [ 51.265108] systemd[544]: Finished Run user-specific NixOS activation.1649machine # [ 51.269687] systemd[1]: Stopped target Reactivate sysinit units.1650machine # [ 51.274006] systemd[1]: Stopping Reactivate sysinit units...1651machine # [ 51.275160] systemd[1]: Reached target Reactivate sysinit units.1652machine # [ 51.276832] systemd[1]: Unmounting Testmount...1653machine # [ 51.306982] systemd[1]: testmount.mount: Deactivated successfully.1654machine # [ 51.309013] systemd[1]: Unmounted Testmount.1655machine # [ 51.313857] systemd[1]: Mounting Testmount...1656machine # [ 51.346080] systemd[1]: Mounted Testmount.1657machine # [ 51.353403] systemd[1]: Reached target Local File Systems.1658machine # [ 51.357015] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1659machine # [ 51.362502] systemd[1]: Starting Create SUID/SGID Wrappers...1660machine # [ 51.363533] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1661machine # [ 51.373081] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671662machine # [ 51.375512] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1663machine # [ 51.386744] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1664machine # [ 51.389383] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1665machine # [ 51.391012] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1666machine # [ 51.393267] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1667machine # [ 51.394964] systemd[1]: Reached target Remote File Systems.1668machine # [ 51.546839] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1669machine # [ 51.552453] systemd[1]: Finished Create SUID/SGID Wrappers.1670machine # [ 51.555046] systemd[1]: Starting linger-users.service...1671machine # [ 51.598571] systemd[1]: linger-users.service: Deactivated successfully.1672machine # [ 51.601529] systemd[1]: Finished linger-users.service.1673machine # [ 51.858111] nixos[3098]: finished switching to system configuration /nix/store/65wm68yr81g1q911dnmgqplr9nkclii7-nixos-system-machine-test1674machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/mountModified/bin/switch-to-configuration test 2>&1, in 1.86 seconds)1675machine: must succeed: mount | grep 'on /testmount'1676machine: (finished: must succeed: mount | grep 'on /testmount', in 0.03 seconds)1677(finished: subtest: mounts, in 7.35 seconds)1678subtest: timers1679machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/timer/bin/switch-to-configuration test 2>&11680machine # [ 52.107490] nixos[3247]: switching to system configuration /nix/store/6ygd05jfphrqshrh1bznlwxha64ivipi-nixos-system-machine-test1681machine # [ 52.112855] systemd[1]: Unmounting Testmount...1682machine # [ 52.120172] systemd[1]: Stopped target Remote File Systems.1683machine # [ 52.121157] systemd[1]: Stopped target Local File Systems.1684machine # [ 52.142214] systemd[1]: testmount.mount: Deactivated successfully.1685machine # [ 52.143890] systemd[1]: Unmounted Testmount.1686machine # [ 52.431377] systemd[1]: Reload requested from client PID 3247 ('.switch-to-conf') (unit backdoor.service)...1687machine # [ 52.433756] systemd[1]: Reloading...1688machine # [ 52.756526] systemd[1]: Reloading finished in 321 ms.1689machine # [ 52.844489] systemd[544]: Reexecution requested from client PID 3318 ('switch-to-confi')...1690machine # [ 52.849232] systemd[544]: Reexecuting.1691machine # [ 52.907630] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1692machine # [ 53.023247] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1693machine # [ 53.030429] systemd[544]: Reload requested from client PID 3318 ('switch-to-confi')...1694machine # [ 53.033169] systemd[544]: Reloading...1695machine # [ 53.143347] systemd[544]: Reloading finished in 109 ms.1696machine # [ 53.160245] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1697machine # [ 53.173633] systemd[544]: Stopped Run user-specific NixOS activation.1698machine # [ 53.174960] systemd[544]: Stopping Run user-specific NixOS activation...1699machine # [ 53.178683] systemd[544]: Starting Run user-specific NixOS activation...1700machine # [ 53.210053] systemd[544]: Finished Run user-specific NixOS activation.1701machine # [ 53.214738] systemd[1]: Stopped target Reactivate sysinit units.1702machine # [ 53.218349] systemd[1]: Stopping Reactivate sysinit units...1703machine # [ 53.219475] systemd[1]: Reached target Reactivate sysinit units.1704machine # [ 53.227009] systemd[1]: Reached target Local File Systems.1705machine # [ 53.227886] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1706machine # [ 53.237624] systemd[1]: Starting Create SUID/SGID Wrappers...1707machine # [ 53.238574] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1708machine # [ 53.239870] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671709machine # [ 53.251422] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1710machine # [ 53.253431] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1711machine # [ 53.254765] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1712machine # [ 53.256499] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1713machine # [ 53.257924] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1714machine # [ 53.260736] systemd[1]: Reached target Remote File Systems.1715machine # [ 53.413556] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1716machine # [ 53.419193] systemd[1]: Finished Create SUID/SGID Wrappers.1717machine # [ 53.422043] systemd[1]: Started test-timer.timer.1718machine # [ 53.422835] systemd[1]: Starting linger-users.service...1719machine # [ 53.470807] systemd[1]: linger-users.service: Deactivated successfully.1720machine # [ 53.473127] systemd[1]: Finished linger-users.service.1721machine # [ 53.731412] nixos[3247]: finished switching to system configuration /nix/store/6ygd05jfphrqshrh1bznlwxha64ivipi-nixos-system-machine-test1722machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/timer/bin/switch-to-configuration test 2>&1, in 1.84 seconds)1723machine: must succeed: systemctl show test-timer.timer1724machine: (finished: must succeed: systemctl show test-timer.timer, in 0.04 seconds)1725machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&11726machine # [ 53.984485] nixos[3392]: switching to system configuration /nix/store/3n7mhsq20rzmz1fkcn5q2vxd1hrqsi3z-nixos-system-machine-test1727machine # [ 53.990318] systemd[1]: Stopped target Remote File Systems.1728machine # [ 53.991295] systemd[1]: Stopped target Local File Systems.1729machine # [ 54.263840] systemd[1]: Reload requested from client PID 3392 ('.switch-to-conf') (unit backdoor.service)...1730machine # [ 54.266247] systemd[1]: Reloading...1731machine # [ 54.576085] systemd[1]: Reloading finished in 308 ms.1732machine # [ 54.664406] systemd[544]: Reexecution requested from client PID 3461 ('switch-to-confi')...1733machine # [ 54.665859] systemd[544]: Reexecuting.1734machine # [ 54.714874] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1735machine # [ 54.821544] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1736machine # [ 54.827758] systemd[544]: Reload requested from client PID 3461 ('switch-to-confi')...1737machine # [ 54.831890] systemd[544]: Reloading...1738machine # [ 54.933847] systemd[544]: Reloading finished in 103 ms.1739machine # [ 54.948156] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1740machine # [ 54.957509] systemd[544]: Stopped Run user-specific NixOS activation.1741machine # [ 54.959727] systemd[544]: Stopping Run user-specific NixOS activation...1742machine # [ 54.961890] systemd[544]: Starting Run user-specific NixOS activation...1743machine # [ 54.986642] systemd[544]: Finished Run user-specific NixOS activation.1744machine # [ 54.990837] systemd[1]: Stopped target Reactivate sysinit units.1745machine # [ 54.994241] systemd[1]: Stopping Reactivate sysinit units...1746machine # [ 54.995167] systemd[1]: Reached target Reactivate sysinit units.1747machine # [ 54.997301] systemd[1]: test-timer.timer: Deactivated successfully.1748machine # [ 54.998228] systemd[1]: Stopped test-timer.timer.1749machine # [ 54.998913] systemd[1]: Stopping test-timer.timer...1750machine # [ 54.999635] systemd[1]: Started test-timer.timer.1751machine # [ 55.002746] systemd[1]: Reached target Remote File Systems.1752machine # [ 55.003661] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1753machine # [ 55.008274] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1754machine # [ 55.010389] systemd[1]: Starting linger-users.service...1755machine # [ 55.014112] systemd[1]: Reached target Local File Systems.1756machine # [ 55.023707] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1757machine # [ 55.033128] systemd[1]: Starting Create SUID/SGID Wrappers...1758machine # [ 55.034006] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1759machine # [ 55.035248] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671760machine # [ 55.048645] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1761machine # [ 55.050305] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1762machine # [ 55.051600] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1763machine # [ 55.087940] systemd[1]: linger-users.service: Deactivated successfully.1764machine # [ 55.091469] systemd[1]: Finished linger-users.service.1765machine # [ 55.222085] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1766machine # [ 55.225468] systemd[1]: Finished Create SUID/SGID Wrappers.1767machine # [ 55.479425] nixos[3392]: finished switching to system configuration /nix/store/3n7mhsq20rzmz1fkcn5q2vxd1hrqsi3z-nixos-system-machine-test1768machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&1, in 1.71 seconds)1769machine: must succeed: systemctl show test-timer.timer1770machine: (finished: must succeed: systemctl show test-timer.timer, in 0.04 seconds)1771(finished: subtest: timers, in 3.63 seconds)1772subtest: targets1773machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/hybridSleepModified/bin/switch-to-configuration test 2>&11774machine # [ 55.721928] nixos[3535]: switching to system configuration /nix/store/z6j2cl83wahi266p9b1jyh0my1sp743l-nixos-system-machine-test1775machine # [ 55.724958] systemd[1]: test-timer.timer: Deactivated successfully.1776machine # [ 55.725949] systemd[1]: Stopped test-timer.timer.1777machine # [ 55.727581] systemd[1]: Stopped target Local File Systems.1778machine # [ 55.730122] systemd[1]: Stopped target Remote File Systems.1779machine # [ 55.997700] systemd[1]: Reload requested from client PID 3535 ('.switch-to-conf') (unit backdoor.service)...1780machine # [ 55.999611] systemd[1]: Reloading...1781machine # [ 56.305723] systemd[1]: Reloading finished in 304 ms.1782machine # [ 56.388445] systemd[544]: Reexecution requested from client PID 3604 ('switch-to-confi')...1783machine # [ 56.391660] systemd[544]: Reexecuting.1784machine # [ 56.447475] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1785machine # [ 56.556880] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1786machine # [ 56.564372] systemd[544]: Reload requested from client PID 3604 ('switch-to-confi')...1787machine # [ 56.565741] systemd[544]: Reloading...1788machine # [ 56.669141] systemd[544]: Reloading finished in 101 ms.1789machine # [ 56.684157] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1790machine # [ 56.693324] systemd[544]: Stopped Run user-specific NixOS activation.1791machine # [ 56.695591] systemd[544]: Stopping Run user-specific NixOS activation...1792machine # [ 56.697736] systemd[544]: Starting Run user-specific NixOS activation...1793machine # [ 56.726626] systemd[544]: Finished Run user-specific NixOS activation.1794machine # [ 56.730384] systemd[1]: Stopped target Reactivate sysinit units.1795machine # [ 56.732662] systemd[1]: Stopping Reactivate sysinit units...1796machine # [ 56.733976] systemd[1]: Reached target Reactivate sysinit units.1797machine # [ 56.738101] systemd[1]: Reached target Local File Systems.1798machine # [ 56.738961] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1799machine # [ 56.748518] systemd[1]: Starting Create SUID/SGID Wrappers...1800machine # [ 56.749406] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1801machine # [ 56.750638] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671802machine # [ 56.757472] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1803machine # [ 56.759124] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1804machine # [ 56.767476] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1805machine # [ 56.770559] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1806machine # [ 56.772991] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1807machine # [ 56.776146] systemd[1]: Reached target Remote File Systems.1808machine # [ 56.915155] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1809machine # [ 56.917681] systemd[1]: Finished Create SUID/SGID Wrappers.1810machine # [ 56.921968] systemd[1]: Starting linger-users.service...1811machine # [ 56.958965] systemd[1]: linger-users.service: Deactivated successfully.1812machine # [ 56.961930] systemd[1]: Finished linger-users.service.1813machine # [ 57.215669] nixos[3535]: finished switching to system configuration /nix/store/z6j2cl83wahi266p9b1jyh0my1sp743l-nixos-system-machine-test1814machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/hybridSleepModified/bin/switch-to-configuration test 2>&1, in 1.70 seconds)1815machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/target/bin/switch-to-configuration test 2>&11816machine # [ 57.428152] nixos[3675]: switching to system configuration /nix/store/zk0a8fjj4jmavbxxd4mwhzpjmgn2g0i5-nixos-system-machine-test1817machine # [ 57.433475] systemd[1]: Stopped target Remote File Systems.1818machine # [ 57.434426] systemd[1]: Stopped target Local File Systems.1819machine # [ 57.696299] systemd[1]: Reload requested from client PID 3675 ('.switch-to-conf') (unit backdoor.service)...1820machine # [ 57.698242] systemd[1]: Reloading...1821machine # [ 58.006622] systemd[1]: Reloading finished in 307 ms.1822machine # [ 58.095687] systemd[544]: Reexecution requested from client PID 3744 ('switch-to-confi')...1823machine # [ 58.097962] systemd[544]: Reexecuting.1824machine # [ 58.159190] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1825machine # [ 58.271089] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1826machine # [ 58.277971] systemd[544]: Reload requested from client PID 3744 ('switch-to-confi')...1827machine # [ 58.280380] systemd[544]: Reloading...1828machine # [ 58.383295] systemd[544]: Reloading finished in 101 ms.1829machine # [ 58.400168] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1830machine # [ 58.409385] systemd[544]: Stopped Run user-specific NixOS activation.1831machine # [ 58.411801] systemd[544]: Stopping Run user-specific NixOS activation...1832machine # [ 58.414097] systemd[544]: Starting Run user-specific NixOS activation...1833machine # [ 58.442814] systemd[544]: Finished Run user-specific NixOS activation.1834machine # [ 58.446873] systemd[1]: Stopped target Reactivate sysinit units.1835machine # [ 58.450021] systemd[1]: Stopping Reactivate sysinit units...1836machine # [ 58.450974] systemd[1]: Reached target Reactivate sysinit units.1837machine # [ 58.454393] systemd[1]: Reached target Remote File Systems.1838machine # [ 58.457779] systemd[1]: Reached target test-target.target.1839machine # [ 58.459434] systemd[1]: Starting linger-users.service...1840machine # [ 58.464778] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1841machine # [ 58.466155] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1842machine # [ 58.472765] systemd[1]: Reached target Local File Systems.1843machine # [ 58.480233] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1844machine # [ 58.487723] systemd[1]: Starting Create SUID/SGID Wrappers...1845machine # [ 58.493391] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1846machine # [ 58.494741] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671847machine # [ 58.501983] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1848machine # [ 58.503700] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1849machine # [ 58.509472] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1850machine # [ 58.546894] systemd[1]: linger-users.service: Deactivated successfully.1851machine # [ 58.551111] systemd[1]: Finished linger-users.service.1852machine # [ 58.709034] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1853machine # [ 58.713044] systemd[1]: Finished Create SUID/SGID Wrappers.1854machine # [ 58.968321] nixos[3675]: finished switching to system configuration /nix/store/zk0a8fjj4jmavbxxd4mwhzpjmgn2g0i5-nixos-system-machine-test1855machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/target/bin/switch-to-configuration test 2>&1, in 1.75 seconds)1856machine # [ 59.023357] systemd[1]: Started test-service.service.1857machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/targetModified/bin/switch-to-configuration test 2>&11858machine # [ 59.245299] nixos[3821]: switching to system configuration /nix/store/qfpxqqi3980nzgcxr55cwid60rny10li-nixos-system-machine-test1859machine # [ 59.247879] systemd[1]: Stopped target Local File Systems.1860machine # [ 59.250794] systemd[1]: Stopped target Remote File Systems.1861machine # [ 59.530066] systemd[1]: Reload requested from client PID 3821 ('.switch-to-conf') (unit backdoor.service)...1862machine # [ 59.532267] systemd[1]: Reloading...1863machine # [ 59.843181] systemd[1]: Reloading finished in 309 ms.1864machine # [ 59.929318] systemd[544]: Reexecution requested from client PID 3890 ('switch-to-confi')...1865machine # [ 59.933545] systemd[544]: Reexecuting.1866machine # [ 59.995604] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1867machine # [ 60.108219] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1868machine # [ 60.114947] systemd[544]: Reload requested from client PID 3890 ('switch-to-confi')...1869machine # [ 60.117472] systemd[544]: Reloading...1870machine # [ 60.221620] systemd[544]: Reloading finished in 103 ms.1871machine # [ 60.236171] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1872machine # [ 60.245645] systemd[544]: Stopped Run user-specific NixOS activation.1873machine # [ 60.248117] systemd[544]: Stopping Run user-specific NixOS activation...1874machine # [ 60.250207] systemd[544]: Starting Run user-specific NixOS activation...1875machine # [ 60.279175] systemd[544]: Finished Run user-specific NixOS activation.1876machine # [ 60.282671] systemd[1]: Stopped target Reactivate sysinit units.1877machine # [ 60.285715] systemd[1]: Stopping Reactivate sysinit units...1878machine # [ 60.286600] systemd[1]: Reached target Reactivate sysinit units.1879machine # [ 60.288569] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1880machine # [ 60.289927] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1881machine # [ 60.295586] systemd[1]: Reached target Local File Systems.1882machine # [ 60.299680] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1883machine # [ 60.310201] systemd[1]: Starting Create SUID/SGID Wrappers...1884machine # [ 60.311099] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1885machine # [ 60.312564] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671886machine # [ 60.314956] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1887machine # [ 60.319588] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1888machine # [ 60.323324] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1889machine # [ 60.325085] systemd[1]: Reached target Remote File Systems.1890machine # [ 60.476791] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1891machine # [ 60.479174] systemd[1]: Finished Create SUID/SGID Wrappers.1892machine # [ 60.483670] systemd[1]: Starting linger-users.service...1893machine # [ 60.524562] systemd[1]: linger-users.service: Deactivated successfully.1894machine # [ 60.527624] systemd[1]: Finished linger-users.service.1895machine # [ 60.781834] nixos[3821]: finished switching to system configuration /nix/store/qfpxqqi3980nzgcxr55cwid60rny10li-nixos-system-machine-test1896machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/targetModified/bin/switch-to-configuration test 2>&1, in 1.75 seconds)1897machine: must succeed: systemctl is-active test-service.service1898machine: (finished: must succeed: systemctl is-active test-service.service, in 0.03 seconds)1899machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/targetModifiedStopOnReconfig/bin/switch-to-configuration test 2>&11900machine # [ 61.024967] nixos[3965]: switching to system configuration /nix/store/q6cvrcp42np9g5g3i76k2k5chi5crgcr-nixos-system-machine-test1901machine # [ 61.029512] systemd[1]: Stopped target Local File Systems.1902machine # [ 61.030443] systemd[1]: Stopped target Remote File Systems.1903machine # [ 61.031294] systemd[1]: Stopped target test-target.target.1904machine # [ 61.039297] systemd[1]: Stopping test-service.service...1905machine # [ 61.041338] systemd[1]: test-service.service: Deactivated successfully.1906machine # [ 61.042286] systemd[1]: Stopped test-service.service.1907machine # [ 61.313717] systemd[1]: Reload requested from client PID 3965 ('.switch-to-conf') (unit backdoor.service)...1908machine # [ 61.315724] systemd[1]: Reloading...1909machine # [ 61.641433] systemd[1]: Reloading finished in 324 ms.1910machine # [ 61.732121] systemd[544]: Reexecution requested from client PID 4035 ('switch-to-confi')...1911machine # [ 61.736565] systemd[544]: Reexecuting.1912machine # [ 61.787289] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1913machine # [ 61.902733] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1914machine # [ 61.910680] systemd[544]: Reload requested from client PID 4035 ('switch-to-confi')...1915machine # [ 61.913895] systemd[544]: Reloading...1916machine # [ 62.021031] systemd[544]: Reloading finished in 107 ms.1917machine # [ 62.037998] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1918machine # [ 62.048004] systemd[544]: Stopped Run user-specific NixOS activation.1919machine # [ 62.051018] systemd[544]: Stopping Run user-specific NixOS activation...1920machine # [ 62.053790] systemd[544]: Starting Run user-specific NixOS activation...1921machine # [ 62.092750] systemd[544]: Finished Run user-specific NixOS activation.1922machine # [ 62.098365] systemd[1]: Stopped target Reactivate sysinit units.1923machine # [ 62.101142] systemd[1]: Stopping Reactivate sysinit units...1924machine # [ 62.102324] systemd[1]: Reached target Reactivate sysinit units.1925machine # [ 62.107186] systemd[1]: Reached target test-target.target.1926machine # [ 62.110187] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1927machine # [ 62.111621] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1928machine # [ 62.116553] systemd[1]: Reached target Local File Systems.1929machine # [ 62.117496] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1930machine # [ 62.128790] systemd[1]: Starting Create SUID/SGID Wrappers...1931machine # [ 62.129867] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1932machine # [ 62.131211] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671933machine # [ 62.142470] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1934machine # [ 62.144407] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1935machine # [ 62.145799] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1936machine # [ 62.147063] systemd[1]: Reached target Remote File Systems.1937machine # [ 62.303170] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1938machine # [ 62.308326] systemd[1]: Finished Create SUID/SGID Wrappers.1939machine # [ 62.310466] systemd[1]: Starting linger-users.service...1940machine # [ 62.352934] systemd[1]: linger-users.service: Deactivated successfully.1941machine # [ 62.355063] systemd[1]: Finished linger-users.service.1942machine # [ 62.614198] nixos[3965]: finished switching to system configuration /nix/store/q6cvrcp42np9g5g3i76k2k5chi5crgcr-nixos-system-machine-test1943machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/targetModifiedStopOnReconfig/bin/switch-to-configuration test 2>&1, in 1.80 seconds)1944machine: must fail: systemctl is-active test-service.servce1945machine: (finished: must fail: systemctl is-active test-service.servce, in 0.03 seconds)1946machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&11947machine # [ 62.847050] nixos[4109]: switching to system configuration /nix/store/3n7mhsq20rzmz1fkcn5q2vxd1hrqsi3z-nixos-system-machine-test1948machine # [ 62.850075] systemd[1]: Stopped target test-target.target.1949machine # [ 62.853871] systemd[1]: Stopped target Local File Systems.1950machine # [ 62.854800] systemd[1]: Stopped target Remote File Systems.1951machine # [ 63.139500] systemd[1]: Reload requested from client PID 4109 ('.switch-to-conf') (unit backdoor.service)...1952machine # [ 63.142062] systemd[1]: Reloading...1953machine # [ 63.482158] systemd[1]: Reloading finished in 338 ms.1954machine # [ 63.572457] systemd[544]: Reexecution requested from client PID 4178 ('switch-to-confi')...1955machine # [ 63.576429] systemd[544]: Reexecuting.1956machine # [ 63.632078] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1957machine # [ 63.746614] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1958machine # [ 63.753798] systemd[544]: Reload requested from client PID 4178 ('switch-to-confi')...1959machine # [ 63.758170] systemd[544]: Reloading...1960machine # [ 63.868845] systemd[544]: Reloading finished in 111 ms.1961machine # [ 63.884195] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1962machine # [ 63.893718] systemd[544]: Stopped Run user-specific NixOS activation.1963machine # [ 63.896140] systemd[544]: Stopping Run user-specific NixOS activation...1964machine # [ 63.898598] systemd[544]: Starting Run user-specific NixOS activation...1965machine # [ 63.933195] systemd[544]: Finished Run user-specific NixOS activation.1966machine # [ 63.937261] systemd[1]: Stopped target Reactivate sysinit units.1967machine # [ 63.939311] systemd[1]: Stopping Reactivate sysinit units...1968machine # [ 63.942178] systemd[1]: Reached target Reactivate sysinit units.1969machine # [ 63.946181] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1970machine # [ 63.949524] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1971machine # [ 63.953371] systemd[1]: Reached target Remote File Systems.1972machine # [ 63.955469] systemd[1]: Starting linger-users.service...1973machine # [ 63.959108] systemd[1]: Reached target Local File Systems.1974machine # [ 63.963476] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1975machine # [ 63.975243] systemd[1]: Starting Create SUID/SGID Wrappers...1976machine # [ 63.982018] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1977machine # [ 63.983387] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671978machine # [ 63.987452] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1979machine # [ 63.989368] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1980machine # [ 63.990707] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1981machine # [ 64.048783] systemd[1]: linger-users.service: Deactivated successfully.1982machine # [ 64.053801] systemd[1]: Finished linger-users.service.1983machine # [ 64.177944] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1984machine # [ 64.182121] systemd[1]: Finished Create SUID/SGID Wrappers.1985machine # [ 64.183058] systemd[1]: Started test-timer.timer.1986machine # [ 64.437442] nixos[4109]: finished switching to system configuration /nix/store/3n7mhsq20rzmz1fkcn5q2vxd1hrqsi3z-nixos-system-machine-test1987machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&1, in 1.79 seconds)1988(finished: subtest: targets, in 8.92 seconds)1989subtest: paths1990machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/path/bin/switch-to-configuration test 2>&11991machine # [ 64.670575] nixos[4249]: switching to system configuration /nix/store/xnvwfk05c7zmjhrna7d0yflncvdc5ppy-nixos-system-machine-test1992machine # [ 64.673535] systemd[1]: Stopped target Local File Systems.1993machine # [ 64.677194] systemd[1]: test-timer.timer: Deactivated successfully.1994machine # [ 64.678650] systemd[1]: Stopped test-timer.timer.1995machine # [ 64.679398] systemd[1]: Stopped target Remote File Systems.1996machine # [ 64.971805] systemd[1]: Reload requested from client PID 4249 ('.switch-to-conf') (unit backdoor.service)...1997machine # [ 64.974405] systemd[1]: Reloading...1998machine # [ 65.313937] systemd[1]: Reloading finished in 337 ms.1999machine # [ 65.403307] systemd[544]: Reexecution requested from client PID 4318 ('switch-to-confi')...2000machine # [ 65.407404] systemd[544]: Reexecuting.2001machine # [ 65.459538] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2002machine # [ 65.571375] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2003machine # [ 65.579268] systemd[544]: Reload requested from client PID 4318 ('switch-to-confi')...2004machine # [ 65.582669] systemd[544]: Reloading...2005machine # [ 65.686420] systemd[544]: Reloading finished in 105 ms.2006machine # [ 65.704180] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2007machine # [ 65.713661] systemd[544]: Stopped Run user-specific NixOS activation.2008machine # [ 65.716084] systemd[544]: Stopping Run user-specific NixOS activation...2009machine # [ 65.718216] systemd[544]: Starting Run user-specific NixOS activation...2010machine # [ 65.752113] systemd[544]: Finished Run user-specific NixOS activation.2011machine # [ 65.756113] systemd[1]: Stopped target Reactivate sysinit units.2012machine # [ 65.760328] systemd[1]: Stopping Reactivate sysinit units...2013machine # [ 65.761287] systemd[1]: Reached target Reactivate sysinit units.2014machine # [ 65.763308] systemd[1]: Reached target Remote File Systems.2015machine # [ 65.766380] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2016machine # [ 65.767831] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2017machine # [ 65.770986] systemd[1]: Started test-watch.path.2018machine # [ 65.776190] systemd[1]: Starting linger-users.service...2019machine # [ 65.780073] systemd[1]: Reached target Local File Systems.2020machine # [ 65.788230] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2021machine # [ 65.799813] systemd[1]: Starting Create SUID/SGID Wrappers...2022machine # [ 65.801048] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2023machine # [ 65.802355] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672024machine # [ 65.804913] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2025machine # [ 65.806536] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2026machine # [ 65.807838] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2027machine # [ 65.862755] systemd[1]: linger-users.service: Deactivated successfully.2028machine # [ 65.866744] systemd[1]: Finished linger-users.service.2029machine # [ 65.991144] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2030machine # [ 65.994342] systemd[1]: Finished Create SUID/SGID Wrappers.2031machine # [ 66.251141] nixos[4249]: finished switching to system configuration /nix/store/xnvwfk05c7zmjhrna7d0yflncvdc5ppy-nixos-system-machine-test2032machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/path/bin/switch-to-configuration test 2>&1, in 1.81 seconds)2033machine: must fail: test -f /testpath-modified2034machine: (finished: must fail: test -f /testpath-modified, in 0.02 seconds)2035machine: must succeed: touch /testpath2036machine: (finished: must succeed: touch /testpath, in 0.03 seconds)2037machine: waiting for success: test -f /testpath-modified2038machine # [ 66.318693] systemd[1]: Starting test-watch.service...2039machine # [ 66.361477] systemd[1]: Finished test-watch.service.2040machine: (finished: waiting for success: test -f /testpath-modified, in 1.07 seconds)2041machine: must succeed: rm /testpath /testpath-modified2042machine: (finished: must succeed: rm /testpath /testpath-modified, in 0.02 seconds)2043machine # [ 67.436619] systemd[1]: test-watch.service: Deactivated successfully.2044machine # [ 67.436898] systemd[1]: Stopped test-watch.service.2045machine # Stopping 'test-watch.service', but its triggering units are still active:2046machine # test-watch.path2047machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/pathModified/bin/switch-to-configuration test 2>&12048machine # [ 67.634531] nixos[4414]: switching to system configuration /nix/store/z628lph3srhzm7nv77q7bsvpgapx6c9g-nixos-system-machine-test2049machine # [ 67.638510] systemd[1]: Stopped target Local File Systems.2050machine # [ 67.639407] systemd[1]: Stopped target Remote File Systems.2051machine # [ 67.913154] systemd[1]: Reload requested from client PID 4414 ('.switch-to-conf') (unit backdoor.service)...2052machine # [ 67.915141] systemd[1]: Reloading...2053machine # [ 68.229755] systemd[1]: Reloading finished in 313 ms.2054machine # [ 68.316424] systemd[544]: Reexecution requested from client PID 4484 ('switch-to-confi')...2055machine # [ 68.319727] systemd[544]: Reexecuting.2056machine # [ 68.375466] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2057machine # [ 68.486603] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2058machine # [ 68.494032] systemd[544]: Reload requested from client PID 4484 ('switch-to-confi')...2059machine # [ 68.495374] systemd[544]: Reloading...2060machine # [ 68.599657] systemd[544]: Reloading finished in 103 ms.2061machine # [ 68.616179] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2062machine # [ 68.625655] systemd[544]: Stopped Run user-specific NixOS activation.2063machine # [ 68.628048] systemd[544]: Stopping Run user-specific NixOS activation...2064machine # [ 68.630106] systemd[544]: Starting Run user-specific NixOS activation...2065machine # [ 68.663042] systemd[544]: Finished Run user-specific NixOS activation.2066machine # [ 68.666978] systemd[1]: Stopped target Reactivate sysinit units.2067machine # [ 68.670152] systemd[1]: Stopping Reactivate sysinit units...2068machine # [ 68.671072] systemd[1]: Reached target Reactivate sysinit units.2069machine # [ 68.676377] systemd[1]: Reached target Remote File Systems.2070machine # [ 68.677277] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2071machine # [ 68.678589] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2072machine # [ 68.681414] systemd[1]: Starting linger-users.service...2073machine # [ 68.685700] systemd[1]: Reached target Local File Systems.2074machine # [ 68.698095] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2075machine # [ 68.704102] systemd[1]: Starting Create SUID/SGID Wrappers...2076machine # [ 68.705048] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2077machine # [ 68.706341] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672078machine # [ 68.715650] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2079machine # [ 68.722661] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2080machine # [ 68.726255] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2081machine # [ 68.766716] systemd[1]: linger-users.service: Deactivated successfully.2082machine # [ 68.768579] systemd[1]: Finished linger-users.service.2083machine # [ 68.899396] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2084machine # [ 68.902995] systemd[1]: Finished Create SUID/SGID Wrappers.2085machine # [ 69.157717] nixos[4414]: finished switching to system configuration /nix/store/z628lph3srhzm7nv77q7bsvpgapx6c9g-nixos-system-machine-test2086machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/pathModified/bin/switch-to-configuration test 2>&1, in 1.72 seconds)2087machine: must succeed: touch /testpath2088machine: (finished: must succeed: touch /testpath, in 0.03 seconds)2089machine: must fail: test -f /testpath-modified2090machine: (finished: must fail: test -f /testpath-modified, in 0.02 seconds)2091machine: must succeed: touch /testpath22092machine: (finished: must succeed: touch /testpath2, in 0.03 seconds)2093machine: waiting for success: test -f /testpath-modified2094machine # [ 69.242303] systemd[1]: Starting test-watch.service...2095machine # [ 69.280380] systemd[1]: Finished test-watch.service.2096machine: (finished: waiting for success: test -f /testpath-modified, in 1.05 seconds)2097(finished: subtest: paths, in 5.85 seconds)2098subtest: slices2099machine: must succeed: echo 0 > /proc/sys/vm/panic_on_oom2100machine: (finished: must succeed: echo 0 > /proc/sys/vm/panic_on_oom, in 0.02 seconds)2101machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/slice/bin/switch-to-configuration test 2>&12102machine # [ 70.521016] nixos[4579]: switching to system configuration /nix/store/q65n5g31g94bm4904i0qaaf3x6671rg3-nixos-system-machine-test2103machine # [ 70.525630] systemd[1]: Stopped target Local File Systems.2104machine # [ 70.528676] systemd[1]: Stopped target Remote File Systems.2105machine # [ 70.530298] systemd[1]: test-watch.service: Deactivated successfully.2106machine # [ 70.531364] systemd[1]: Stopped test-watch.service.2107machine # [ 70.533205] systemd[1]: test-watch.path: Deactivated successfully.2108machine # [ 70.535617] systemd[1]: Stopped test-watch.path.2109machine # [ 70.803166] systemd[1]: Reload requested from client PID 4579 ('.switch-to-conf') (unit backdoor.service)...2110machine # [ 70.805486] systemd[1]: Reloading...2111machine # [ 71.118546] systemd[1]: Reloading finished in 311 ms.2112machine # [ 71.206693] systemd[544]: Reexecution requested from client PID 4648 ('switch-to-confi')...2113machine # [ 71.209001] systemd[544]: Reexecuting.2114machine # [ 71.267457] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2115machine # [ 71.380659] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2116machine # [ 71.387285] systemd[544]: Reload requested from client PID 4648 ('switch-to-confi')...2117machine # [ 71.390918] systemd[544]: Reloading...2118machine # [ 71.494785] systemd[544]: Reloading finished in 104 ms.2119machine # [ 71.513787] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2120machine # [ 71.521361] systemd[544]: Stopped Run user-specific NixOS activation.2121machine # [ 71.523750] systemd[544]: Stopping Run user-specific NixOS activation...2122machine # [ 71.526110] systemd[544]: Starting Run user-specific NixOS activation...2123machine # [ 71.559116] systemd[544]: Finished Run user-specific NixOS activation.2124machine # [ 71.563176] systemd[1]: Stopped target Reactivate sysinit units.2125machine # [ 71.566580] systemd[1]: Stopping Reactivate sysinit units...2126machine # [ 71.567458] systemd[1]: Reached target Reactivate sysinit units.2127machine # [ 71.571986] systemd[1]: Reached target Local File Systems.2128machine # [ 71.573826] systemd[1]: Reached target Remote File Systems.2129machine # [ 71.574673] systemd[1]: Starting linger-users.service...2130machine # [ 71.586833] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2131machine # [ 71.596591] systemd[1]: Starting Create SUID/SGID Wrappers...2132machine # [ 71.597522] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2133machine # [ 71.598794] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672134machine # [ 71.613885] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2135machine # [ 71.615677] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2136machine # [ 71.625019] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2137machine # [ 71.630634] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2138machine # [ 71.634356] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2139machine # [ 71.662306] systemd[1]: linger-users.service: Deactivated successfully.2140machine # [ 71.666170] systemd[1]: Finished linger-users.service.2141machine # [ 71.796429] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2142machine # [ 71.799673] systemd[1]: Finished Create SUID/SGID Wrappers.2143machine # [ 72.053882] nixos[4579]: finished switching to system configuration /nix/store/q65n5g31g94bm4904i0qaaf3x6671rg3-nixos-system-machine-test2144machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/slice/bin/switch-to-configuration test 2>&1, in 1.75 seconds)2145machine: must fail: systemctl start testservice.service2146machine # [ 72.099758] systemd[1]: Created slice Slice /testslice.2147machine # [ 72.116878] systemd invoked oom-killer: gfp_mask=0x400dc0(GFP_KERNEL_ACCOUNT|__GFP_ZERO), order=0, oom_score_adj=02148machine # [ 72.116927] CPU: 0 UID: 0 PID: 1 Comm: systemd Not tainted 6.18.54 #1-NixOS PREEMPTLAZY2149machine # [ 72.116931] Hardware name: linux,dummy-virt (DT)2150machine # [ 72.116934] Call trace:2151machine # [ 72.116962] show_stack+0x30/0x98 (C)2152machine # [ 72.116975] dump_stack_lvl+0x60/0x802153machine # [ 72.116985] dump_stack+0x18/0x242154machine # [ 72.116987] dump_header+0x48/0x1982155machine # [ 72.116998] out_of_memory+0x54c/0x5a02156machine # [ 72.117005] mem_cgroup_out_of_memory+0xcc/0xe02157machine # [ 72.117013] try_charge_memcg+0x4c0/0x6b82158machine # [ 72.117018] obj_cgroup_charge_pages+0x70/0x1f02159machine # [ 72.117022] obj_cgroup_charge_account+0x110/0x1d02160machine # [ 72.117025] __memcg_slab_post_alloc_hook+0xf4/0x2d02161machine # [ 72.117028] __kmalloc_cache_noprof+0x4ac/0x5b82162machine # [ 72.117032] mem_cgroup_css_alloc+0x10c/0x8402163machine # [ 72.117042] cgroup_apply_control_enable+0x14c/0x3602164machine # [ 72.117052] cgroup_mkdir+0x150/0x6882165machine # [ 72.117055] kernfs_iop_mkdir+0x94/0x1002166machine # [ 72.117073] vfs_mkdir+0x1a0/0x2902167machine # [ 72.117079] do_mkdirat+0x148/0x1902168machine # [ 72.117082] __arm64_sys_mkdirat+0x54/0xa82169machine # [ 72.117084] invoke_syscall.constprop.0+0x48/0xf02170machine # [ 72.117089] el0_svc_common.constprop.0+0x40/0xf02171machine # [ 72.117092] do_el0_svc+0x24/0x402172machine # [ 72.117093] el0_svc+0x40/0x1b02173machine # [ 72.117103] el0t_64_sync_handler+0xa0/0xe82174machine # [ 72.117107] el0t_64_sync+0x198/0x1a02175machine # [ 72.117111] memory: usage 0kB, limit 0kB, failcnt 182176machine # [ 72.117339] swap: usage 0kB, limit 9007199254740988kB, failcnt 02177machine # [ 72.117351] Memory cgroup stats for /testslice.slice:2178machine # [ 72.117379] anon 02179machine # [ 72.117395] file 02180machine # [ 72.117402] kernel 02181machine # [ 72.117421] kernel_stack 02182machine # [ 72.117428] pagetables 02183machine # [ 72.117434] sec_pagetables 02184machine # [ 72.117441] percpu 02185machine # [ 72.117448] sock 02186machine # [ 72.117454] vmalloc 02187machine # [ 72.117460] shmem 02188machine # [ 72.117466] zswap 02189machine # [ 72.117472] zswapped 02190machine # [ 72.117479] file_mapped 02191machine # [ 72.117485] file_dirty 02192machine # [ 72.117722] file_writeback 02193machine # [ 72.117935] swapcached 02194machine # [ 72.118134] anon_thp 02195machine # [ 72.118373] file_thp 02196machine # [ 72.156865] shmem_thp 02197machine # [ 72.157122] inactive_anon 02198machine # [ 72.157284] active_anon 02199machine # [ 72.157435] inactive_file 02200machine # [ 72.157580] active_file 02201machine # [ 72.157722] unevictable 02202machine # [ 72.157861] slab_reclaimable 02203machine # [ 72.158000] slab_unreclaimable 02204machine # [ 72.158139] slab 02205machine # [ 72.158301] hugetlb 02206machine # [ 72.158439] workingset_refault_anon 02207machine # [ 72.165441] workingset_refault_file 02208machine # [ 72.165660] workingset_activate_anon 02209machine # [ 72.165800] workingset_activate_file 02210machine # [ 72.165934] workingset_restore_anon 02211machine # [ 72.166065] workingset_restore_file 02212machine # [ 72.166195] workingset_nodereclaim 02213machine # [ 72.166346] pgdemote_kswapd 02214machine # [ 72.172189] pgdemote_direct 02215machine # [ 72.172406] pgdemote_khugepaged 02216machine # [ 72.172535] pgdemote_proactive 02217machine # [ 72.172659] pgpromote_success 02218machine # [ 72.172784] pgscan 02219machine # [ 72.172905] pgsteal 02220machine # [ 72.173026] pswpin 02221machine # [ 72.173146] pswpout 02222machine # [ 72.173264] pgscan_kswapd 02223machine # [ 72.173383] pgscan_direct 02224machine # [ 72.173501] pgscan_khugepaged 02225machine # [ 72.173615] pgscan_proactive 02226machine # [ 72.173726] pgsteal_kswapd 02227machine # [ 72.173849] pgsteal_direct 02228machine # [ 72.173957] pgsteal_khugepaged 02229machine # [ 72.174065] pgsteal_proactive 02230machine # [ 72.174173] pgfault 02231machine # [ 72.174302] pgmajfault 02232machine # [ 72.174410] pgrefill 02233machine # [ 72.184868] pgactivate 02234machine # [ 72.185073] pgdeactivate 02235machine # [ 72.185191] pglazyfree 02236machine # [ 72.185303] pglazyfreed 02237machine # [ 72.185414] swpin_zero 02238machine # [ 72.185525] swpout_zero 02239machine # [ 72.185637] zswpin 02240machine # [ 72.185746] zswpout 02241machine # [ 72.185854] zswpwb 02242machine # [ 72.185962] thp_fault_alloc 02243machine # [ 72.186071] thp_collapse_alloc 02244machine # [ 72.186180] thp_swpout 02245machine # [ 72.186386] thp_swpout_fallback 02246machine # [ 72.193651] numa_pages_migrated 02247machine # [ 72.193837] numa_pte_updates 02248machine # [ 72.193949] numa_hint_faults 02249machine # [ 72.194058] Tasks state (memory values in pages):2250machine # [ 72.194171] [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name2251machine # [ 72.194386] Out of memory and no killable processes...2252machine # [ 72.185627] systemd[1]: testservice.service: Failed to create cgroup /testslice.slice/testservice.service: Cannot allocate memory2253machine # [ 72.188757] systemd[1]: testservice.service: Failed to spawn 'start' task: Cannot allocate memory2254machine # [ 72.193437] systemd[1]: testservice.service: Failed with result 'resources'.2255machine # [ 72.195130] systemd[1]: Failed to start testservice.service.2256machine # Job for testservice.service failed because of unavailable resources or another system error.2257machine # See "systemctl status testservice.service" and "journalctl -xeu testservice.service" for details.2258machine: (finished: must fail: systemctl start testservice.service, in 0.14 seconds)2259machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/sliceModified/bin/switch-to-configuration test 2>&12260machine # [ 72.400228] nixos[4723]: switching to system configuration /nix/store/4r209cnxzfydcbisd2dvsihaqkr4h4xh-nixos-system-machine-test2261machine # [ 72.402722] systemd[1]: Stopped target Local File Systems.2262machine # [ 72.405263] systemd[1]: Stopped target Remote File Systems.2263machine # [ 72.694454] systemd[1]: Reload requested from client PID 4723 ('.switch-to-conf') (unit backdoor.service)...2264machine # [ 72.696581] systemd[1]: Reloading...2265machine # [ 73.018886] systemd[1]: Reloading finished in 320 ms.2266machine # [ 73.101202] systemd[544]: Reexecution requested from client PID 4792 ('switch-to-confi')...2267machine # [ 73.105677] systemd[544]: Reexecuting.2268machine # [ 73.155073] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2269machine # [ 73.266073] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2270machine # [ 73.272662] systemd[544]: Reload requested from client PID 4792 ('switch-to-confi')...2271machine # [ 73.275132] systemd[544]: Reloading...2272machine # [ 73.381972] systemd[544]: Reloading finished in 105 ms.2273machine # [ 73.396175] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2274machine # [ 73.405548] systemd[544]: Stopped Run user-specific NixOS activation.2275machine # [ 73.407887] systemd[544]: Stopping Run user-specific NixOS activation...2276machine # [ 73.410114] systemd[544]: Starting Run user-specific NixOS activation...2277machine # [ 73.444129] systemd[544]: Finished Run user-specific NixOS activation.2278machine # [ 73.447678] systemd[1]: Stopped target Reactivate sysinit units.2279machine # [ 73.450085] systemd[1]: Stopping Reactivate sysinit units...2280machine # [ 73.450977] systemd[1]: Reached target Reactivate sysinit units.2281machine # [ 73.453765] systemd[1]: Reached target Local File Systems.2282machine # [ 73.456842] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2283machine # [ 73.461684] systemd[1]: Starting Create SUID/SGID Wrappers...2284machine # [ 73.462629] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2285machine # [ 73.463902] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672286machine # [ 73.480813] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2287machine # [ 73.482537] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2288machine # [ 73.483952] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2289machine # [ 73.485552] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2290machine # [ 73.486921] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2291machine # [ 73.489310] systemd[1]: Reached target Remote File Systems.2292machine # [ 73.637277] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2293machine # [ 73.642294] systemd[1]: Finished Create SUID/SGID Wrappers.2294machine # [ 73.644709] systemd[1]: Starting linger-users.service...2295machine # [ 73.686118] systemd[1]: linger-users.service: Deactivated successfully.2296machine # [ 73.689166] systemd[1]: Finished linger-users.service.2297machine # [ 73.944542] nixos[4723]: finished switching to system configuration /nix/store/4r209cnxzfydcbisd2dvsihaqkr4h4xh-nixos-system-machine-test2298machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/sliceModified/bin/switch-to-configuration test 2>&1, in 1.75 seconds)2299machine: must succeed: systemctl start testservice.service2300machine # [ 73.989915] systemd[1]: Starting testservice.service...2301machine # [ 74.025481] systemd[1]: Finished testservice.service.2302machine: (finished: must succeed: systemctl start testservice.service, in 0.09 seconds)2303machine: must succeed: echo 1 > /proc/sys/vm/panic_on_oom2304machine: (finished: must succeed: echo 1 > /proc/sys/vm/panic_on_oom, in 0.02 seconds)2305(finished: subtest: slices, in 3.77 seconds)2306subtest: dbus reloads2307machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/bin/switch-to-configuration test 2>&12308machine # [ 74.239298] nixos[4872]: switching to system configuration /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test2309machine # [ 74.243770] systemd[1]: testservice.service: Deactivated successfully.2310machine # [ 74.245268] systemd[1]: Stopped testservice.service.2311machine # [ 74.246567] systemd[1]: Stopped target Local File Systems.2312machine # [ 74.249167] systemd[1]: Removed slice Slice /testslice.2313machine # [ 74.250554] systemd[1]: Stopped target Remote File Systems.2314machine # [ 74.500824] systemd[1]: Reload requested from client PID 4872 ('.switch-to-conf') (unit backdoor.service)...2315machine # [ 74.502777] systemd[1]: Reloading...2316machine # [ 74.804176] systemd[1]: Reloading finished in 299 ms.2317machine # [ 74.890699] systemd[544]: Reexecution requested from client PID 4941 ('switch-to-confi')...2318machine # [ 74.895082] systemd[544]: Reexecuting.2319machine # [ 74.952375] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2320machine # [ 75.067356] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2321machine # [ 75.074269] systemd[544]: Reload requested from client PID 4941 ('switch-to-confi')...2322machine # [ 75.078084] systemd[544]: Reloading...2323machine # [ 75.181800] systemd[544]: Reloading finished in 104 ms.2324machine # [ 75.196173] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2325machine # [ 75.205536] systemd[544]: Stopped Run user-specific NixOS activation.2326machine # [ 75.207989] systemd[544]: Stopping Run user-specific NixOS activation...2327machine # [ 75.210145] systemd[544]: Starting Run user-specific NixOS activation...2328machine # [ 75.244077] systemd[544]: Finished Run user-specific NixOS activation.2329machine # [ 75.248421] systemd[1]: Stopped target Reactivate sysinit units.2330machine # [ 75.252981] systemd[1]: Stopping Reactivate sysinit units...2331machine # [ 75.253897] systemd[1]: Reached target Reactivate sysinit units.2332machine # [ 75.257756] systemd[1]: Reached target Remote File Systems.2333machine # [ 75.260327] systemd[1]: Starting linger-users.service...2334machine # [ 75.264504] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2335machine # [ 75.265913] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2336machine # [ 75.272961] systemd[1]: Reached target Local File Systems.2337machine # [ 75.275340] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2338machine # [ 75.283765] systemd[1]: Starting Create SUID/SGID Wrappers...2339machine # [ 75.293225] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2340machine # [ 75.294768] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672341machine # [ 75.304149] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2342machine # [ 75.305890] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2343machine # [ 75.307250] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2344machine # [ 75.350965] systemd[1]: linger-users.service: Deactivated successfully.2345machine # [ 75.353415] systemd[1]: Finished linger-users.service.2346machine # [ 75.489153] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2347machine # [ 75.491025] systemd[1]: Finished Create SUID/SGID Wrappers.2348machine # [ 75.747441] nixos[4872]: finished switching to system configuration /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test2349machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.70 seconds)2350machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/dbusReload/bin/switch-to-configuration test 2>&12351machine # [ 75.985324] nixos[5012]: switching to system configuration /nix/store/bvk2m2f7cvv3chhpfdr2sq5xxbnpmjv1-nixos-system-machine-test2352machine # [ 75.988245] systemd[1]: Stopped target Remote File Systems.2353machine # [ 75.991133] systemd[1]: Stopped target Local File Systems.2354machine # [ 76.278030] systemd[1]: Reload requested from client PID 5012 ('.switch-to-conf') (unit backdoor.service)...2355machine # [ 76.280169] systemd[1]: Reloading...2356machine # [ 76.596707] systemd[1]: Reloading finished in 315 ms.2357machine # [ 76.684487] systemd[544]: Reexecution requested from client PID 5081 ('switch-to-confi')...2358machine # [ 76.686242] systemd[544]: Reexecuting.2359machine # [ 76.752171] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2360machine # [ 76.872173] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2361machine # [ 76.879498] systemd[544]: Reload requested from client PID 5081 ('switch-to-confi')...2362machine # [ 76.883697] systemd[544]: Reloading...2363machine # [ 76.989499] systemd[544]: Reloading finished in 106 ms.2364machine # [ 77.004183] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2365machine # [ 77.013421] systemd[544]: Stopped Run user-specific NixOS activation.2366machine # [ 77.015807] systemd[544]: Stopping Run user-specific NixOS activation...2367machine # [ 77.018032] systemd[544]: Starting Run user-specific NixOS activation...2368machine # [ 77.052910] systemd[544]: Finished Run user-specific NixOS activation.2369machine # [ 77.057762] systemd[1]: Stopped target Reactivate sysinit units.2370machine # [ 77.063319] systemd[1]: Stopping Reactivate sysinit units...2371machine # [ 77.066124] systemd[1]: Reached target Reactivate sysinit units.2372machine # [ 77.067248] systemd[1]: Reloading D-Bus System Message Bus...2373machine # [ 77.071303] dbus-broker-launch[438]: Caught SIGHUP, trigger reload.2374machine # [ 77.072730] dbus-broker-launch[438]: Looking up NSS user entry for 'systemd-timesync'...2375machine # [ 77.074676] dbus-broker-launch[438]: NSS returned no entry for 'systemd-timesync'2376machine # [ 77.075952] dbus-broker-launch[438]: Invalid user-name in /nix/store/n9gdx9aa5f6rhwnpm4q6k6k9ys4c0vjz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2377machine # [ 77.097413] systemd[1]: Reloaded D-Bus System Message Bus.2378machine # [ 77.102936] systemd[1]: Reached target Local File Systems.2379machine # [ 77.107189] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2380machine # [ 77.112531] systemd[1]: Starting Create SUID/SGID Wrappers...2381machine # [ 77.113581] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2382machine # [ 77.114925] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672383machine # [ 77.129281] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2384machine # [ 77.131014] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2385machine # [ 77.137070] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2386machine # [ 77.138467] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2387machine # [ 77.139967] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2388machine # [ 77.143033] systemd[1]: Reached target Remote File Systems.2389machine # [ 77.296429] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2390machine # [ 77.300737] systemd[1]: Finished Create SUID/SGID Wrappers.2391machine # [ 77.303402] systemd[1]: Starting linger-users.service...2392machine # [ 77.345114] systemd[1]: linger-users.service: Deactivated successfully.2393machine # [ 77.348123] systemd[1]: Finished linger-users.service.2394machine # [ 77.603732] nixos[5012]: finished switching to system configuration /nix/store/bvk2m2f7cvv3chhpfdr2sq5xxbnpmjv1-nixos-system-machine-test2395machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/dbusReload/bin/switch-to-configuration test 2>&1, in 1.86 seconds)2396(finished: subtest: dbus reloads, in 3.55 seconds)2397subtest: generators2398machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/generators/bin/switch-to-configuration test 2>&12399machine # [ 77.815894] nixos[5152]: switching to system configuration /nix/store/mxyaghc84hfqxx4ii0596wcx83556gcw-nixos-system-machine-test2400machine # [ 77.821000] systemd[1]: Stopped target Remote File Systems.2401machine # [ 77.822724] systemd[1]: Stopped target Local File Systems.2402machine # [ 78.097029] systemd[1]: Reload requested from client PID 5152 ('.switch-to-conf') (unit backdoor.service)...2403machine # [ 78.099108] systemd[1]: Reloading...2404machine # [ 78.424846] systemd[1]: Reloading finished in 323 ms.2405machine # [ 78.512137] systemd[544]: Reexecution requested from client PID 5223 ('switch-to-confi')...2406machine # [ 78.513642] systemd[544]: Reexecuting.2407machine # [ 78.568743] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2408machine # [ 78.684724] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2409machine # [ 78.692638] systemd[544]: Reload requested from client PID 5223 ('switch-to-confi')...2410machine # [ 78.694140] systemd[544]: Reloading...2411machine # [ 78.803349] systemd[544]: Reloading finished in 107 ms.2412machine # [ 78.820205] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2413machine # [ 78.831025] systemd[544]: Stopped Run user-specific NixOS activation.2414machine # [ 78.833719] systemd[544]: Stopping Run user-specific NixOS activation...2415machine # [ 78.835976] systemd[544]: Starting Run user-specific NixOS activation...2416machine # [ 78.870273] systemd[544]: Finished Run user-specific NixOS activation.2417machine # [ 78.874712] systemd[1]: Stopped target Reactivate sysinit units.2418machine # [ 78.877097] systemd[1]: Stopping Reactivate sysinit units...2419machine # [ 78.878052] systemd[1]: Reached target Reactivate sysinit units.2420machine # [ 78.882152] systemd[1]: Reloading D-Bus System Message Bus...2421machine # [ 78.884594] dbus-broker-launch[438]: Caught SIGHUP, trigger reload.2422machine # [ 78.888325] dbus-broker-launch[438]: Looking up NSS user entry for 'systemd-timesync'...2423machine # [ 78.890538] dbus-broker-launch[438]: NSS returned no entry for 'systemd-timesync'2424machine # [ 78.892185] dbus-broker-launch[438]: Invalid user-name in /nix/store/n9gdx9aa5f6rhwnpm4q6k6k9ys4c0vjz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2425machine # [ 78.908250] systemd[1]: Reloaded D-Bus System Message Bus.2426machine # [ 78.914798] systemd[1]: Reached target Local File Systems.2427machine # [ 78.921318] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2428machine # [ 78.922972] systemd[1]: Starting Create SUID/SGID Wrappers...2429machine # [ 78.923938] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2430machine # [ 78.934131] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672431machine # [ 78.941998] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2432machine # [ 78.943950] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2433machine # [ 78.947149] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2434machine # [ 78.952172] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2435machine # [ 78.953806] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2436machine # [ 78.956106] systemd[1]: Reached target Remote File Systems.2437machine # [ 79.113129] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2438machine # [ 79.118467] systemd[1]: Finished Create SUID/SGID Wrappers.2439machine # [ 79.121053] systemd[1]: Starting linger-users.service...2440machine # [ 79.163582] systemd[1]: linger-users.service: Deactivated successfully.2441machine # [ 79.167224] systemd[1]: Finished linger-users.service.2442machine # [ 79.421510] nixos[5152]: finished switching to system configuration /nix/store/mxyaghc84hfqxx4ii0596wcx83556gcw-nixos-system-machine-test2443machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/specialisation/generators/bin/switch-to-configuration test 2>&1, in 1.82 seconds)2444machine: must succeed: systemctl start simple-generated.service && systemctl is-active simple-generated.service2445machine # [ 79.474405] systemd[1]: Started simple-generated.service.2446machine: (finished: must succeed: systemctl start simple-generated.service && systemctl is-active simple-generated.service, in 0.07 seconds)2447machine: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/bin/switch-to-configuration test 2>&12448machine # [ 79.695507] nixos[5301]: switching to system configuration /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test2449machine # [ 79.698232] systemd[1]: Stopped target Local File Systems.2450machine # [ 79.701013] systemd[1]: Stopped target Remote File Systems.2451machine # [ 79.979101] systemd[1]: Reload requested from client PID 5301 ('.switch-to-conf') (unit backdoor.service)...2452machine # [ 79.981578] systemd[1]: Reloading...2453machine # [ 80.305750] systemd[1]: Reloading finished in 322 ms.2454machine # [ 80.400433] systemd[544]: Reexecution requested from client PID 5371 ('switch-to-confi')...2455machine # [ 80.403873] systemd[544]: Reexecuting.2456machine # [ 80.459531] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2457machine # [ 80.573001] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2458machine # [ 80.580342] systemd[544]: Reload requested from client PID 5371 ('switch-to-confi')...2459machine # [ 80.584315] systemd[544]: Reloading...2460machine # [ 80.692650] systemd[544]: Reloading finished in 109 ms.2461machine # [ 80.708196] systemd[544]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2462machine # [ 80.717888] systemd[544]: Stopped Run user-specific NixOS activation.2463machine # [ 80.720471] systemd[544]: Stopping Run user-specific NixOS activation...2464machine # [ 80.722753] systemd[544]: Starting Run user-specific NixOS activation...2465machine # [ 80.756252] systemd[544]: Finished Run user-specific NixOS activation.2466machine # [ 80.760833] systemd[1]: Stopped target Reactivate sysinit units.2467machine # [ 80.763964] systemd[1]: Stopping Reactivate sysinit units...2468machine # [ 80.765930] systemd[1]: Reached target Reactivate sysinit units.2469machine # [ 80.766842] systemd[1]: Reached target Remote File Systems.2470machine # [ 80.768607] systemd[1]: Reached target Local File Systems.2471machine # [ 80.775019] systemd[1]: Starting linger-users.service...2472machine # [ 80.784161] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2473machine # [ 80.785628] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2474machine # [ 80.795529] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2475machine # [ 80.810130] systemd[1]: Starting Create SUID/SGID Wrappers...2476machine # [ 80.811094] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2477machine # [ 80.816911] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672478machine # [ 80.819246] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2479machine # [ 80.827949] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2480machine # [ 80.832466] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2481machine # [ 80.870216] systemd[1]: linger-users.service: Deactivated successfully.2482machine # [ 80.874918] systemd[1]: Finished linger-users.service.2483machine # [ 81.007973] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2484machine # [ 81.010403] systemd[1]: Finished Create SUID/SGID Wrappers.2485machine # [ 81.267076] nixos[5301]: finished switching to system configuration /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test2486machine: (finished: must succeed: /nix/store/gskn86xzpgz867jcm0fcqy7vvzisfm2a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.77 seconds)2487machine: must succeed: systemctl is-active simple-generated.service2488machine: (finished: must succeed: systemctl is-active simple-generated.service, in 0.03 seconds)2489(finished: subtest: generators, in 3.69 seconds)2490(finished: run the VM test script, in 82.07 seconds)2491test script finished in 82.15s2492cleanup2493kill QemuMachine (pid 45)2494machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2495machine # [2026-09-29T03:51:22Z INFO virtiofsd] Client disconnected, shutting down2496machine # [2026-09-29T03:51:22Z INFO virtiofsd] Client disconnected, shutting down2497machine # [2026-09-29T03:51:22Z INFO virtiofsd] Client disconnected, shutting down2498(finished: cleanup, in 0.16 seconds)2499additionally exposed symbols:2500 machine, other,2501 vlan1,2502 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