tribuchet: building on eliza
Machine state will be reset. To keep it, pass --keep-machine-state
start all VLans
(finished: start all VLans, in 0.00 seconds)
start vhost-device-vsock
(finished: start vhost-device-vsock, in 0.00 seconds)
Test will time out and terminate in 600.0 seconds
run the VM test script
start all VMs
host1: starting vm
server: starting vm
server # Disk image does not exist, creating the virtualisation disk image...
server # Formatting '/build/vm-state-server/tmp.wq7OdFu3DG', fmt=raw size=1073741824
server # mke2fs 1.47.4 (6-Mar-2025)
server: QEMU running (pid 55)
server # Discarding device blocks:      0/262144             done
server # Creating filesystem with 262144 4k blocks and 65536 inodes
server # Filesystem UUID: 7504b1f0-3e09-464e-881f-aa277ed43f01
host1 # Disk image does not exist, creating the virtualisation disk image...
host1: QEMU running (pid 54)
host1 # Formatting '/build/vm-state-host1/tmp.AJRIdMwNLZ', fmt=raw size=1073741824
server # Superblock backups stored on blocks:
host1 # mke2fs 1.47.4 (6-Mar-2025)
server # 	32768, 98304, 163840, 229376
server # 
host1 # Discarding device blocks:      0/262144             done
server # Allocating group tables: 0/8   done
host1 # Creating filesystem with 262144 4k blocks and 65536 inodes
server # Writing inode tables: 0/8   done
host1 # Filesystem UUID: 39a96e25-1b9c-4cae-8a87-a91134af90a2
server # Creating journal (8192 blocks): done
host1 # Superblock backups stored on blocks:
server # Writing superblocks and filesystem accounting information: 0/8   done
host1 # 	32768, 98304, 163840, 229376
server # 
host1 # 
server # Virtualisation disk image created.
host1 # Allocating group tables: 0/8   done
server # Starting virtiofs daemons...
host1 # Writing inode tables: 0/8   done
server # [2026-09-30T09:10:44Z WARN  virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)
host1 # Creating journal (8192 blocks): done
server # [2026-09-30T09:10:44Z WARN  virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether
host1 # Writing superblocks and filesystem accounting information: 0/8   done
server # [2026-09-30T09:10:44Z WARN  virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)
host1 # 
server # [2026-09-30T09:10:44Z WARN  virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether
host1 # Virtualisation disk image created.
server # [2026-09-30T09:10:44Z INFO  virtiofsd] Waiting for vhost-user socket connection...
host1 # Starting virtiofs daemons...
server # [2026-09-30T09:10:44Z INFO  virtiofsd] Waiting for vhost-user socket connection...
host1 # [2026-09-30T09:10:44Z WARN  virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)
server # [2026-09-30T09:10:44Z WARN  virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)
host1 # [2026-09-30T09:10:44Z WARN  virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether
server # [2026-09-30T09:10:44Z WARN  virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether
host1 # [2026-09-30T09:10:44Z INFO  virtiofsd] Waiting for vhost-user socket connection...
server # [2026-09-30T09:10:44Z INFO  virtiofsd] Waiting for vhost-user socket connection...
host1 # [2026-09-30T09:10:44Z WARN  virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)
server # [2026-09-30T09:10:44Z INFO  virtiofsd] Client connected, servicing requests
host1 # [2026-09-30T09:10:44Z WARN  virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether
server # [2026-09-30T09:10:44Z INFO  virtiofsd] Client connected, servicing requests
host1 # [2026-09-30T09:10:44Z INFO  virtiofsd] Waiting for vhost-user socket connection...
server # [2026-09-30T09:10:44Z INFO  virtiofsd] Client connected, servicing requests
host1 # [2026-09-30T09:10:44Z WARN  virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)
(finished: start all VMs, in 0.45 seconds)
host1 # [2026-09-30T09:10:44Z WARN  virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether
host1 # [2026-09-30T09:10:44Z INFO  virtiofsd] Waiting for vhost-user socket connection...
server: waiting for unit step-ca.service
host1 # [2026-09-30T09:10:44Z INFO  virtiofsd] Client connected, servicing requests
host1 # [2026-09-30T09:10:44Z INFO  virtiofsd] Client connected, servicing requests
server: waiting for the VM to finish booting
host1 # [2026-09-30T09:10:44Z INFO  virtiofsd] Client connected, servicing requests
server # [    0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]
server # [    0.000000] Linux version 6.18.53 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 21 13:06:06 UTC 2026
server # [    0.000000] KASLR enabled
server # [    0.000000] random: crng init done
server # [    0.000000] Machine model: linux,dummy-virt
server # [    0.000000] efi: UEFI not found.
server # [    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT
server # [    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x00000000bfffffff]
server # [    0.000000] NODE_DATA(0) allocated [mem 0xbfded700-0xbfdf0e7f]
server # [    0.000000] Zone ranges:
server # [    0.000000]   DMA      [mem 0x0000000040000000-0x00000000bfffffff]
server # [    0.000000]   DMA32    empty
server # [    0.000000]   Normal   empty
server # [    0.000000]   Device   empty
server # [    0.000000] Movable zone start for each node
server # [    0.000000] Early memory node ranges
server # [    0.000000]   node   0: [mem 0x0000000040000000-0x00000000bfffffff]
server # [    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x00000000bfffffff]
server # [    0.000000] cma: Reserved 32 MiB at 0x00000000bbc00000
server # [    0.000000] psci: probing for conduit method from DT.
server # [    0.000000] psci: PSCIv1.3 detected in firmware.
server # [    0.000000] psci: Using standard PSCI v0.2 function IDs
server # [    0.000000] psci: Trusted OS migration not required
server # [    0.000000] psci: SMC Calling Convention v1.1
server # [    0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)
server # [    0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296
server # [    0.000000] Detected PIPT I-cache on CPU0
server # [    0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)
server # [    0.000000] CPU features: detected: GICv3 CPU interface
server # [    0.000000] CPU features: detected: Spectre-v4
server # [    0.000000] CPU features: detected: Spectre-BHB
server # [    0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38
server # [    0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23
server # [    0.000000] alternatives: applying boot alternatives
server # [    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/w6gnz0kfj9gf6zsjcaxac0hn63r4ik34-nixos-system-server-test/init regInfo=/nix/.ro-store/hfngqi5wvqjbcbj9lhdcvrgcdqkhgxf5-closure-info/registration console=ttyAMA0,115200n8 console=tty0
server # [    0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/hfngqi5wvqjbcbj9lhdcvrgcdqkhgxf5-closure-info/registration", will be passed to user space.
server # [    0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes
host1 # [    0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]
server # [    0.000000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
host1 # [    0.000000] Linux version 6.18.53 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 21 13:06:06 UTC 2026
server # [    0.000000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
host1 # [    0.000000] KASLR enabled
host1 # [    0.000000] random: crng init done
server # [    0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB
host1 # [    0.000000] Machine model: linux,dummy-virt
server # [    0.000000] software IO TLB: area num 1.
host1 # [    0.000000] efi: UEFI not found.
server # [    0.000000] software IO TLB: mapped [mem 0x00000000bb800000-0x00000000bba00000] (2MB)
host1 # [    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT
server # [    0.000000] Fallback order for Node 0: 0
host1 # [    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x00000000bfffffff]
server # [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 524288
host1 # [    0.000000] NODE_DATA(0) allocated [mem 0xbfded700-0xbfdf0e7f]
server # [    0.000000] Policy zone: DMA
host1 # [    0.000000] Zone ranges:
server # [    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
host1 # [    0.000000]   DMA      [mem 0x0000000040000000-0x00000000bfffffff]
host1 # [    0.000000]   DMA32    empty
server # [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
host1 # [    0.000000]   Normal   empty
host1 # [    0.000000]   Device   empty
server # [    0.000000] allocated 4194304 bytes of page_ext
host1 # [    0.000000] Movable zone start for each node
server # [    0.000000] ftrace: allocating 74963 entries in 294 pages
host1 # [    0.000000] Early memory node ranges
server # [    0.000000] ftrace: allocated 294 pages with 4 groups
host1 # [    0.000000]   node   0: [mem 0x0000000040000000-0x00000000bfffffff]
server # [    0.000000] rcu: Hierarchical RCU implementation.
server # [    0.000000] rcu: 	RCU event tracing is enabled.
host1 # [    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x00000000bfffffff]
host1 # [    0.000000] cma: Reserved 32 MiB at 0x00000000bbc00000
server # [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
host1 # [    0.000000] psci: probing for conduit method from DT.
server # [    0.000000] 	Trampoline variant of Tasks RCU enabled.
host1 # [    0.000000] psci: PSCIv1.3 detected in firmware.
server # [    0.000000] 	Rude variant of Tasks RCU enabled.
host1 # [    0.000000] psci: Using standard PSCI v0.2 function IDs
server # [    0.000000] 	Tracing variant of Tasks RCU enabled.
host1 # [    0.000000] psci: Trusted OS migration not required
server # [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
host1 # [    0.000000] psci: SMC Calling Convention v1.1
server # [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
host1 # [    0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)
server # [    0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
host1 # [    0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296
host1 # [    0.000000] Detected PIPT I-cache on CPU0
server # [    0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
host1 # [    0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)
host1 # [    0.000000] CPU features: detected: GICv3 CPU interface
server # [    0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
host1 # [    0.000000] CPU features: detected: Spectre-v4
server # [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
host1 # [    0.000000] CPU features: detected: Spectre-BHB
server # [    0.000000] GICv3: 256 SPIs implemented
server # [    0.000000] GICv3: 0 Extended SPIs implemented
host1 # [    0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38
server # [    0.000000] Root IRQ handler: gic_handle_irq
host1 # [    0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23
server # [    0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI
host1 # [    0.000000] alternatives: applying boot alternatives
server # [    0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0
server # [    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000
server # [    0.000000] ITS [mem 0x08080000-0x0809ffff]
server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ae0000 (indirect, esz 8, psz 64K, shr 1)
host1 # [    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/8l3qg5rvgynixnmqxwr6gn0bsscd062g-nixos-system-host1-test/init regInfo=/nix/.ro-store/xsvpdnnyhv9gj1m2n75lldqvss398pjc-closure-info/registration console=ttyAMA0,115200n8 console=tty0
server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44af0000 (flat, esz 8, psz 64K, shr 1)
server # [    0.000000] GICv3: using LPI property table @0x0000000044b10000
host1 # [    0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/xsvpdnnyhv9gj1m2n75lldqvss398pjc-closure-info/registration", will be passed to user space.
server # [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044b20000
host1 # [    0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes
server # [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
host1 # [    0.000000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
server # [    0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
host1 # [    0.000000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
server # [    0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).
host1 # [    0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB
host1 # [    0.000000] software IO TLB: area num 1.
server # [    0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
host1 # [    0.000000] software IO TLB: mapped [mem 0x00000000bb800000-0x00000000bba00000] (2MB)
server # [    0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns
host1 # [    0.000000] Fallback order for Node 0: 0
server # [    0.000033] arm-pv: using stolen time PV
host1 # [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 524288
host1 # [    0.000000] Policy zone: DMA
server # [    0.000456] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
host1 # [    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
server # [    0.000745] Console: colour dummy device 80x25
server # [    0.000754] printk: legacy console [tty0] enabled
host1 # [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
host1 # [    0.000000] allocated 4194304 bytes of page_ext
host1 # [    0.000000] ftrace: allocating 74963 entries in 294 pages
server # [    0.000954] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)
server # [    0.000961] pid_max: default: 32768 minimum: 301
host1 # [    0.000000] ftrace: allocated 294 pages with 4 groups
host1 # [    0.000000] rcu: Hierarchical RCU implementation.
server # [    0.001039] LSM: initializing lsm=capability,landlock,yama,bpf,ima
host1 # [    0.000000] rcu: 	RCU event tracing is enabled.
server # [    0.001178] landlock: Up and running.
server # [    0.001181] Yama: becoming mindful.
host1 # [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
server # [    0.001668] LSM support for eBPF active
host1 # [    0.000000] 	Trampoline variant of Tasks RCU enabled.
server # [    0.001821] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
host1 # [    0.000000] 	Rude variant of Tasks RCU enabled.
host1 # [    0.000000] 	Tracing variant of Tasks RCU enabled.
server # [    0.001854] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
server # [    0.003774] rcu: Hierarchical SRCU implementation.
host1 # [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
server # [    0.003780] rcu: 	Max phase no-delay instances is 1000.
host1 # [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
server # [    0.005228] fsl-mc MSI: its@8080000 domain created
server # [    0.005348] EFI services will not be available.
host1 # [    0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
server # [    0.005489] smp: Bringing up secondary CPUs ...
server # [    0.005498] smp: Brought up 1 node, 1 CPU
host1 # [    0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
server # [    0.005501] SMP: Total of 1 processors activated.
server # [    0.005504] CPU: All CPU(s) started at EL1
host1 # [    0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
server # [    0.005517] CPU features: detected: Branch Target Identification
host1 # [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
host1 # [    0.000000] GICv3: 256 SPIs implemented
server # [    0.005522] CPU features: detected: ARMv8.4 Translation Table Level
host1 # [    0.000000] GICv3: 0 Extended SPIs implemented
host1 # [    0.000000] Root IRQ handler: gic_handle_irq
server # [    0.005525] CPU features: detected: Instruction cache invalidation not required for I/D coherence
host1 # [    0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI
host1 # [    0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0
server # [    0.005528] CPU features: detected: Data cache clean to the PoU not required for I/D coherence
host1 # [    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000
server # [    0.005532] CPU features: detected: Common not Private translations
host1 # [    0.000000] ITS [mem 0x08080000-0x0809ffff]
server # [    0.005535] CPU features: detected: CRC32 instructions
server # [    0.005538] CPU features: detected: Data cache clean to Point of Deep Persistence
host1 # [    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44af0000 (indirect, esz 8, psz 64K, shr 1)
server # [    0.005541] CPU features: detected: Data cache clean to Point of Persistence
host1 # [    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44b00000 (flat, esz 8, psz 64K, shr 1)
server # [    0.005544] CPU features: detected: Data independent timing control (DIT)
host1 # [    0.000000] GICv3: using LPI property table @0x0000000044b10000
server # [    0.005548] CPU features: detected: E0PD
host1 # [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044b20000
server # [    0.005550] CPU features: detected: Enhanced Counter Virtualization
host1 # [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
server # [    0.005553] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)
server # [    0.005557] CPU features: detected: Enhanced Virtualization Traps
host1 # [    0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
server # [    0.005560] CPU features: detected: Fine Grained Traps
host1 # [    0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).
server # [    0.005563] CPU features: detected: Generic authentication (architected QARMA5 algorithm)
host1 # [    0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
server # [    0.005567] CPU features: detected: RCpc load-acquire (LDAPR)
server # [    0.005570] CPU features: detected: LSE atomic instructions
host1 # [    0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns
server # [    0.005573] CPU features: detected: Privileged Access Never
host1 # [    0.000032] arm-pv: using stolen time PV
server # [    0.005576] CPU features: detected: PMUv3
host1 # [    0.000398] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
server # [    0.005578] CPU features: detected: RAS Extension Support
host1 # [    0.000579] Console: colour dummy device 80x25
server # [    0.005581] CPU features: detected: RASv1p1 Extension Support
host1 # [    0.000586] printk: legacy console [tty0] enabled
server # [    0.005584] CPU features: detected: Random Number Generator
server # [    0.005586] CPU features: detected: Speculation barrier (SB)
host1 # [    0.000784] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)
server # [    0.005589] CPU features: detected: Stage-2 Force Write-Back
host1 # [    0.000791] pid_max: default: 32768 minimum: 301
server # [    0.005592] CPU features: detected: TLB range maintenance instructions
host1 # [    0.000868] LSM: initializing lsm=capability,landlock,yama,bpf,ima
host1 # [    0.001002] landlock: Up and running.
server # [    0.005597] CPU features: detected: Speculative Store Bypassing Safe (SSBS)
host1 # [    0.001005] Yama: becoming mindful.
host1 # [    0.001454] LSM support for eBPF active
server # [    0.005636] alternatives: applying system-wide alternatives
host1 # [    0.001648] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
server # [    0.008813] CPU features: detected: BBM Level 2 without TLB conflict abort
host1 # [    0.001683] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
host1 # [    0.003645] rcu: Hierarchical SRCU implementation.
server # [    0.008959] Memory: 1920544K/2097152K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 133012K reserved, 32768K cma-reserved)
host1 # [    0.003653] rcu: 	Max phase no-delay instances is 1000.
server # [    0.009429] devtmpfs: initialized
host1 # [    0.004942] fsl-mc MSI: its@8080000 domain created
server # [    0.011571] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)
host1 # [    0.005062] EFI services will not be available.
host1 # [    0.005363] smp: Bringing up secondary CPUs ...
server # [    0.011594] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).
host1 # [    0.005372] smp: Brought up 1 node, 1 CPU
host1 # [    0.005376] SMP: Total of 1 processors activated.
server # [    0.011786] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL
host1 # [    0.005378] CPU: All CPU(s) started at EL1
server # [    0.011791] 0 pages in range for non-PLT usage
host1 # [    0.005392] CPU features: detected: Branch Target Identification
server # [    0.011792] 508224 pages in range for PLT usage
host1 # [    0.005396] CPU features: detected: ARMv8.4 Translation Table Level
server # [    0.011903] pinctrl core: initialized pinctrl subsystem
server # [    0.012899] DMI not present or invalid.
host1 # [    0.005399] CPU features: detected: Instruction cache invalidation not required for I/D coherence
server # [    0.016288] NET: Registered PF_NETLINK/PF_ROUTE protocol family
host1 # [    0.005403] CPU features: detected: Data cache clean to the PoU not required for I/D coherence
server # [    0.018800] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations
host1 # [    0.005406] CPU features: detected: Common not Private translations
host1 # [    0.005409] CPU features: detected: CRC32 instructions
server # [    0.018983] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
host1 # [    0.005412] CPU features: detected: Data cache clean to Point of Deep Persistence
server # [    0.019198] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
host1 # [    0.005416] CPU features: detected: Data cache clean to Point of Persistence
server # [    0.019219] audit: initializing netlink subsys (disabled)
host1 # [    0.005419] CPU features: detected: Data independent timing control (DIT)
server # [    0.019918] thermal_sys: Registered thermal governor 'fair_share'
host1 # [    0.005422] CPU features: detected: E0PD
server # [    0.019920] thermal_sys: Registered thermal governor 'bang_bang'
host1 # [    0.005424] CPU features: detected: Enhanced Counter Virtualization
server # [    0.019924] thermal_sys: Registered thermal governor 'step_wise'
host1 # [    0.005428] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)
server # [    0.019927] thermal_sys: Registered thermal governor 'user_space'
host1 # [    0.005431] CPU features: detected: Enhanced Virtualization Traps
server # [    0.019930] thermal_sys: Registered thermal governor 'power_allocator'
host1 # [    0.005435] CPU features: detected: Fine Grained Traps
server # [    0.019969] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1
host1 # [    0.005438] CPU features: detected: Generic authentication (architected QARMA5 algorithm)
server # [    0.019977] cpuidle: using governor ladder
server # [    0.019982] cpuidle: using governor menu
host1 # [    0.005443] CPU features: detected: RCpc load-acquire (LDAPR)
host1 # [    0.005446] CPU features: detected: LSE atomic instructions
server # [    0.020192] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.
host1 # [    0.005449] CPU features: detected: Privileged Access Never
server # [    0.020209] ASID allocator initialised with 65536 entries
host1 # [    0.005452] CPU features: detected: PMUv3
server # [    0.021577] Serial: AMBA PL011 UART driver
host1 # [    0.005455] CPU features: detected: RAS Extension Support
server # [    0.027424] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1
server # [    0.027605] printk: console [ttyAMA0] enabled
host1 # [    0.005458] CPU features: detected: RASv1p1 Extension Support
host1 # [    0.005460] CPU features: detected: Random Number Generator
host1 # [    0.005463] CPU features: detected: Speculation barrier (SB)
host1 # [    0.005466] CPU features: detected: Stage-2 Force Write-Back
host1 # [    0.005469] CPU features: detected: TLB range maintenance instructions
host1 # [    0.005473] CPU features: detected: Speculative Store Bypassing Safe (SSBS)
host1 # [    0.005528] alternatives: applying system-wide alternatives
host1 # [    0.008425] CPU features: detected: BBM Level 2 without TLB conflict abort
host1 # [    0.008649] Memory: 1920548K/2097152K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 133016K reserved, 32768K cma-reserved)
host1 # [    0.009082] devtmpfs: initialized
host1 # [    0.011168] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)
host1 # [    0.011198] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).
host1 # [    0.011400] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL
server # [    0.159377] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
host1 # [    0.011404] 0 pages in range for non-PLT usage
host1 # [    0.011405] 508224 pages in range for PLT usage
server # [    0.159388] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page
host1 # [    0.011513] pinctrl core: initialized pinctrl subsystem
server # [    0.159392] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages
host1 # [    0.012397] DMI not present or invalid.
server # [    0.159394] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page
host1 # [    0.015612] NET: Registered PF_NETLINK/PF_ROUTE protocol family
server # [    0.159397] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
host1 # [    0.017989] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations
server # [    0.159400] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page
host1 # [    0.018187] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
server # [    0.159403] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages
host1 # [    0.018389] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
server # [    0.159406] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page
host1 # [    0.018413] audit: initializing netlink subsys (disabled)
server # [    0.160687] fbcon: Taking over console
server # [    0.160697] ACPI: Interpreter disabled.
host1 # [    0.019012] thermal_sys: Registered thermal governor 'fair_share'
host1 # [    0.019014] thermal_sys: Registered thermal governor 'bang_bang'
server # [    0.170603] iommu: Default domain type: Translated
host1 # [    0.019017] thermal_sys: Registered thermal governor 'step_wise'
server # [    0.170608] iommu: DMA domain TLB invalidation policy: strict mode
server # [    0.171052] SCSI subsystem initialized
host1 # [    0.019020] thermal_sys: Registered thermal governor 'user_space'
host1 # [    0.019023] thermal_sys: Registered thermal governor 'power_allocator'
host1 # [    0.019050] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1
host1 # [    0.019058] cpuidle: using governor ladder
host1 # [    0.019064] cpuidle: using governor menu
server # [    0.173254] usbcore: registered new interface driver usbfs
server # [    0.173285] usbcore: registered new interface driver hub
host1 # [    0.019244] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.
server # [    0.173317] usbcore: registered new device driver usb
host1 # [    0.019259] ASID allocator initialised with 65536 entries
server # [    0.173629] pps_core: LinuxPPS API ver. 1 registered
host1 # [    0.020520] Serial: AMBA PL011 UART driver
host1 # [    0.026190] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1
server # [    0.173633] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
host1 # [    0.026351] printk: console [ttyAMA0] enabled
server # [    0.173642] PTP clock support registered
server # [    0.173697] EDAC MC: Ver: 3.0.0
server # [    0.175207] scmi_core: SCMI protocol bus registered
server # [    0.181348] FPGA manager framework
server # [    0.183619] vgaarb: loaded
server # [    0.184825] clocksource: Switched to clocksource arch_sys_counter
host1 # [    0.155766] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
host1 # [    0.155776] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page
host1 # [    0.155779] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages
server # [    0.191617] VFS: Disk quotas dquot_6.6.0
host1 # [    0.155782] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page
server # [    0.191651] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
host1 # [    0.155785] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
host1 # [    0.155788] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page
host1 # [    0.155791] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages
host1 # [    0.155794] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page
server # [    0.194531] netfs: FS-Cache loaded
host1 # [    0.156985] fbcon: Taking over console
server # [    0.194663] pnp: PnP ACPI: disabled
host1 # [    0.156993] ACPI: Interpreter disabled.
host1 # [    0.167312] iommu: Default domain type: Translated
host1 # [    0.167319] iommu: DMA domain TLB invalidation policy: strict mode
host1 # [    0.167757] SCSI subsystem initialized
server # [    0.200216] NET: Registered PF_INET protocol family
server # [    0.200569] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)
host1 # [    0.169900] usbcore: registered new interface driver usbfs
host1 # [    0.169935] usbcore: registered new interface driver hub
host1 # [    0.169959] usbcore: registered new device driver usb
host1 # [    0.170285] pps_core: LinuxPPS API ver. 1 registered
host1 # [    0.170288] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
host1 # [    0.170297] PTP clock support registered
host1 # [    0.170345] EDAC MC: Ver: 3.0.0
host1 # [    0.171775] scmi_core: SCMI protocol bus registered
host1 # [    0.177677] FPGA manager framework
host1 # [    0.179885] vgaarb: loaded
host1 # [    0.181078] clocksource: Switched to clocksource arch_sys_counter
host1 # [    0.187650] VFS: Disk quotas dquot_6.6.0
host1 # [    0.187681] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
host1 # [    0.188021] netfs: FS-Cache loaded
host1 # [    0.188140] pnp: PnP ACPI: disabled
host1 # [    0.195765] NET: Registered PF_INET protocol family
host1 # [    0.196390] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)
server # [    0.232010] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear)
server # [    0.232049] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
server # [    0.232080] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)
server # [    0.232156] TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear)
server # [    0.232296] TCP: Hash tables configured (established 16384 bind 16384)
server # [    0.232389] MPTCP token hash table entries: 2048 (order: 4, 49152 bytes, linear)
server # [    0.232439] UDP hash table entries: 1024 (order: 4, 65536 bytes, linear)
server # [    0.232562] UDP-Lite hash table entries: 1024 (order: 4, 65536 bytes, linear)
server # [    0.232653] NET: Registered PF_UNIX/PF_LOCAL protocol family
server # [    0.232694] NET: Registered PF_XDP protocol family
server # [    0.232718] PCI: CLS 0 bytes, default 64
server # [    0.242629] Trying to unpack rootfs image as initramfs...
server # [    0.245930] kvm [1]: HYP mode not available
host1 # [    0.226435] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear)
host1 # [    0.226471] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
host1 # [    0.226495] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)
host1 # [    0.226565] TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear)
host1 # [    0.226706] TCP: Hash tables configured (established 16384 bind 16384)
host1 # [    0.226788] MPTCP token hash table entries: 2048 (order: 4, 49152 bytes, linear)
host1 # [    0.226821] UDP hash table entries: 1024 (order: 4, 65536 bytes, linear)
host1 # [    0.226856] UDP-Lite hash table entries: 1024 (order: 4, 65536 bytes, linear)
host1 # [    0.226957] NET: Registered PF_UNIX/PF_LOCAL protocol family
host1 # [    0.226976] NET: Registered PF_XDP protocol family
host1 # [    0.226991] PCI: CLS 0 bytes, default 64
host1 # [    0.227235] Trying to unpack rootfs image as initramfs...
host1 # [    0.247165] kvm [1]: HYP mode not available
server # [    0.342782] Initialise system trusted keyrings
server # [    0.345529] workingset: timestamp_bits=42 max_order=19 bucket_order=0
server # [    0.346002] squashfs: version 4.0 (2009/01/31) Phillip Lougher
server # [    0.346079] 9p: Installing v9fs 9p2000 file system support
host1 # [    0.333974] Initialise system trusted keyrings
host1 # [    0.334119] workingset: timestamp_bits=42 max_order=19 bucket_order=0
host1 # [    0.338847] squashfs: version 4.0 (2009/01/31) Phillip Lougher
host1 # [    0.338939] 9p: Installing v9fs 9p2000 file system support
server # [    0.374171] Key type asymmetric registered
server # [    0.374186] Asymmetric key parser 'x509' registered
server # [    0.374261] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)
server # [    0.374394] io scheduler mq-deadline registered
server # [    0.374398] io scheduler kyber registered
server # [    0.387016] pl061_gpio 9030000.pl061: PL061 GPIO chip registered
server # [    0.387636] ledtrig-cpu: registered to indicate activity on CPUs
server # [    0.388025] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:
server # [    0.388040] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
host1 # [    0.360498] Key type asymmetric registered
host1 # [    0.360512] Asymmetric key parser 'x509' registered
server # [    0.388051] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
host1 # [    0.360584] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)
server # [    0.388056] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000
server # [    0.388078] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits
server # [    0.388097] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]
server # [    0.388171] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00
server # [    0.388178] pci_bus 0000:00: root bus resource [bus 00-ff]
server # [    0.388182] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
server # [    0.388185] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]
server # [    0.388188] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]
server # [    0.388253] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint
server # [    0.388722] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
host1 # [    0.369132] io scheduler mq-deadline registered
host1 # [    0.369144] io scheduler kyber registered
host1 # [    0.374591] pl061_gpio 9030000.pl061: PL061 GPIO chip registered
host1 # [    0.377915] ledtrig-cpu: registered to indicate activity on CPUs
server # [    0.408998] pci 0000:00:01.0: BAR 0 [io  0x0000-0x001f]
host1 # [    0.378343] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:
server # [    0.409017] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]
host1 # [    0.378359] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
server # [    0.409043] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.409055] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]
host1 # [    0.378379] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
server # [    0.409568] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
host1 # [    0.378385] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000
server # [    0.409758] pci 0000:00:02.0: BAR 0 [io  0x0000-0x001f]
host1 # [    0.378404] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits
server # [    0.409770] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.409796] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
host1 # [    0.378425] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]
host1 # [    0.378518] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00
server # [    0.410276] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
host1 # [    0.378525] pci_bus 0000:00: root bus resource [bus 00-ff]
server # [    0.410463] pci 0000:00:03.0: BAR 0 [io  0x0000-0x007f]
host1 # [    0.378529] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
server # [    0.410474] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]
host1 # [    0.378532] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]
server # [    0.410500] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
host1 # [    0.378535] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]
server # [    0.410981] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
server # [    0.411167] pci 0000:00:04.0: BAR 0 [io  0x0000-0x001f]
host1 # [    0.378630] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint
server # [    0.411178] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]
host1 # [    0.379077] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
server # [    0.411204] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
host1 # [    0.379271] pci 0000:00:01.0: BAR 0 [io  0x0000-0x001f]
server # [    0.411215] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]
host1 # [    0.379284] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]
host1 # [    0.379312] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.411683] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint
host1 # [    0.379324] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]
server # [    0.411875] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.411900] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
host1 # [    0.379806] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
host1 # [    0.379995] pci 0000:00:02.0: BAR 0 [io  0x0000-0x001f]
server # [    0.412394] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint
host1 # [    0.380007] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.412605] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]
host1 # [    0.380033] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.412630] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
host1 # [    0.380517] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
host1 # [    0.380704] pci 0000:00:03.0: BAR 0 [io  0x0000-0x007f]
host1 # [    0.380715] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]
host1 # [    0.380742] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.433018] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint
server # [    0.433209] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]
server # [    0.433508] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint
server # [    0.433697] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]
host1 # [    0.413339] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
server # [    0.433723] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
host1 # [    0.413614] pci 0000:00:04.0: BAR 0 [io  0x0000-0x001f]
host1 # [    0.413628] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.434190] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint
server # [    0.434382] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]
host1 # [    0.413654] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
host1 # [    0.413666] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]
server # [    0.434408] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
host1 # [    0.414161] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint
server # [    0.434880] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint
host1 # [    0.414351] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.435079] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]
host1 # [    0.414377] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.435108] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
host1 # [    0.414853] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint
server # [    0.435601] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
host1 # [    0.415043] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.435864] pci 0000:00:0b.0: BAR 0 [io  0x0000-0x003f]
server # [    0.435875] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]
host1 # [    0.415069] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.435901] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
host1 # [    0.415467] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint
host1 # [    0.415651] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]
server # [    0.436369] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
server # [    0.436558] pci 0000:00:0c.0: BAR 0 [io  0x0000-0x001f]
host1 # [    0.415941] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint
server # [    0.436570] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]
host1 # [    0.416132] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.436595] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
host1 # [    0.416159] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
host1 # [    0.416634] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint
host1 # [    0.416827] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]
host1 # [    0.416854] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.462386] pci 0000:00:0d.0: [1af4:1053] type 00 class 0x078000 conventional PCI endpoint
server # [    0.462588] pci 0000:00:0d.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.462615] pci 0000:00:0d.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.463283] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned
host1 # [    0.434377] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint
server # [    0.463289] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned
host1 # [    0.434569] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.463293] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned
host1 # [    0.434596] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.463339] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned
host1 # [    0.435082] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
server # [    0.463384] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned
host1 # [    0.435366] pci 0000:00:0b.0: BAR 0 [io  0x0000-0x003f]
host1 # [    0.435377] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.463429] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned
host1 # [    0.435404] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.463475] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned
host1 # [    0.435875] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
server # [    0.463520] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned
host1 # [    0.436063] pci 0000:00:0c.0: BAR 0 [io  0x0000-0x001f]
server # [    0.463564] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned
host1 # [    0.436074] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]
host1 # [    0.436100] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.463609] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned
host1 # [    0.436568] pci 0000:00:0d.0: [1af4:1053] type 00 class 0x078000 conventional PCI endpoint
server # [    0.463654] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned
host1 # [    0.436759] pci 0000:00:0d.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.463698] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned
host1 # [    0.436784] pci 0000:00:0d.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.463791] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned
server # [    0.463865] pci 0000:00:0d.0: BAR 4 [mem 0x800002c000-0x800002ffff 64bit pref]: assigned
server # [    0.463906] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned
server # [    0.463924] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned
server # [    0.463941] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned
server # [    0.463959] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned
server # [    0.463979] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned
server # [    0.463997] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned
server # [    0.464015] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned
server # [    0.464034] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned
server # [    0.464051] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned
server # [    0.464069] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned
server # [    0.464087] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned
server # [    0.464106] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned
host1 # [    0.457592] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned
server # [    0.464124] pci 0000:00:0d.0: BAR 1 [mem 0x1008c000-0x1008cfff]: assigned
host1 # [    0.457605] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned
server # [    0.464141] pci 0000:00:03.0: BAR 0 [io  0x1000-0x107f]: assigned
host1 # [    0.457609] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned
server # [    0.464158] pci 0000:00:0b.0: BAR 0 [io  0x1080-0x10bf]: assigned
server # [    0.464175] pci 0000:00:01.0: BAR 0 [io  0x10c0-0x10df]: assigned
host1 # [    0.457661] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned
server # [    0.464192] pci 0000:00:02.0: BAR 0 [io  0x10e0-0x10ff]: assigned
host1 # [    0.457708] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned
server # [    0.464209] pci 0000:00:04.0: BAR 0 [io  0x1100-0x111f]: assigned
host1 # [    0.457754] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned
server # [    0.464226] pci 0000:00:0c.0: BAR 0 [io  0x1120-0x113f]: assigned
server # [    0.464249] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]
host1 # [    0.457800] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned
server # [    0.464254] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]
host1 # [    0.457845] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned
server # [    0.464257] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]
host1 # [    0.457890] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned
host1 # [    0.457936] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned
host1 # [    0.457980] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned
host1 # [    0.458026] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned
host1 # [    0.458139] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned
server # [    0.501160] pci 0000:00:07.0: enabling device (0000 -> 0002)
host1 # [    0.458186] pci 0000:00:0d.0: BAR 4 [mem 0x800002c000-0x800002ffff 64bit pref]: assigned
host1 # [    0.458230] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned
host1 # [    0.458248] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned
host1 # [    0.458266] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned
host1 # [    0.458283] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned
host1 # [    0.458304] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned
host1 # [    0.458321] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned
host1 # [    0.458339] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned
host1 # [    0.458358] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned
host1 # [    0.458377] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned
host1 # [    0.458394] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned
host1 # [    0.458413] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned
host1 # [    0.458431] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned
host1 # [    0.458449] pci 0000:00:0d.0: BAR 1 [mem 0x1008c000-0x1008cfff]: assigned
host1 # [    0.458466] pci 0000:00:03.0: BAR 0 [io  0x1000-0x107f]: assigned
host1 # [    0.458484] pci 0000:00:0b.0: BAR 0 [io  0x1080-0x10bf]: assigned
host1 # [    0.458502] pci 0000:00:01.0: BAR 0 [io  0x10c0-0x10df]: assigned
host1 # [    0.458520] pci 0000:00:02.0: BAR 0 [io  0x10e0-0x10ff]: assigned
host1 # [    0.458537] pci 0000:00:04.0: BAR 0 [io  0x1100-0x111f]: assigned
host1 # [    0.458555] pci 0000:00:0c.0: BAR 0 [io  0x1120-0x113f]: assigned
host1 # [    0.458579] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]
host1 # [    0.458583] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]
host1 # [    0.458587] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]
host1 # [    0.459451] pci 0000:00:07.0: enabling device (0000 -> 0002)
server # [    0.524547] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)
server # [    0.534312] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)
host1 # [    0.513918] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)
server # [    0.545069] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)
host1 # [    0.516208] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)
server # [    0.547512] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)
server # [    0.551693] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)
host1 # [    0.526608] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)
host1 # [    0.528739] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)
server # [    0.562441] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)
server # [    0.564310] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)
server # [    0.568045] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)
host1 # [    0.539063] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)
server # [    0.578496] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)
server # [    0.580577] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)
host1 # [    0.549173] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)
host1 # [    0.551604] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)
server # [    0.584668] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)
host1 # [    0.555106] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)
host1 # [    0.557000] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)
server # [    0.594368] virtio-pci 0000:00:0d.0: enabling device (0000 -> 0002)
host1 # [    0.566554] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)
host1 # [    0.568814] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)
host1 # [    0.573027] virtio-pci 0000:00:0d.0: enabling device (0000 -> 0002)
server # [    0.606411] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
server # [    0.608590] msm_serial: driver initialized
server # [    0.608800] SuperH (H)SCI(F) driver initialized
server # [    0.610865] STM32 USART driver initialized
host1 # [    0.585233] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
host1 # [    0.590918] msm_serial: driver initialized
host1 # [    0.591068] SuperH (H)SCI(F) driver initialized
host1 # [    0.591134] STM32 USART driver initialized
server # [    0.648102] loop: module loaded
server # [    0.648330] virtio_blk virtio2: 1/0/0 default/read/poll queues
server # [    0.650698] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
host1 # [    0.627797] loop: module loaded
host1 # [    0.627991] virtio_blk virtio2: 1/0/0 default/read/poll queues
host1 # [    0.628797] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
host1 # [    0.633967] megasas: 07.734.00.00-rc1
server # [    0.665719] megasas: 07.734.00.00-rc1
host1 # [    0.634672] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]
server # [    0.666564] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]
host1 # [    0.636838] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000
host1 # [    0.636853] Intel/Sharp Extended Query Table at 0x0031
server # [    0.670752] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000
server # [    0.670810] Intel/Sharp Extended Query Table at 0x0031
server # [    0.672784] Using buffer write method
server # [    0.672823] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]
server # [    0.678208] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000
server # [    0.678224] Intel/Sharp Extended Query Table at 0x0031
host1 # [    0.650258] Using buffer write method
host1 # [    0.650296] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]
host1 # [    0.652373] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000
host1 # [    0.652387] Intel/Sharp Extended Query Table at 0x0031
server # [    0.689397] Using buffer write method
server # [    0.689416] Concatenating MTD devices:
server # [    0.689418] (0): "0.flash"
server # [    0.689421] (1): "0.flash"
server # [    0.689423] into device "0.flash"
host1 # [    0.666218] Using buffer write method
host1 # [    0.666235] Concatenating MTD devices:
host1 # [    0.666238] (0): "0.flash"
host1 # [    0.666240] (1): "0.flash"
host1 # [    0.666242] into device "0.flash"
host1 # [    0.935504] Freeing initrd memory: 26948K
host1 # [    0.941850] tun: Universal TUN/TAP device driver, 1.6
server # [    0.973726] Freeing initrd memory: 26944K
server # [    0.980025] tun: Universal TUN/TAP device driver, 1.6
host1 # [    0.945888] thunder_xcv, ver 1.0
host1 # [    0.945923] thunder_bgx, ver 1.0
host1 # [    0.945942] nicpf, ver 1.0
host1 # [    0.946517] e1000: Intel(R) PRO/1000 Network Driver
host1 # [    0.946521] e1000: Copyright (c) 1999-2006 Intel Corporation.
host1 # [    0.946544] e1000e: Intel(R) PRO/1000 Network Driver
host1 # [    0.946552] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
server # [    0.984041] thunder_xcv, ver 1.0
server # [    0.984072] thunder_bgx, ver 1.0
host1 # [    0.946579] igb: Intel(R) Gigabit Ethernet Network Driver
server # [    0.984093] nicpf, ver 1.0
host1 # [    0.946582] igb: Copyright (c) 2007-2014 Intel Corporation.
server # [    0.984767] e1000: Intel(R) PRO/1000 Network Driver
host1 # [    0.946627] igbvf: Intel(R) Gigabit Virtual Function Network Driver
server # [    0.984771] e1000: Copyright (c) 1999-2006 Intel Corporation.
server # [    0.984795] e1000e: Intel(R) PRO/1000 Network Driver
host1 # [    0.946631] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
host1 # [    0.946768] sky2: driver version 1.30
server # [    0.984803] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
host1 # [    0.948421] usbcore: registered new interface driver usb-storage
server # [    0.984832] igb: Intel(R) Gigabit Ethernet Network Driver
host1 # [    0.948542] usbcore: registered new interface driver usbserial_generic
host1 # [    0.948556] usbserial: USB Serial support registered for generic
host1 # [    0.949914] ehci-pci 0000:00:07.0: EHCI Host Controller
host1 # [    0.949933] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1
host1 # [    0.950100] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000
server # [    0.989677] igb: Copyright (c) 2007-2014 Intel Corporation.
server # [    0.989715] igbvf: Intel(R) Gigabit Virtual Function Network Driver
server # [    0.989719] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
server # [    0.989882] sky2: driver version 1.30
server # [    0.991625] usbcore: registered new interface driver usb-storage
host1 # [    0.962569] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00
server # [    0.991674] usbcore: registered new interface driver usbserial_generic
host1 # [    0.962980] hub 1-0:1.0: USB hub found
host1 # [    0.962995] hub 1-0:1.0: 6 ports detected
server # [    0.991684] usbserial: USB Serial support registered for generic
host1 # [    0.963567] hv_vmbus: registering driver hyperv_keyboard
server # [    0.992328] hv_vmbus: registering driver hyperv_keyboard
host1 # [    0.964449] rtc-pl031 9010000.pl031: registered as rtc0
host1 # [    0.964478] rtc-pl031 9010000.pl031: setting system clock to 2026-09-30T09:10:46 UTC (1790759446)
host1 # [    0.964788] i2c_dev: i2c /dev entries driver
server # [    0.999639] ehci-pci 0000:00:07.0: EHCI Host Controller
server # [    0.999661] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1
server # [    0.999906] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000
server # [    1.000571] rtc-pl031 9010000.pl031: registered as rtc0
server # [    1.000600] rtc-pl031 9010000.pl031: setting system clock to 2026-09-30T09:10:46 UTC (1790759446)
host1 # [    0.973057] sdhci: Secure Digital Host Controller Interface driver
host1 # [    0.973063] sdhci: Copyright(c) Pierre Ossman
host1 # [    0.974828] Synopsys Designware Multimedia Card Interface Driver
host1 # [    0.975220] sdhci-pltfm: SDHCI platform and OF driver helper
host1 # [    0.976815] hid: raw HID events driver (C) Jiri Kosina
server # [    1.005123] i2c_dev: i2c /dev entries driver
host1 # [    0.977051] usbcore: registered new interface driver usbhid
server # [    1.008617] sdhci: Secure Digital Host Controller Interface driver
host1 # [    0.977055] usbhid: USB HID core driver
server # [    1.008622] sdhci: Copyright(c) Pierre Ossman
server # [    1.008885] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00
server # [    1.009222] hub 1-0:1.0: USB hub found
server # [    1.009248] hub 1-0:1.0: 6 ports detected
host1 # [    0.981761] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available
server # [    1.013501] Synopsys Designware Multimedia Card Interface Driver
host1 # [    0.983377] drop_monitor: Initializing network drop monitor service
server # [    1.013893] sdhci-pltfm: SDHCI platform and OF driver helper
host1 # [    0.983558] NET: Registered PF_INET6 protocol family
server # [    1.015559] hid: raw HID events driver (C) Jiri Kosina
host1 # [    0.984109] Segment Routing with IPv6
server # [    1.015816] usbcore: registered new interface driver usbhid
host1 # [    0.984134] In-situ OAM (IOAM) with IPv6
server # [    1.015819] usbhid: USB HID core driver
host1 # [    0.984157] NET: Registered PF_PACKET protocol family
host1 # [    0.984216] 9pnet: Installing 9P2000 support
host1 # [    0.984252] Key type dns_resolver registered
server # [    1.020493] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available
server # [    1.023297] drop_monitor: Initializing network drop monitor service
server # [    1.023454] NET: Registered PF_INET6 protocol family
server # [    1.024017] Segment Routing with IPv6
server # [    1.024030] In-situ OAM (IOAM) with IPv6
server # [    1.024085] NET: Registered PF_PACKET protocol family
server # [    1.024144] 9pnet: Installing 9P2000 support
host1 # [    0.995888] registered taskstats version 1
server # [    1.024182] Key type dns_resolver registered
host1 # [    0.996064] Loading compiled-in X.509 certificates
host1 # [    1.004996] Demotion targets for Node 0: null
server # [    1.034670] registered taskstats version 1
host1 # [    1.005121] Key type .fscrypt registered
server # [    1.034810] Loading compiled-in X.509 certificates
host1 # [    1.005125] Key type fscrypt-provisioning registered
host1 # [    1.005229] ima: No TPM chip found, activating TPM-bypass!
host1 # [    1.005246] ima: Allocated hash algorithm: sha1
host1 # [    1.005270] ima: No architecture policies found
host1 # [    1.009598] input: gpio-keys as /devices/platform/gpio-keys/input/input0
server # [    1.043716] Demotion targets for Node 0: null
server # [    1.043829] Key type .fscrypt registered
server # [    1.043832] Key type fscrypt-provisioning registered
server # [    1.043943] ima: No TPM chip found, activating TPM-bypass!
server # [    1.043960] ima: Allocated hash algorithm: sha1
server # [    1.043981] ima: No architecture policies found
server # [    1.044752] input: gpio-keys as /devices/platform/gpio-keys/input/input0
host1 # [    1.027307] clk: Disabling unused clocks
host1 # [    1.027322] PM: genpd: Disabling unused power domains
host1 # [    1.031577] Freeing unused kernel memory: 4736K
host1 # [    1.031748] Run /init as init process
server # [    1.066849] clk: Disabling unused clocks
server # [    1.066866] PM: genpd: Disabling unused power domains
server # [    1.071121] Freeing unused kernel memory: 4736K
server # [    1.071321] Run /init as init process
host1 # [    1.047573] systemd[1]: Successfully made /usr/ read-only.
server # [    1.087333] systemd[1]: Successfully made /usr/ read-only.
host1 # [    1.209124] usb 1-1: new high-speed USB device number 2 using ehci-pci
server # [    1.256920] usb 1-1: new high-speed USB device number 2 using ehci-pci
host1 # [    1.360995] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1
server # [    1.406866] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1
host1 # [    1.381455] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -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)
host1 # [    1.381567] systemd[1]: Detected virtualization qemu.
host1 # [    1.381621] systemd[1]: Detected architecture arm64.
host1 # [    1.381630] systemd[1]: Running in initrd.
host1 # [    1.382266] systemd[1]: Initializing machine ID from random generator.
host1 # [    1.382457] systemd[1]: Hostname set to <host1>.
server # [    1.421331] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -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)
server # [    1.421356] systemd[1]: Detected virtualization qemu.
server # [    1.421401] systemd[1]: Detected architecture arm64.
server # [    1.421408] systemd[1]: Running in initrd.
server # [    1.421963] systemd[1]: Initializing machine ID from random generator.
server # [    1.422141] systemd[1]: Hostname set to <server>.
host1 # [    1.449315] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0
server # [    1.489056] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0
host1 # [    1.569183] usb 1-2: new high-speed USB device number 3 using ehci-pci
server # [    1.608892] usb 1-2: new high-speed USB device number 3 using ehci-pci
host1 # [    1.649052] systemd[1]: bpf-restrict-fs: LSM BPF program attached
server # [    1.684548] systemd[1]: bpf-restrict-fs: LSM BPF program attached
host1 # [    1.721439] systemd[1]: Queued start job for default target Initrd Default Target.
server # [    1.755602] systemd[1]: Queued start job for default target Initrd Default Target.
host1 # [    1.727838] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2
host1 # [    1.727963] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0
host1 # [    1.728865] systemd[1]: Created slice Slice /system/modprobe.
host1 # [    1.729036] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
server # [    1.763456] systemd[1]: Created slice Slice /system/modprobe.
host1 # [    1.729060] systemd[1]: Expecting device /dev/disk/by-label/nixos...
server # [    1.763673] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
server # [    1.763704] systemd[1]: Expecting device /dev/disk/by-label/nixos...
host1 # [    1.735343] systemd[1]: Reached target Path Units.
host1 # [    1.735368] systemd[1]: Reached target Slice Units.
server # [    1.763728] systemd[1]: Reached target Path Units.
host1 # [    1.735381] systemd[1]: Reached target Swaps.
server # [    1.763745] systemd[1]: Reached target Slice Units.
host1 # [    1.735394] systemd[1]: Reached target Timer Units.
server # [    1.763760] systemd[1]: Reached target Swaps.
host1 # [    1.735570] systemd[1]: Listening on D-Bus System Message Bus Socket.
server # [    1.763777] systemd[1]: Reached target Timer Units.
host1 # [    1.735693] systemd[1]: Listening on Journal Socket (/dev/log).
host1 # [    1.735806] systemd[1]: Listening on Journal Sockets.
server # [    1.763925] systemd[1]: Listening on D-Bus System Message Bus Socket.
host1 # [    1.735916] systemd[1]: Listening on udev Control Socket.
server # [    1.764097] systemd[1]: Listening on Journal Socket (/dev/log).
host1 # [    1.736009] systemd[1]: Listening on udev Kernel Socket.
server # [    1.764272] systemd[1]: Listening on Journal Sockets.
host1 # [    1.736024] systemd[1]: Reached target Socket Units.
server # [    1.764402] systemd[1]: Listening on udev Control Socket.
server # [    1.764505] systemd[1]: Listening on udev Kernel Socket.
server # [    1.764523] systemd[1]: Reached target Socket Units.
host1 # [    1.745218] systemd[1]: Starting Create List of Static Device Nodes...
server # [    1.779244] systemd[1]: Starting Create List of Static Device Nodes...
host1 # [    1.745281] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs
server # [    1.779313] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs
server # [    1.780244] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2
server # [    1.780398] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0
host1 # [    1.753276] systemd[1]: Mounting Kernel Configuration File System...
server # [    1.795892] systemd[1]: Mounting Kernel Configuration File System...
host1 # [    1.768400] systemd[1]: Starting Journal Service...
host1 # [    1.773564] systemd[1]: Starting Load Kernel Modules...
host1 # [    1.773655] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os
server # [    1.821101] systemd[1]: Starting Journal Service...
host1 # [    1.793608] systemd[1]: Starting Coldplug All udev Devices...
host1 # [    1.794934] systemd[1]: Finished Create List of Static Device Nodes.
server # [    1.828995] systemd[1]: Starting Load Kernel Modules...
server # [    1.829099] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os
server # [    1.830409] systemd[1]: Starting Coldplug All udev Devices...
server # [    1.836309] systemd[1]: Finished Create List of Static Device Nodes.
server # [    1.838243] systemd[1]: Mounted Kernel Configuration File System.
host1 # [    1.821706] systemd-journald[71]: Collecting audit messages is disabled.
host1 # [    1.826975] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
server # [    1.853278] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
host1 # [    1.849546] systemd[1]: Mounted Kernel Configuration File System.
server # [    1.888390] systemd-journald[71]: Collecting audit messages is disabled.
server # [    1.902241] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
host1 # [    1.881917] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
host1 # [    1.886517] systemd[1]: Starting Create Static Device Nodes in /dev...
server # [    1.921572] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
server # [    1.922946] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev
server # [    1.929264] systemd[1]: Starting Create Static Device Nodes in /dev...
host1 # [    1.901423] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
server # [    1.938724] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0
server # [    1.938976] [drm] features: -virgl +edid -resource_blob -host_visible
server # [    1.938979] [drm] features: -context_init
server # [    1.939779] [drm] number of scanouts: 1
server # [    1.939792] [drm] number of cap sets: 0
host1 # [    1.909164] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev
host1 # [    1.922692] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0
host1 # [    1.922938] [drm] features: -virgl +edid -resource_blob -host_visible
host1 # [    1.922941] [drm] features: -context_init
host1 # [    1.923704] [drm] number of scanouts: 1
host1 # [    1.923715] [drm] number of cap sets: 0
server # [    1.961208] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic
server # [    1.961223] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0
host1 # [    1.949857] systemd[1]: Finished Create Static Device Nodes in /dev.
host1 # [    1.950052] systemd[1]: Reached target Preparation for Local File Systems.
host1 # [    1.950075] systemd[1]: Reached target Local File Systems.
host1 # [    1.951642] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic
host1 # [    1.951653] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0
server # [    1.989425] Console: switching to colour frame buffer device 160x50
host1 # [    1.958043] systemd[1]: Starting Rule-based Manager for Device Events and Files...
server # [    2.005556] systemd[1]: Finished Create Static Device Nodes in /dev.
server # [    2.005777] systemd[1]: Reached target Preparation for Local File Systems.
server # [    2.005800] systemd[1]: Reached target Local File Systems.
server # [    2.007497] systemd[1]: Starting Rule-based Manager for Device Events and Files...
server # [    2.013055] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device
host1 # [    1.985410] Console: switching to colour frame buffer device 160x50
host1 # [    2.002972] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device
host1 # [    2.004369] systemd[1]: Started Journal Service.
server # [    2.037089] NET: Registered PF_VSOCK protocol family
host1 # [    2.008650] systemd-modules-load[72]: Inserted module 'dm_mod'
host1 # [    2.012797] systemd-modules-load[72]: Module 'virtio_balloon' is built in
host1 # [    2.013984] systemd-modules-load[72]: Module 'virtio_console' is built in
host1 # [    2.015101] systemd-modules-load[72]: Inserted module 'virtio_gpu'
host1 # [    2.037249] NET: Registered PF_VSOCK protocol family
host1 # [    2.032229] systemd-modules-load[72]: Module 'virtio_rng' is built in
server # [    2.073466] systemd[1]: Finished Load Kernel Modules.
server # [    2.076026] systemd[1]: Starting Apply Kernel Variables...
host1 # [    2.036509] systemd[1]: Starting Create System Files and Directories...
server # [    2.072262] systemd-modules-load[72]: Inserted module 'dm_mod'
server # [    2.080286] systemd-modules-load[72]: Module 'virtio_balloon' is built in
server # [    2.081551] systemd-modules-load[72]: Module 'virtio_console' is built in
server # [    2.082735] systemd-modules-load[72]: Inserted module 'virtio_gpu'
host1 # [    2.059465] systemd-modules-load[72]: Inserted module 'vmw_vsock_virtio_transport'
server # [    2.083913] systemd-modules-load[72]: Module 'virtio_rng' is built in
host1 # [    2.064352] systemd[1]: Finished Load Kernel Modules.
server # [    2.105995] systemd[1]: Started Journal Service.
host1 # [    2.076155] systemd[1]: Starting Apply Kernel Variables...
host1 # [    2.077474] systemd-udevd[78]: Using default interface naming scheme 'v261'.
server # [    2.104605] systemd-modules-load[72]: Inserted module 'vmw_vsock_virtio_transport'
server # [    2.105884] systemd[1]: Starting Create System Files and Directories...
host1 # [    2.087833] systemd[1]: Finished Create System Files and Directories.
server # [    2.118556] systemd-udevd[77]: Using default interface naming scheme 'v261'.
server # [    2.128288] systemd[1]: Finished Apply Kernel Variables.
host1 # [    2.117149] systemd[1]: Finished Apply Kernel Variables.
host1 # [    2.121572] systemd[1]: Started Rule-based Manager for Device Events and Files.
server # [    2.147042] systemd[1]: Finished Create System Files and Directories.
server # [    2.162017] systemd[1]: Started Rule-based Manager for Device Events and Files.
host1 # [    2.180818] systemd[1]: Starting Virtual Console Setup...
server # [    2.216689] systemd[1]: Starting Virtual Console Setup...
host1 # [    2.244587] systemd-vconsole-setup[105]: Configuration of first virtual console was skipped, ignoring remaining ones.
host1 # [    2.248346] systemd[1]: Finished Virtual Console Setup.
server # [    2.297460] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.
server # [    2.304249] systemd[1]: Finished Virtual Console Setup.
host1 # [    2.847346] systemd[1]: Finished Coldplug All udev Devices.
host1 # [    2.848780] systemd[1]: Reached target System Initialization.
host1 # [    2.852529] systemd[1]: Reached target Basic System.
server # [    2.932484] systemd[1]: Finished Coldplug All udev Devices.
server # [    2.934167] systemd[1]: Reached target System Initialization.
server # [    2.935045] systemd[1]: Reached target Basic System.
host1 # [    3.013537] (udev-worker)[91]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
host1 # [    3.029467] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.
host1 # [    3.042526] (udev-worker)[104]: Network interface NamePolicy= disabled on kernel command line.
server # [    3.091923] (udev-worker)[88]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
server # [    3.096945] (udev-worker)[88]: Network interface NamePolicy= disabled on kernel command line.
server # [    3.134365] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.
host1 # [    3.120928] systemd[1]: Found device /dev/disk/by-label/nixos.
host1 # [    3.124151] systemd[1]: Reached target Initrd Root Device.
host1 # [    3.128121] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...
host1 # [    3.173231] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks
host1 # [    3.180989] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.
host1 # [    3.184193] systemd[1]: Mounting /sysroot...
server # [    3.217989] systemd[1]: Found device /dev/disk/by-label/nixos.
server # [    3.220487] systemd[1]: Reached target Initrd Root Device.
server # [    3.225263] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...
host1 # [    3.214957] systemd[1]: Mounted /sysroot.
host1 # [    3.225500] EXT4-fs (vda): mounted filesystem 39a96e25-1b9c-4cae-8a87-a91134af90a2 r/w with ordered data mode. Quota mode: none.
host1 # [    3.218905] systemd[1]: Reached target Initrd Root File System.
host1 # [    3.223714] systemd[1]: Starting Mountpoints Configured in the Real Root...
server # [    3.266869] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks
server # [    3.274050] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.
host1 # [    3.251838] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.
server # [    3.280774] systemd[1]: Mounting /sysroot...
host1 # [    3.259372] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...
host1 # [    3.263142] systemd[1]: Reloading...
server # [    3.336972] EXT4-fs (vda): mounted filesystem 7504b1f0-3e09-464e-881f-aa277ed43f01 r/w with ordered data mode. Quota mode: none.
server # [    3.322655] systemd[1]: Mounted /sysroot.
server # [    3.325993] systemd[1]: Reached target Initrd Root File System.
server # [    3.328493] systemd[1]: Starting Mountpoints Configured in the Real Root...
server # [    3.356291] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.
server # [    3.364109] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...
server # [    3.366861] systemd[1]: Reloading...
host1 # [    3.475337] systemd[1]: Reloading finished in 217 ms.
host1 # [    3.509036] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...
host1 # [    3.513315] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...
host1 # [    3.521177] systemd[1]: initrd-parse-etc.service: Deactivated successfully.
host1 # [    3.523812] systemd[1]: Finished Mountpoints Configured in the Real Root.
host1 # [    3.526156] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.
server # [    3.583058] systemd[1]: Reloading finished in 219 ms.
server # [    3.616936] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...
server # [    3.620852] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...
server # [    3.628825] systemd[1]: initrd-parse-etc.service: Deactivated successfully.
server # [    3.631524] systemd[1]: Finished Mountpoints Configured in the Real Root.
server # [    3.633873] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.
host1 # [    3.786920] systemd[1]: Mounting /sysroot/nix/.ro-store...
server # [    3.814596] systemd[1]: Mounting /sysroot/nix/.ro-store...
host1 # [    3.804195] systemd[1]: Mounting /sysroot/nix/.rw-store...
server # [    3.827955] systemd[1]: Mounting /sysroot/nix/.rw-store...
host1 # [    3.807636] systemd[1]: Mounting /sysroot/run...
server # [    3.840910] systemd[1]: Mounting /sysroot/run...
host1 # [    3.818485] systemd[1]: Mounting /sysroot/tmp/shared...
server # [    3.865145] systemd[1]: Mounting /sysroot/tmp/shared...
host1 # [    3.856979] systemd[1]: Mounting /sysroot/tmp/xchg...
host1 # [    3.867027] systemd[1]: Mounted /sysroot/nix/.rw-store.
server # [    3.908996] systemd[1]: Mounting /sysroot/tmp/xchg...
server # [    3.913290] systemd[1]: Mounted /sysroot/nix/.rw-store.
host1 # [    3.904316] systemd[1]: Starting rw-sysroot-nix-store.service...
server # [    3.948321] fuse: init (API version 7.45)
host1 # [    3.932894] fuse: init (API version 7.45)
server # [    3.965019] virtiofs virtio6: discovered new tag: nix-store
server # [    3.965859] virtiofs virtio6: virtio_fs_setup_dax: No cache capability
server # [    3.953862] systemd[1]: Starting rw-sysroot-nix-store.service...
host1 # [    3.931245] systemd[1]: Mounted /sysroot/run.
server # [    3.958889] systemd[1]: Mounted /sysroot/run.
host1 # [    3.950481] virtiofs virtio6: discovered new tag: nix-store
server # [    3.982532] virtiofs virtio7: discovered new tag: shared
host1 # [    3.951288] virtiofs virtio6: virtio_fs_setup_dax: No cache capability
server # [    3.983344] virtiofs virtio7: virtio_fs_setup_dax: No cache capability
server # [    3.992770] virtiofs virtio8: discovered new tag: xchg
host1 # [    3.967648] virtiofs virtio7: discovered new tag: shared
server # [    3.999146] virtiofs virtio8: virtio_fs_setup_dax: No cache capability
host1 # [    3.968488] virtiofs virtio7: virtio_fs_setup_dax: No cache capability
host1 # [    3.978009] virtiofs virtio8: discovered new tag: xchg
host1 # [    3.978813] virtiofs virtio8: virtio_fs_setup_dax: No cache capability
host1 # [    3.970941] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.
host1 # [    3.978758] systemd[1]: Finished rw-sysroot-nix-store.service.
server # [    4.002453] systemd[1]: Mounted /sysroot/tmp/shared.
host1 # [    3.983797] systemd[1]: Mounted /sysroot/nix/.ro-store.
host1 # [    3.986907] systemd[1]: Mounted /sysroot/tmp/shared.
host1 # [    3.990034] systemd[1]: Mounted /sysroot/tmp/xchg.
host1 # [    4.003203] systemd[1]: Mounting /sysroot/nix/store...
server # [    4.030405] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.
server # [    4.036911] systemd[1]: Finished rw-sysroot-nix-store.service.
host1 # [    4.043850] systemd[1]: Mounted /sysroot/nix/store.
host1 # [    4.046152] systemd[1]: Reached target Initrd File Systems.
host1 # [    4.051944] systemd[1]: Starting Find NixOS closure...
server # [    4.079344] systemd[1]: Mounted /sysroot/nix/.ro-store.
server # [    4.085323] systemd[1]: Mounted /sysroot/tmp/xchg.
host1 # [    4.068131] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...
host1 # [    4.094328] systemd[1]: Finished Find NixOS closure.
host1 # [    4.099879] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.
host1 # [    4.104357] systemd[1]: Reached target Initrd Default Target.
host1 # [    4.112171] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...
host1 # [    4.145283] systemd[1]: Stopped target Initrd Default Target.
host1 # [    4.147364] systemd[1]: Stopped target Basic System.
host1 # [    4.148227] systemd[1]: Stopped target Initrd Root Device.
host1 # [    4.151257] systemd[1]: Stopped target Path Units.
host1 # [    4.152742] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
host1 # [    4.157819] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.
host1 # [    4.161091] systemd[1]: Stopped target Slice Units.
host1 # [    4.165213] systemd[1]: Stopped target Socket Units.
host1 # [    4.166050] systemd[1]: Stopped target System Initialization.
host1 # [    4.167741] systemd[1]: Stopped target Swaps.
host1 # [    4.172558] systemd[1]: Stopped target Timer Units.
host1 # [    4.174229] systemd[1]: dbus.socket: Deactivated successfully.
host1 # [    4.175977] systemd[1]: Closed D-Bus System Message Bus Socket.
host1 # [    4.178767] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.
host1 # [    4.183927] systemd[1]: Stopped Find NixOS closure.
host1 # [    4.185765] systemd[1]: Starting rw-sysroot-nix-store.service...
host1 # [    4.193808] systemd[1]: systemd-sysctl.service: Deactivated successfully.
host1 # [    4.195572] systemd[1]: Stopped Apply Kernel Variables.
host1 # [    4.200413] systemd[1]: systemd-modules-load.service: Deactivated successfully.
host1 # [    4.203829] systemd[1]: Stopped Load Kernel Modules.
host1 # [    4.205315] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.
host1 # [    4.211753] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.
host1 # [    4.213598] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
host1 # [    4.214668] systemd[1]: Stopped Create System Files and Directories.
host1 # [    4.215572] systemd[1]: Stopped target Local File Systems.
host1 # [    4.218429] systemd[1]: Stopped target Preparation for Local File Systems.
host1 # [    4.222308] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
host1 # [    4.227941] systemd[1]: Stopped Coldplug All udev Devices.
host1 # [    4.229056] systemd[1]: Stopping Rule-based Manager for Device Events and Files...
host1 # [    4.230108] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
host1 # [    4.231139] systemd[1]: Stopped Virtual Console Setup.
host1 # [    4.231884] systemd[1]: initrd-cleanup.service: Deactivated successfully.
host1 # [    4.233577] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.
host1 # [    4.234536] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.
host1 # [    4.235562] systemd[1]: Finished rw-sysroot-nix-store.service.
host1 # [    4.236535] systemd[1]: systemd-udevd.service: Deactivated successfully.
host1 # [    4.237532] systemd[1]: Stopped Rule-based Manager for Device Events and Files.
host1 # [    4.238564] systemd[1]: systemd-udevd.service: Consumed 1.065s CPU time over 2.275s wall clock time, 18.5M memory peak.
host1 # [    4.248221] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
host1 # [    4.249413] systemd[1]: Closed udev Control Socket.
host1 # [    4.250395] systemd[1]: Starting Cleanup udev Database...
host1 # [    4.251223] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
host1 # [    4.256244] systemd[1]: Stopped Create Static Device Nodes in /dev.
host1 # [    4.257249] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.
host1 # [    4.258414] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.
host1 # [    4.264480] systemd[1]: kmod-static-nodes.service: Deactivated successfully.
host1 # [    4.265543] systemd[1]: Stopped Create List of Static Device Nodes.
host1 # [    4.280506] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
host1 # [    4.283027] systemd[1]: Finished Cleanup udev Database.
host1 # [    4.288613] systemd[1]: Reached target Switch Root.
host1 # [    4.289426] systemd[1]: Starting NixOS Activation...
host1 # [    4.358893] initrd-nixos-activation-start[173]: booting system configuration /nix/store/8l3qg5rvgynixnmqxwr6gn0bsscd062g-nixos-system-host1-test
host1 # [    4.389914] initrd-nixos-activation-start[173]: running activation script...
server # [    4.549221] (udev-worker)[90]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory
server # [    4.555295] (udev-worker)[90]: 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 directory
server # [    4.596113] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
server # [    4.597560] systemd[1]: Stopped Virtual Console Setup.
server # [    4.600916] systemd[1]: Stopping Virtual Console Setup...
server # [    4.601932] systemd[1]: Starting Virtual Console Setup...
server # [    4.618246] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
server # [    4.619456] systemd[1]: Stopped Virtual Console Setup.
server # [    4.624183] systemd[1]: Starting Virtual Console Setup...
host1 # [    4.606207] initrd-nixos-activation-start[196]: setting up /etc...
server # [    4.643816] systemd-vconsole-setup[157]: Configuration of first virtual console was skipped, ignoring remaining ones.
server # [    4.647629] systemd[1]: Finished Virtual Console Setup.
host1 # [    4.720590] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.
host1 # [    4.723405] systemd[1]: Finished NixOS Activation.
host1 # [    4.724805] systemd[1]: Starting Switch Root...
host1 # [    4.751495] systemd[1]: Switching root.
server # [    4.815211] systemd[1]: Mounting /sysroot/nix/store...
server # [    4.860869] systemd[1]: Mounted /sysroot/nix/store.
server # [    4.863464] systemd[1]: Reached target Initrd File Systems.
server # [    4.868174] systemd[1]: Starting Find NixOS closure...
server # [    4.880361] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...
server # [    4.927523] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.
server # [    4.933909] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.
host1 # [    4.926806] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).
server # [    4.941274] systemd[1]: Finished Find NixOS closure.
server # [    4.944245] systemd[1]: Reached target Initrd Default Target.
server # [    4.946700] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...
server # [    4.968378] systemd[1]: Stopped target Initrd Default Target.
server # [    4.970256] systemd[1]: Stopped target Basic System.
server # [    4.971321] systemd[1]: Stopped target Initrd Root Device.
server # [    4.972802] systemd[1]: Stopped target Path Units.
server # [    4.976395] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
server # [    4.977623] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.
server # [    4.979775] systemd[1]: Stopped target Slice Units.
server # [    4.984257] systemd[1]: Stopped target Socket Units.
server # [    4.985076] systemd[1]: Stopped target System Initialization.
server # [    4.985948] systemd[1]: Stopped target Swaps.
server # [    4.986626] systemd[1]: Stopped target Timer Units.
server # [    4.987382] systemd[1]: dbus.socket: Deactivated successfully.
server # [    4.994183] systemd[1]: Closed D-Bus System Message Bus Socket.
server # [    4.996070] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.
server # [    4.999747] systemd[1]: Stopped Find NixOS closure.
server # [    5.004278] systemd[1]: Starting rw-sysroot-nix-store.service...
server # [    5.005901] systemd[1]: systemd-sysctl.service: Deactivated successfully.
server # [    5.016447] systemd[1]: Stopped Apply Kernel Variables.
server # [    5.017361] systemd[1]: systemd-modules-load.service: Deactivated successfully.
server # [    5.020986] systemd[1]: Stopped Load Kernel Modules.
server # [    5.025033] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.
server # [    5.026762] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.
server # [    5.031923] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
server # [    5.034972] systemd[1]: Stopped Create System Files and Directories.
server # [    5.040627] systemd[1]: Stopped target Local File Systems.
server # [    5.041545] systemd[1]: Stopped target Preparation for Local File Systems.
server # [    5.042541] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
server # [    5.043695] systemd[1]: Stopped Coldplug All udev Devices.
server # [    5.045311] systemd[1]: Stopping Rule-based Manager for Device Events and Files...
server # [    5.046484] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
server # [    5.047676] systemd[1]: Stopped Virtual Console Setup.
server # [    5.048582] systemd[1]: initrd-cleanup.service: Deactivated successfully.
server # [    5.049558] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.
server # [    5.050522] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.
server # [    5.051572] systemd[1]: Finished rw-sysroot-nix-store.service.
server # [    5.057034] systemd[1]: systemd-udevd.service: Deactivated successfully.
server # [    5.060175] systemd[1]: Stopped Rule-based Manager for Device Events and Files.
server # [    5.061606] systemd[1]: systemd-udevd.service: Consumed 1.518s CPU time over 3.044s wall clock time, 23.9M memory peak.
server # [    5.068281] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
server # [    5.069412] systemd[1]: Closed udev Control Socket.
server # [    5.070161] systemd[1]: Starting Cleanup udev Database...
server # [    5.070980] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
server # [    5.076443] systemd[1]: Stopped Create Static Device Nodes in /dev.
server # [    5.077421] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.
server # [    5.078601] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.
server # [    5.079663] systemd[1]: kmod-static-nodes.service: Deactivated successfully.
server # [    5.084201] systemd[1]: Stopped Create List of Static Device Nodes.
server # [    5.106440] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
server # [    5.109898] systemd[1]: Finished Cleanup udev Database.
server # [    5.112124] systemd[1]: Reached target Switch Root.
server # [    5.116167] systemd[1]: Starting NixOS Activation...
server # [    5.196550] initrd-nixos-activation-start[180]: booting system configuration /nix/store/w6gnz0kfj9gf6zsjcaxac0hn63r4ik34-nixos-system-server-test
server # [    5.233724] initrd-nixos-activation-start[180]: running activation script...
host1 # [    5.425188] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -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)
host1 # [    5.425256] systemd[1]: Detected virtualization qemu.
host1 # [    5.425334] systemd[1]: Detected architecture arm64.
host1 # [    5.425572] systemd[1]: Detected first boot.
host1 # [    5.427770] systemd[1]: Initializing machine ID from random generator.
server # [    5.488375] initrd-nixos-activation-start[203]: setting up /etc...
server # [    5.610421] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.
server # [    5.613691] systemd[1]: Finished NixOS Activation.
server # [    5.615025] systemd[1]: Starting Switch Root...
server # [    5.642184] systemd[1]: Switching root.
host1 # [    5.692895] systemd[1]: bpf-restrict-fs: LSM BPF program attached
server # [    5.822365] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).
host1 # [    5.867483] systemd[1]: Applying preset policy.
host1 # [    6.105999] systemd[1]: Populated /etc with preset unit settings.
server # [    6.297872] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -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)
server # [    6.297923] systemd[1]: Detected virtualization qemu.
server # [    6.297982] systemd[1]: Detected architecture arm64.
server # [    6.298092] systemd[1]: Detected first boot.
server # [    6.299806] systemd[1]: Initializing machine ID from random generator.
host1 # [    6.318472] systemd[1]: initrd-switch-root.service: Deactivated successfully.
host1 # [    6.318891] systemd[1]: Stopped initrd-switch-root.service.
host1 # [    6.320809] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
host1 # [    6.326774] systemd[1]: Created slice Slice /system/getty.
host1 # [    6.328094] systemd[1]: Created slice User and Session Slice.
host1 # [    6.328636] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
host1 # [    6.332483] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
host1 # [    6.332911] systemd[1]: Expecting device /dev/hvc0...
host1 # [    6.335694] systemd[1]: Expecting device /dev/ttyAMA0...
host1 # [    6.336077] systemd[1]: Reached target Local Encrypted Volumes.
host1 # [    6.336685] systemd[1]: Stopped target initrd-fs.target.
host1 # [    6.339984] systemd[1]: Stopped target initrd-root-fs.target.
host1 # [    6.340367] systemd[1]: Stopped target initrd-switch-root.target.
host1 # [    6.340969] systemd[1]: Reached target Virtual Machines and Containers.
host1 # [    6.344422] systemd[1]: Reached target Path Units.
host1 # [    6.344786] systemd[1]: Reached target Remote File Systems.
host1 # [    6.347364] systemd[1]: Reached target Slice Units.
host1 # [    6.347735] systemd[1]: Reached target Swaps.
host1 # [    6.351091] systemd[1]: Listening on Query the User Interactively for a Password.
host1 # [    6.353001] systemd[1]: Listening on Process Core Dump Socket.
host1 # [    6.356102] systemd[1]: Listening on Credential Encryption/Decryption.
host1 # [    6.358554] systemd[1]: Listening on Factory Reset Management.
host1 # [    6.359050] systemd[1]: Listening on Hostname Service Socket.
host1 # [    6.363639] systemd[1]: Starting Journal Log Access Socket...
host1 # [    6.364994] systemd[1]: Listening on Journal Audit Socket.
host1 # [    6.368727] systemd[1]: Listening on Console Output Muting Service Socket.
host1 # [    6.371025] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
host1 # [    6.372252] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os
host1 # [    6.375524] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki
host1 # [    6.380283] systemd[1]: Listening on Disk Repartitioning Service Socket.
host1 # [    6.380784] systemd[1]: Listening on udev Control Socket.
host1 # [    6.383799] systemd[1]: Listening on udev Varlink Socket.
host1 # [    6.386931] systemd[1]: Mounting Huge Pages File System...
host1 # [    6.390594] systemd[1]: Mounting POSIX Message Queue File System...
host1 # [    6.405186] systemd[1]: Mounting Kernel Debug File System...
host1 # [    6.415982] systemd[1]: Mounting Kernel Trace File System...
host1 # [    6.429290] systemd[1]: Starting Create List of Static Device Nodes...
host1 # [    6.429855] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs
host1 # [    6.445381] systemd[1]: Mounting Kernel Configuration File System...
host1 # [    6.448542] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm
host1 # [    6.460559] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore
host1 # [    6.460929] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse
host1 # [    6.481302] systemd[1]: Mounting FUSE Control File System...
host1 # [    6.481805] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67
host1 # [    6.511672] systemd[1]: Starting Journal Service...
host1 # [    6.525702] systemd[1]: Starting Load Kernel Modules...
server # [    6.587355] systemd[1]: bpf-restrict-fs: LSM BPF program attached
host1 # [    6.557617] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
host1 # [    6.568571] systemd[1]: Starting Remount Root and Kernel File Systems...
host1 # [    6.568941] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os
host1 # [    6.576962] systemd-journald[267]: Collecting audit messages is enabled.
host1 # [    6.593848] systemd[1]: Starting Coldplug All udev Devices...
host1 # [    6.596564] systemd[1]: Listening on Journal Log Access Socket.
host1 # [    6.596966] systemd[1]: Mounted Huge Pages File System.
host1 # [    6.591967] systemd[1]: Queued start job for default target Multi-User System.
host1 # [    6.597049] systemd[1]: systemd-journald.service: Deactivated successfully.
host1 # [    6.623136] systemd[1]: Mounted POSIX Message Queue File System.
host1 # [    6.634093] systemd[1]: Started Journal Service.
host1 # [    6.629210] systemd-modules-load[268]: Module 'atkbd' is built in
host1 # [    6.636245] systemd-modules-load[268]: Module 'loop' is built in
host1 # [    6.637345] systemd[1]: Mounted Kernel Debug File System.
host1 # [    6.638178] systemd[1]: Mounted Kernel Trace File System.
host1 # [    6.639001] systemd[1]: Finished Create List of Static Device Nodes.
host1 # [    6.652430] systemd[1]: Finished Load Kernel Modules.
host1 # [    6.669610] EXT4-fs (vda): re-mounted 39a96e25-1b9c-4cae-8a87-a91134af90a2.
host1 # [    6.673594] systemd[1]: Starting Firewall...
host1 # [    6.684473] systemd[1]: Starting Apply Kernel Variables...
server # [    6.742484] systemd[1]: Applying preset policy.
host1 # [    6.710371] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
host1 # [    6.711603] systemd[1]: Mounted FUSE Control File System.
host1 # [    6.720146] systemd[1]: Finished Remount Root and Kernel File Systems.
host1 # [    6.723313] systemd[1]: Listening on Disk Image Download Service Socket.
host1 # [    6.757510] systemd[1]: Starting Flush Journal to Persistent Storage...
host1 # [    6.758611] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore
host1 # [    6.779720] systemd[1]: Starting Load/Save OS Random Seed...
host1 # [    6.784546] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os
host1 # [    6.801203] systemd-oomd[270]: No swap; memory pressure usage will be degraded
host1 # [    6.802790] systemd[1]: Mounted Kernel Configuration File System.
host1 # [    6.822107] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
host1 # [    6.901334] systemd-journald[267]: Received client request to flush runtime journal.
server # [    6.950229] systemd[1]: Populated /etc with preset unit settings.
host1 # [    6.952492] systemd[1]: Finished Apply Kernel Variables.
host1 # [    6.953503] systemd[1]: Finished Load/Save OS Random Seed.
host1 # [    6.954320] systemd[1]: Reached target First Boot Complete.
host1 # [    6.964953] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
host1 # [    6.977398] systemd[1]: Starting Create Static Device Nodes in /dev...
host1 # [    6.978647] systemd[1]: Finished Flush Journal to Persistent Storage.
host1 # [    7.120983] systemd[1]: Finished Create Static Device Nodes in /dev.
server # [    7.163707] systemd[1]: initrd-switch-root.service: Deactivated successfully.
server # [    7.164513] systemd[1]: Stopped initrd-switch-root.service.
host1 # [    7.123865] systemd[1]: Reached target Preparation for Local File Systems.
server # [    7.169639] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
server # [    7.171384] systemd[1]: Created slice Slice /system/getty.
server # [    7.172722] systemd[1]: Created slice User and Session Slice.
server # [    7.176475] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
host1 # [    7.133451] systemd[1]: Starting Rule-based Manager for Device Events and Files...
server # [    7.178609] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
server # [    7.179007] systemd[1]: Expecting device /dev/hvc0...
server # [    7.179616] systemd[1]: Expecting device /dev/ttyAMA0...
server # [    7.180221] systemd[1]: Reached target Local Encrypted Volumes.
server # [    7.184492] systemd[1]: Stopped target initrd-fs.target.
server # [    7.186164] systemd[1]: Stopped target initrd-root-fs.target.
server # [    7.186531] systemd[1]: Stopped target initrd-switch-root.target.
server # [    7.187156] systemd[1]: Reached target Virtual Machines and Containers.
server # [    7.187765] systemd[1]: Reached target Path Units.
server # [    7.188345] systemd[1]: Reached target Remote File Systems.
server # [    7.193276] systemd[1]: Reached target Slice Units.
server # [    7.193655] systemd[1]: Reached target Swaps.
server # [    7.195746] systemd[1]: Listening on Query the User Interactively for a Password.
server # [    7.199981] systemd[1]: Listening on Process Core Dump Socket.
server # [    7.202433] systemd[1]: Listening on Credential Encryption/Decryption.
server # [    7.203873] systemd[1]: Listening on Factory Reset Management.
server # [    7.204337] systemd[1]: Listening on Hostname Service Socket.
server # [    7.210607] systemd[1]: Starting Journal Log Access Socket...
server # [    7.212169] systemd[1]: Listening on Journal Audit Socket.
server # [    7.216289] systemd[1]: Listening on Console Output Muting Service Socket.
server # [    7.218467] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
server # [    7.219365] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os
server # [    7.219705] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki
server # [    7.227228] systemd[1]: Listening on Disk Repartitioning Service Socket.
server # [    7.227751] systemd[1]: Listening on udev Control Socket.
server # [    7.228429] systemd[1]: Listening on udev Varlink Socket.
server # [    7.233881] systemd[1]: Mounting Huge Pages File System...
server # [    7.241023] systemd[1]: Mounting POSIX Message Queue File System...
server # [    7.243696] systemd[1]: Mounting Kernel Debug File System...
server # [    7.260226] systemd[1]: Mounting Kernel Trace File System...
server # [    7.272228] systemd[1]: Starting Create List of Static Device Nodes...
server # [    7.272678] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs
server # [    7.291450] systemd[1]: Mounting Kernel Configuration File System...
server # [    7.291820] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm
server # [    7.292069] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore
server # [    7.292319] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse
server # [    7.310614] systemd[1]: Mounting FUSE Control File System...
server # [    7.311033] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67
server # [    7.353992] systemd[1]: Starting Journal Service...
host1 # [    7.322254] systemd[1]: Mounting /run/wrappers...
server # [    7.369881] systemd[1]: Starting Load Kernel Modules...
server # [    7.388020] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
server # [    7.402961] systemd[1]: Starting Remount Root and Kernel File Systems...
server # [    7.403320] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os
server # [    7.414479] systemd[1]: Starting Coldplug All udev Devices...
server # [    7.419925] systemd[1]: Listening on Journal Log Access Socket.
server # [    7.424604] systemd[1]: Mounted Huge Pages File System.
server # [    7.426672] systemd[1]: Mounted POSIX Message Queue File System.
server # [    7.428035] systemd[1]: Mounted Kernel Debug File System.
server # [    7.430928] systemd[1]: Mounted Kernel Trace File System.
server # [    7.432192] systemd[1]: Mounted Kernel Configuration File System.
server # [    7.437050] systemd[1]: Finished Create List of Static Device Nodes.
server # [    7.437750] systemd[1]: Mounted FUSE Control File System.
server # [    7.444458] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
host1 # [    7.405474] systemd[1]: Mounted /run/wrappers.
host1 # [    7.406344] systemd[1]: Reached target Local File Systems.
host1 # [    7.412138] systemd[1]: Listening on Boot Loader Control Service Socket.
host1 # [    7.418198] systemd[1]: Starting register-nix-paths.service...
host1 # [    7.420824] systemd-udevd[307]: Using default interface naming scheme 'v261'.
host1 # [    7.427462] systemd[1]: Starting Create SUID/SGID Wrappers...
host1 # [    7.432280] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.
host1 # [    7.441923] systemd[1]: Starting Save Transient machine-id to Disk...
host1 # [    7.474238] systemd[1]: Starting Create System Files and Directories...
server # [    7.525260] systemd[1]: Finished Load Kernel Modules.
server # [    7.533205] systemd[1]: Starting Firewall...
server # [    7.537537] systemd[1]: Starting Apply Kernel Variables...
server # [    7.549659] EXT4-fs (vda): re-mounted 7504b1f0-3e09-464e-881f-aa277ed43f01.
server # [    7.560068] systemd[1]: Finished Remount Root and Kernel File Systems.
server # [    7.560782] systemd[1]: Listening on Disk Image Download Service Socket.
server # [    7.563787] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore
server # [    7.567940] systemd-journald[273]: Collecting audit messages is enabled.
server # [    7.583785] systemd[1]: Starting Load/Save OS Random Seed...
server # [    7.585976] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os
host1 # [    7.598000] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
server # [    7.648707] systemd[1]: Started Journal Service.
server # [    7.635055] systemd[1]: Queued start job for default target Multi-User System.
server # [    7.636581] systemd[1]: systemd-journald.service: Deactivated successfully.
host1 # [    7.616132] systemd[1]: Finished Save Transient machine-id to Disk.
server # [    7.655705] systemd-modules-load[274]: Module 'atkbd' is built in
server # [    7.668110] systemd-modules-load[274]: Module 'loop' is built in
host1 # [    7.643333] systemd[1]: Started Rule-based Manager for Device Events and Files.
server # [    7.676537] systemd[1]: Starting Flush Journal to Persistent Storage...
server # [    7.706512] systemd-oomd[275]: No swap; memory pressure usage will be degraded
server # [    7.721929] systemd[1]: Finished Apply Kernel Variables.
server # [    7.729326] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
server # [    7.736945] systemd[1]: Finished Load/Save OS Random Seed.
server # [    7.737919] systemd[1]: Reached target First Boot Complete.
host1 # [    7.725722] systemd[1]: Finished Create System Files and Directories.
server # [    7.756310] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
server # [    7.757461] systemd[1]: Starting Create Static Device Nodes in /dev...
host1 # [    7.741298] systemd[1]: Starting Rebuild Journal Catalog...
host1 # [    7.760804] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
server # [    7.825010] systemd-journald[273]: Received client request to flush runtime journal.
server # [    7.885078] systemd[1]: Finished Flush Journal to Persistent Storage.
server # [    7.922750] systemd[1]: Finished Create Static Device Nodes in /dev.
server # [    7.924657] systemd[1]: Reached target Preparation for Local File Systems.
server # [    7.932536] systemd[1]: Starting Rule-based Manager for Device Events and Files...
host1 # [    7.943053] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
host1 # [    7.982489] systemd[1]: Finished Rebuild Journal Catalog.
host1 # [    7.996764] systemd[1]: Starting Update is Completed...
server # [    8.037053] systemd-udevd[316]: Using default interface naming scheme 'v261'.
host1 # [    8.125329] systemd[1]: Finished Update is Completed.
server # [    8.150069] systemd[1]: Mounting /run/wrappers...
server # [    8.199680] systemd[1]: Mounted /run/wrappers.
server # [    8.201155] systemd[1]: Reached target Local File Systems.
server # [    8.210060] systemd[1]: Listening on Boot Loader Control Service Socket.
server # [    8.211119] systemd[1]: Starting register-nix-paths.service...
server # [    8.228426] systemd[1]: Starting Create SUID/SGID Wrappers...
server # [    8.235825] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.
server # [    8.248656] systemd[1]: Starting Save Transient machine-id to Disk...
server # [    8.254246] systemd[1]: Starting Create System Files and Directories...
server # [    8.260831] systemd[1]: Started Rule-based Manager for Device Events and Files.
server # [    8.339742] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
server # [    8.349796] systemd[1]: Finished Save Transient machine-id to Disk.
server # [    8.478642] systemd[1]: Finished Create System Files and Directories.
server # [    8.487634] systemd[1]: Starting Rebuild Journal Catalog...
server # [    8.498239] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
server # [    8.612829] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
host1 # [    8.609683] systemd[1]: Finished Coldplug All udev Devices.
server # [    8.686958] systemd[1]: Finished Rebuild Journal Catalog.
server # [    8.690888] systemd[1]: Starting Update is Completed...
host1 # [    8.740660] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.
host1 # [    8.745093] systemd[1]: Finished Create SUID/SGID Wrappers.
server # [    8.794569] systemd[1]: Finished Update is Completed.
server # [    9.134591] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.
server # [    9.141036] systemd[1]: Finished Create SUID/SGID Wrappers.
host1 # [    9.294264] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3
host1 # [    9.311296] systemd[1]: Finished register-nix-paths.service.
host1 # [    9.317757] systemd[1]: Reached target System Initialization.
host1 # [    9.318744] systemd[1]: Started Renew ACME Certificate for host1.
host1 # [    9.319668] systemd[1]: Started Discard unused filesystem blocks once a week.
host1 # [    9.325871] systemd[1]: Started Daily Cleanup of Temporary Directories.
host1 # [    9.326915] systemd[1]: Reached target Timer Units.
host1 # [    9.327660] systemd[1]: Listening on D-Bus System Message Bus Socket.
host1 # [    9.332423] systemd[1]: Listening on Nix Daemon Socket.
host1 # [    9.333262] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).
host1 # [    9.346687] systemd[1]: Starting OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK)...
host1 # [    9.348151] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
host1 # [    9.353990] systemd[1]: Starting D-Bus System Message Bus...
server # [    9.425938] systemd[1]: Finished Firewall.
host1 # [    9.412519] systemd-ssh-issue[480]: Options --make-vsock/--rm-vsock have been replaced by make-vsock/rm-vsock verbs.
host1 # [    9.422783] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK).
host1 # [    9.428213] systemd[1]: Reached target Socket Units.
host1 # [    9.521403] dbus-broker-launch[481]: Looking up NSS user entry for 'systemd-timesync'...
host1 # [    9.530251] dbus-broker-launch[481]: NSS returned no entry for 'systemd-timesync'
host1 # [    9.531450] dbus-broker-launch[481]: Invalid user-name in /nix/store/740jcqhlc6jq7vn8bxzw06p1c2bp5ykk-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
host1 # [    9.548264] systemd[1]: Finished Firewall.
host1 # [    9.628479] systemd[1]: Started D-Bus System Message Bus.
host1 # [    9.629462] systemd[1]: Reached target Basic System.
host1 # [    9.637624] systemd[1]: Starting Set up the ACME certificate renewal infrastructure...
host1 # [    9.642489] systemd[1]: Starting Import lastlog data into lastlog2 database...
host1 # [    9.647988] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
host1 # [    9.661076] systemd[1]: Starting Post-Boot Actions...
host1 # [    9.689041] dbus-broker-launch[481]: Ready
host1 # [    9.693472] systemd[1]: Started Reset console on configuration changes.
server # [    9.721526] systemd[1]: Finished Coldplug All udev Devices.
host1 # [    9.736498] systemd[1]: Starting resolvconf update...
server # [    9.777181] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs
host1 # [    9.784133] systemd[1]: Starting SSH Host Keys Generation...
server # [    9.815767] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse
host1 # [    9.811063] acme-setup-privileged[495]: + set -euo pipefail
host1 # [    9.822342] acme-setup-privileged[495]: + cd /var/lib/acme
host1 # [    9.823242] acme-setup-privileged[495]: + chmod -R u=rwX,g=,o= .lego/accounts
host1 # [    9.832381] acme-setup-privileged[495]: + chown -R acme .lego/accounts
host1 # [    9.853314] acme-setup-privileged[495]: + for fixpath in host1 .lego/host1
host1 # [    9.854429] acme-setup-privileged[495]: + '[' -d host1 ']'
host1 # [    9.855272] acme-setup-privileged[495]: + for fixpath in host1 .lego/host1
host1 # [    9.870621] acme-setup-privileged[495]: + '[' -d .lego/host1 ']'
host1 # [    9.871644] systemd[1]: Started Name Service Cache Daemon (nsncd).
server # [    9.901792] systemd[1]: Finished register-nix-paths.service.
server # [    9.903405] systemd[1]: Reached target System Initialization.
server # [    9.905558] systemd[1]: Started Renew ACME Certificate for server.
host1 # [    9.883401] nsncd[497]: Sep 30 09:10:55.408 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
server # [    9.907838] systemd[1]: Started Discard unused filesystem blocks once a week.
server # [    9.911856] systemd[1]: Started Daily Cleanup of Temporary Directories.
server # [    9.913226] systemd[1]: Reached target Timer Units.
server # [    9.913985] systemd[1]: Listening on D-Bus System Message Bus Socket.
server # [    9.914906] systemd[1]: Listening on Nix Daemon Socket.
server # [    9.915697] systemd[1]: Listening on Nix worker-protocol over gRPC.
server # [    9.923832] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).
host1 # [    9.906139] systemd[1]: Finished Post-Boot Actions.
server # [    9.932644] systemd[1]: Starting OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK)...
server # [    9.938465] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
server # [    9.947828] systemd[1]: Starting D-Bus System Message Bus...
host1 # [    9.934808] systemd[1]: Reached target Host and Network Name Lookups.
host1 # [    9.944858] systemd[1]: Reached target User and Group Name Lookups.
server # [    9.988733] systemd-ssh-issue[499]: Options --make-vsock/--rm-vsock have been replaced by make-vsock/rm-vsock verbs.
host1 # [    9.971173] systemd[1]: Starting User Login Management...
server # [    9.997068] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK).
server # [   10.000898] systemd[1]: Reached target Socket Units.
server # [   10.028603] dbus-broker-launch[500]: Looking up NSS user entry for 'systemd-timesync'...
server # [   10.033602] dbus-broker-launch[500]: NSS returned no entry for 'systemd-timesync'
server # [   10.037608] dbus-broker-launch[500]: Invalid user-name in /nix/store/gxzza24k6p57gy91fxf5q3mhvcmkl3sw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
host1 # [   10.021350] systemd[1]: Finished Import lastlog data into lastlog2 database.
server # [   10.052092] systemd[1]: Started D-Bus System Message Bus.
server # [   10.059507] systemd[1]: Reached target Basic System.
server # [   10.074938] systemd[1]: Starting Set up the ACME certificate renewal infrastructure...
server # [   10.092598] systemd[1]: Starting Import lastlog data into lastlog2 database...
server # [   10.107682] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
server # [   10.125191] systemd[1]: Starting Post-Boot Actions...
server # [   10.132945] systemd[1]: Started Reset console on configuration changes.
server # [   10.141177] systemd[1]: Starting resolvconf update...
server # [   10.151927] systemd[1]: Starting SSH Host Keys Generation...
server # [   10.164809] systemd[1]: Starting step-ca-pki.service...
server # [   10.180549] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.
host1 # [   10.157349] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs
server # [   10.195209] dbus-broker-launch[500]: Ready
host1 # [   10.201282] acme-setup-start[527]: + set -euo pipefail
host1 # [   10.202260] acme-setup-start[527]: + test -e ca/key.pem
host1 # [   10.203034] acme-setup-start[527]: + minica --ca-key ca/key.pem --ca-cert ca/cert.pem --domains selfsigned.local
host1 # [   10.218521] systemd[1]: Stopped target Host and Network Name Lookups.
host1 # [   10.219613] systemd[1]: Stopping Host and Network Name Lookups...
host1 # [   10.225664] systemd[1]: Stopped target User and Group Name Lookups.
host1 # [   10.226636] systemd[1]: Stopping User and Group Name Lookups...
host1 # [   10.227498] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
server # [   10.265093] systemd[1]: Finished Post-Boot Actions.
host1 # [   10.244294] systemd[1]: nscd.service: Deactivated successfully.
host1 # [   10.245248] systemd[1]: Stopped Name Service Cache Daemon (nsncd).
server # [   10.276824] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.
server # [   10.287449] systemd[1]: Started backdoor.service.
host1 # [   10.269363] systemd-logind[531]: New seat seat0.
host1 # [   10.275258] systemd[1]: Started User Login Management.
server # [   10.313567] systemd[1]: Started Name Service Cache Daemon (nsncd).
host1 # [   10.302892] systemd[1]: Starting linger-users.service...
server # [   10.325130] nsncd[506]: Sep 30 09:10:55.832 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
host1 # [   10.316119] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
server # [   10.347694] acme-setup-privileged[503]: + set -euo pipefail
server # [   10.358966] acme-setup-privileged[503]: + cd /var/lib/acme
host1 # [   10.335739] systemd[1]: Starting Virtual Console Setup...
server # [   10.369601] acme-setup-privileged[503]: + chmod -R u=rwX,g=,o= .lego/accounts
server # [   10.377238] systemd[1]: Reached target Host and Network Name Lookups.
host1 # [   10.360214] systemd[1]: Finished Set up the ACME certificate renewal infrastructure.
server # [   10.392805] acme-setup-privileged[503]: + chown -R acme .lego/accounts
server # [   10.406439] acme-setup-privileged[503]: + for fixpath in server .lego/server
server # [   10.413181] acme-setup-privileged[503]: + '[' -d server ']'
server # [   10.421049] acme-setup-privileged[503]: + for fixpath in server .lego/server
server # [   10.430555] acme-setup-privileged[503]: + '[' -d .lego/server ']'
server # [   10.438733] systemd[1]: Reached target User and Group Name Lookups.
server # [   10.451415] systemd[1]: Starting User Login Management...
server # [   10.461469] systemd[1]: Finished Import lastlog data into lastlog2 database.
host1 # [   10.456214] systemd[1]: Starting Ensure certificate for host1...
server # connecting to host...
host1 # [   10.496454] systemd[1]: Finished resolvconf update.
host1 # [   10.500871] systemd[1]: linger-users.service: Deactivated successfully.
host1 # [   10.501928] systemd[1]: Finished linger-users.service.
host1 # [   10.511232] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
host1 # [   10.516252] systemd[1]: Stopped Virtual Console Setup.
host1 # [   10.523790] nsncd[595]: Sep 30 09:10:56.066 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
host1 # [   10.537936] systemd[1]: Started Name Service Cache Daemon (nsncd).
server: Guest shell says: b'Spawning backdoor root shell...\n'
host1 # [   10.559719] systemd[1]: Reached target Preparation for Network.
host1 # [   10.565270] systemd[1]: Reached target Host and Network Name Lookups.
host1 # [   10.566208] systemd[1]: Reached target User and Group Name Lookups.
host1 # [   10.571836] systemd[1]: Starting DHCP Client...
server: connected to guest root shell
host1 # [   10.582020] systemd[1]: Starting Extra networking commands....
server: (connecting took 10.92 seconds)
host1 # [   10.589401] systemd[1]: Starting Virtual Console Setup...
server: (finished: waiting for the VM to finish booting, in 10.92 seconds)
host1 # [   10.653411] acme-host1-start[607]: Waiting to acquire lock in /run/acme/
server # [   10.696563] systemd-logind[537]: New seat seat0.
server # [   10.705562] systemd[1]: Started User Login Management.
server # [   10.706427] systemd[1]: Starting linger-users.service...
host1 # [   10.684147] acme-host1-start[607]: + '[' -e out/acme-success ']'
host1 # [   10.685280] acme-host1-start[607]: + minica --ca-cert=ca/cert.pem --ca-key=ca/key.pem --domains=host1 --ip-addresses=
server # [   10.765701] acme-setup-start[546]: + set -euo pipefail
server # [   10.766584] acme-setup-start[546]: + test -e ca/key.pem
server # [   10.767316] acme-setup-start[546]: + minica --ca-key ca/key.pem --ca-cert ca/cert.pem --domains selfsigned.local
host1 # [   10.753422] acme-host1-start[638]: + cd host1
host1 # [   10.754225] acme-host1-start[638]: + cp -vp cert.pem ../out/cert.pem
host1 # [   10.772676] acme-host1-start[639]: 'cert.pem' -> '../out/cert.pem'
server # [   10.798927] systemd[1]: Stopped target Host and Network Name Lookups.
host1 # [   10.778369] acme-host1-start[638]: + cp -vp key.pem ../out/key.pem
host1 # [   10.787047] acme-host1-start[638]: 'key.pem' -> '../out/key.pem'
server # [   10.819817] systemd[1]: Stopping Host and Network Name Lookups...
host1 # [   10.798600] acme-host1-start[607]: + cat out/cert.pem ca/cert.pem
server # [   10.829265] systemd[1]: Stopped target User and Group Name Lookups.
server # [   10.830281] systemd[1]: Stopping User and Group Name Lookups...
server # [   10.831170] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
host1 # [   10.816593] acme-host1-start[607]: + cp ca/cert.pem out/chain.pem
server # [   10.858648] systemd[1]: nscd.service: Deactivated successfully.
host1 # [   10.840836] acme-host1-start[607]: + cat out/key.pem out/fullchain.pem
server # [   10.867954] systemd[1]: Stopped Name Service Cache Daemon (nsncd).
host1 # [   10.860516] acme-host1-start[607]: + for fixpath in out certificates
host1 # [   10.861577] acme-host1-start[607]: + '[' -d out ']'
host1 # [   10.862323] acme-host1-start[607]: + chmod -R u=rwX,g=rX,o= out
server # [   10.879331] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
server # [   10.904139] systemd[1]: linger-users.service: Deactivated successfully.
server # [   10.905170] systemd[1]: Finished linger-users.service.
host1 # [   10.887182] acme-host1-start[607]: + chown -R acme:acme out
host1 # [   10.917008] acme-host1-start[607]: + for fixpath in out certificates
host1 # [   10.918074] acme-host1-start[607]: + '[' -d certificates ']'
host1 # [   10.928412] systemd[1]: Finished Ensure certificate for host1.
server # [   10.964473] systemd[1]: Finished Set up the ACME certificate renewal infrastructure.
server # [   10.973647] systemd[1]: Starting Ensure certificate for server...
server # [   11.007818] systemd[1]: Started Name Service Cache Daemon (nsncd).
server # [   11.018608] nsncd[609]: Sep 30 09:10:56.527 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
server # [   11.032373] systemd[1]: Reached target Host and Network Name Lookups.
server # [   11.033370] systemd[1]: Reached target User and Group Name Lookups.
host1 # [   11.049845] dhcpcd[649]: dhcpcd-10.3.2 starting
host1 # [   11.067521] dhcpcd[699]: dev: loaded udev
server # [   11.091025] systemd[1]: Finished resolvconf update.
server # [   11.091956] systemd[1]: Reached target Preparation for Network.
host1 # [   11.072356] dhcpcd[699]: no valid interfaces found
host1 # [   11.073275] dhcpcd[699]: no valid interfaces found
host1 # [   11.082260] dhcpcd[699]: libudev: received NULL device
host1 # [   11.083202] dhcpcd[699]: libudev: received NULL device
server # [   11.112559] (udev-worker)[496]: Network interface NamePolicy= disabled on kernel command line.
server # [   11.113817] systemd[1]: Starting DHCP Client...
server # [   11.114537] systemd[1]: Starting Extra networking commands....
server # [   11.133291] step-ca-pki-start[526]: Your certificate has been saved in root_ca.crt.
server # [   11.134753] (udev-worker)[493]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
server # [   11.159462] (udev-worker)[493]: Network interface NamePolicy= disabled on kernel command line.
host1 # [   11.146984] systemd[1]: Finished Extra networking commands..
host1 # [   11.149639] systemd[1]: Reached target Network.
server # [   11.176683] step-ca-pki-start[526]: Your private key has been saved in root_ca.key.
host1 # [   11.154666] systemd[1]: Starting Permit User Sessions...
host1 # [   11.207108] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.
host1 # [   11.234794] systemd[1]: Finished Permit User Sessions.
host1 # [   11.242519] systemd[1]: Started Getty on tty1.
host1 # [   11.243387] systemd[1]: Reached target Login Prompts.
server # [   11.273649] acme-server-start[629]: Waiting to acquire lock in /run/acme/
server # [   11.317630] acme-server-start[629]: + '[' -e out/acme-success ']'
server # [   11.318595] acme-server-start[629]: + minica --ca-cert=ca/cert.pem --ca-key=ca/key.pem --domains=server --ip-addresses=
host1 # [   11.334536] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.
server # [   11.360208] step-ca-pki-start[650]: Your certificate has been saved in intermediate_ca.crt.
host1 # [   11.345450] systemd[1]: Started backdoor.service.
server # [   11.375160] step-ca-pki-start[650]: Your private key has been saved in intermediate_ca.key.
server # [   11.384825] acme-server-start[681]: + cd server
server # [   11.385533] acme-server-start[681]: + cp -vp cert.pem ../out/cert.pem
server # [   11.403964] acme-server-start[682]: 'cert.pem' -> '../out/cert.pem'
server # [   11.414374] acme-server-start[681]: + cp -vp key.pem ../out/key.pem
server # [   11.424406] acme-server-start[681]: 'key.pem' -> '../out/key.pem'
server # [   11.425360] acme-server-start[629]: + cat out/cert.pem ca/cert.pem
server # [   11.445671] systemd[1]: Finished step-ca-pki.service.
server # [   11.446541] systemd[1]: Condition check resulted in Virtio network device being skipped.
server # [   11.460097] systemd[1]: Starting Address configuration of eth1...
server # [   11.461114] acme-server-start[629]: + cp ca/cert.pem out/chain.pem
host1 # connecting to host...
server # [   11.482423] acme-server-start[629]: + cat out/key.pem out/fullchain.pem
server # [   11.490379] acme-server-start[629]: + for fixpath in out certificates
server # [   11.491340] acme-server-start[629]: + '[' -d out ']'
server # [   11.506257] acme-server-start[629]: + chmod -R u=rwX,g=rX,o= out
server # [   11.525705] acme-server-start[629]: + chown -R acme:nix-grpc-daemon out
host1 # [   11.503899] (udev-worker)[658]: Network interface NamePolicy= disabled on kernel command line.
host1 # [   11.513972] (udev-worker)[677]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
host1 # [   11.527809] (udev-worker)[677]: Network interface NamePolicy= disabled on kernel command line.
host1 # [   11.549002] 8021q: 802.1Q VLAN Support v1.8
server # [   11.566141] acme-server-start[629]: + for fixpath in out certificates
server # [   11.567144] acme-server-start[629]: + '[' -d certificates ']'
server # [   11.578446] systemd[1]: Finished Ensure certificate for server.
server # [   11.592861] dhcpcd[661]: dhcpcd-10.3.2 starting
server # [   11.606082] dhcpcd[726]: dev: loaded udev
server # [   11.652355] 8021q: 802.1Q VLAN Support v1.8
server # [   11.666977] 8021q: adding VLAN 0 to HW filter on device eth1
server # [   11.705110] network-addresses-eth1-start[692]: adding address 192.168.1.2/24... done
host1 # [   11.707908] cfg80211: Loading compiled-in X.509 certificates for regulatory database
server # [   11.736698] systemd[1]: Finished Extra networking commands..
server # [   11.737629] systemd[1]: Reached target Network.
server # [   11.764776] cfg80211: Loading compiled-in X.509 certificates for regulatory database
server # [   11.756262] systemd[1]: Starting Permit User Sessions...
host1 # [   11.751237] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
host1 # [   11.751726] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
host1 # [   11.752991] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2
server # [   11.773461] network-addresses-eth1-start[692]: adding address 2001:db8:1::2/64... done
host1 # [   11.761119] cfg80211: failed to load regulatory.db
server # [   11.810479] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
server # [   11.810987] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
server # [   11.812051] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2
server # [   11.812379] cfg80211: failed to load regulatory.db
server # [   11.817456] systemd[1]: Finished Address configuration of eth1.
server # [   11.856570] systemd[1]: Finished Permit User Sessions.
server # [   11.865487] systemd[1]: Started Getty on tty1.
server # [   11.866299] systemd[1]: Reached target Login Prompts.
server # [   11.923238] dhcpcd[726]: eth0: waiting for carrier
server # [   11.941357] 8021q: adding VLAN 0 to HW filter on device eth0
server # [   11.929164] dhcpcd[726]: eth0: carrier acquired
server # [   11.940779] dhcpcd[726]: DUID 00:01:00:01:32:4f:8e:a1:52:54:00:12:34:56
server # [   11.941785] dhcpcd[726]: eth0: IAID 00:12:34:56
server # [   11.942438] dhcpcd[726]: eth0: adding address fe80::5054:ff:fe12:3456
host1 # [   12.034337] systemd-logind[531]: Watching system buttons on /dev/input/event0 (gpio-keys)
host1 # [   12.078747] systemd[1]: Condition check resulted in Virtio network device being skipped.
host1 # [   12.087728] systemd[1]: Starting Address configuration of eth1...
host1 # [   12.123934] systemd-vconsole-setup[625]: Configuration of first virtual console was skipped, ignoring remaining ones.
host1 # [   12.134492] systemd[1]: Finished Virtual Console Setup.
host1 # [   12.209245] sshd-keygen-start[552]: Generating public/private rsa key pair.
host1 # [   12.210304] sshd-keygen-start[552]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key
host1 # [   12.211497] sshd-keygen-start[552]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub
server # [   12.244252] systemd-logind[537]: Watching system buttons on /dev/input/event0 (gpio-keys)
host1 # [   12.234353] mousedev: PS/2 mouse device common for all mice
host1 # [   12.240361] 8021q: adding VLAN 0 to HW filter on device eth1
host1 # [   12.232201] sshd-keygen-start[552]: The key fingerprint is:
host1 # [   12.233141] sshd-keygen-start[552]: SHA256:p1oaA2kvA2OIllrXfN2suLci5M0xNjb7OrF8Ht8iyQA root@host1
host1 # [   12.234313] sshd-keygen-start[552]: The key's randomart image is:
host1 # [   12.235140] sshd-keygen-start[552]: +---[RSA 4096]----+
host1 # [   12.235862] sshd-keygen-start[552]: |                 |
host1 # [   12.245907] sshd-keygen-start[552]: |                 |
host1 # [   12.246754] sshd-keygen-start[552]: |                 |
host1 # [   12.247475] sshd-keygen-start[552]: |...  +E  . o     |
host1 # [   12.256430] sshd-keygen-start[552]: |oo= = o.S o o    |
host1 # [   12.257261] sshd-keygen-start[552]: |oo = o..X+ .     |
host1 # [   12.258001] sshd-keygen-start[552]: |.   oo+*+@o.     |
host1 # [   12.258722] sshd-keygen-start[552]: |     oo*O.*o..   |
host1 # [   12.259444] sshd-keygen-start[552]: |      o.oO+o...  |
host1 # [   12.273894] 8021q: adding VLAN 0 to HW filter on device eth0
host1 # [   12.272940] sshd-keygen-start[552]: +----[SHA256]-----+
host1 # [   12.274081] dhcpcd[699]: eth0: waiting for carrier
host1 # [   12.275052] network-addresses-eth1-start[729]: adding address 192.168.1.1/24... done
host1 # [   12.285066] dhcpcd[699]: eth0: carrier acquired
host1 # [   12.285835] dhcpcd[699]: DUID 00:01:00:01:32:4f:8e:a1:52:54:00:12:34:56
host1 # [   12.286777] dhcpcd[699]: eth0: IAID 00:12:34:56
host1 # [   12.287425] dhcpcd[699]: eth0: adding address fe80::5054:ff:fe12:3456
host1 # [   12.299186] network-addresses-eth1-start[729]: adding address 2001:db8:1::1/64... done
host1 # [   12.320524] sshd-keygen-start[751]: Generating public/private ed25519 key pair.
host1 # [   12.321594] sshd-keygen-start[751]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key
host1 # [   12.322842] sshd-keygen-start[751]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub
host1 # [   12.330308] sshd-keygen-start[751]: The key fingerprint is:
host1 # [   12.331140] sshd-keygen-start[751]: SHA256:xfdKSxFxRQiKFX5IYSziMqk1ouhhL3wyaeOLBSIkIAY root@host1
host1 # [   12.336165] sshd-keygen-start[751]: The key's randomart image is:
host1 # [   12.337096] sshd-keygen-start[751]: +--[ED25519 256]--+
host1 # [   12.337826] sshd-keygen-start[751]: |E.       .*o+o.+o|
host1 # [   12.338542] sshd-keygen-start[751]: |+     . .B.o o.  |
host1 # [   12.339254] sshd-keygen-start[751]: |..   o ...* +    |
host1 # [   12.339973] sshd-keygen-start[751]: |o . * .  . o o   |
host1 # [   12.348233] sshd-keygen-start[751]: |=. + +  S   o .  |
host1 # [   12.349003] sshd-keygen-start[751]: |=+.        o o   |
host1 # [   12.349707] sshd-keygen-start[751]: |+ =         o    |
host1 # [   12.350412] sshd-keygen-start[751]: |.@ o             |
host1 # [   12.351165] sshd-keygen-start[751]: |+oB              |
host1 # [   12.351884] sshd-keygen-start[751]: +----[SHA256]-----+
host1 # [   12.361835] systemd[1]: sshd-keygen.service: Deactivated successfully.
host1 # [   12.362791] systemd[1]: Finished SSH Host Keys Generation.
host1 # [   12.363580] systemd[1]: Starting SSH Daemon...
host1 # [   12.367844] systemd[1]: Finished Address configuration of eth1.
host1 # [   12.455925] sshd[755]: Server listening on 0.0.0.0 port 22.
host1 # [   12.459092] sshd[755]: Server listening on :: port 22.
host1 # [   12.459948] systemd[1]: Started SSH Daemon.
host1 # [   12.484514] systemd-logind[531]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)
server # [   12.552890] mousedev: PS/2 mouse device common for all mice
server # [   12.689263] systemd-logind[537]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)
server # [   12.918809] sshd-keygen-start[555]: Generating public/private rsa key pair.
server # [   12.919980] sshd-keygen-start[555]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key
server # [   12.927087] sshd-keygen-start[555]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub
server # [   12.930739] sshd-keygen-start[555]: The key fingerprint is:
server # [   12.931664] sshd-keygen-start[555]: SHA256:79tU2eClqWwPY0cyitJFxeNu1duEtdx/IxN0p4JcxXE root@server
server # [   12.937422] sshd-keygen-start[555]: The key's randomart image is:
server # [   12.938313] sshd-keygen-start[555]: +---[RSA 4096]----+
server # [   12.939067] sshd-keygen-start[555]: |           ..oo.E|
server # [   12.939869] sshd-keygen-start[555]: |           .+..oo|
server # [   12.947411] sshd-keygen-start[555]: |         ..+..+==|
server # [   12.949705] sshd-keygen-start[555]: |         .o oo+X+|
server # [   12.950477] sshd-keygen-start[555]: |        S ..ooO.=|
server # [   12.951193] sshd-keygen-start[555]: |       . + ooO o+|
server # [   12.951944] sshd-keygen-start[555]: |      . o o.O + o|
server # [   12.958860] sshd-keygen-start[555]: |       . . = =   |
server # [   12.961561] sshd-keygen-start[555]: |          o.. .  |
server # [   12.962318] sshd-keygen-start[555]: +----[SHA256]-----+
server # [   12.980193] sshd-keygen-start[784]: Generating public/private ed25519 key pair.
server # [   12.981281] sshd-keygen-start[784]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key
server # [   12.982561] sshd-keygen-start[784]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub
server # [   12.983822] sshd-keygen-start[784]: The key fingerprint is:
server # [   12.995747] sshd-keygen-start[784]: SHA256:BImHXm2PkP8FLAwZf2aJcFXrCs+mzJkG/rbLm6vd3v0 root@server
server # [   13.000993] sshd-keygen-start[784]: The key's randomart image is:
server # [   13.001881] sshd-keygen-start[784]: +--[ED25519 256]--+
server # [   13.002604] sshd-keygen-start[784]: |     o=O.o...    |
server # [   13.003335] sshd-keygen-start[784]: |    o B==.o. .   |
server # [   13.009794] sshd-keygen-start[784]: |   . o +++=..    |
server # [   13.010573] sshd-keygen-start[784]: |    .  .o+...    |
server # [   13.011288] sshd-keygen-start[784]: |        S. ..    |
server # [   13.014423] sshd-keygen-start[784]: |      .  +..     |
server # [   13.015183] sshd-keygen-start[784]: |     . .  =      |
server # [   13.015936] sshd-keygen-start[784]: |      .=oB . .   |
server # [   13.019241] sshd-keygen-start[784]: |      .=/*o . ..E|
server # [   13.022531] sshd-keygen-start[784]: +----[SHA256]-----+
server # [   13.025996] systemd[1]: sshd-keygen.service: Deactivated successfully.
server # [   13.032129] systemd[1]: Finished SSH Host Keys Generation.
server # [   13.033017] systemd[1]: Starting SSH Daemon...
server # [   13.063321] sshd[786]: Server listening on 0.0.0.0 port 22.
server # [   13.065690] systemd[1]: Started SSH Daemon.
server # [   13.066393] sshd[786]: Server listening on :: port 22.
server # [   13.405740] dhcpcd[726]: eth0: soliciting a DHCP lease
server # [   13.412651] dhcpcd[726]: eth0: offered 10.0.2.15 from 10.0.2.2
server # [   13.420291] dhcpcd[726]: eth0: probing address 10.0.2.15/24
host1 # [   13.434834] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
host1 # [   13.436003] systemd[1]: Stopped Virtual Console Setup.
host1 # [   13.436840] systemd[1]: Stopping Virtual Console Setup...
host1 # [   13.445125] systemd[1]: Starting Virtual Console Setup...
host1 # [   13.456755] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
host1 # [   13.457888] systemd[1]: Stopped Virtual Console Setup.
host1 # [   13.467782] systemd[1]: Starting Virtual Console Setup...
server # [   13.511747] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3
host1 # [   13.576636] dhcpcd[699]: eth0: soliciting a DHCP lease
host1 # [   13.584647] dhcpcd[699]: eth0: offered 10.0.2.15 from 10.0.2.2
host1 # [   13.592251] dhcpcd[699]: eth0: probing address 10.0.2.15/24
host1 # [   13.612866] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
host1 # [   13.643993] systemd-logind[531]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)
host1 # [   13.696104] systemd-vconsole-setup[762]: Configuration of first virtual console was skipped, ignoring remaining ones.
host1 # [   13.699612] systemd[1]: Finished Virtual Console Setup.
server # [   13.805634] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
server # [   13.832241] systemd[1]: Starting Virtual Console Setup...
server # [   13.846542] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
server # [   13.854350] systemd[1]: Stopped Virtual Console Setup.
server # [   13.858244] systemd[1]: Starting Virtual Console Setup...
server # [   13.919816] systemd-logind[537]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)
server # [   13.977274] systemd-vconsole-setup[802]: Configuration of first virtual console was skipped, ignoring remaining ones.
server # [   13.980061] systemd[1]: Finished Virtual Console Setup.
host1 # [   14.043749] dhcpcd[699]: eth0: soliciting an IPv6 router
host1 # [   14.046840] dhcpcd[699]: eth0: Router Advertisement from fe80::2
host1 # [   14.048563] dhcpcd[699]: eth0: adding address fec0::5054:ff:fe12:3456/64
host1 # [   14.050251] dhcpcd[699]: eth0: adding route to fec0::/64
host1 # [   14.051566] dhcpcd[699]: eth0: adding default route via fe80::2
server # [   14.253302] dhcpcd[726]: eth0: soliciting an IPv6 router
server # [   14.255290] dhcpcd[726]: eth0: Router Advertisement from fe80::2
server # [   14.256866] dhcpcd[726]: eth0: adding address fec0::5054:ff:fe12:3456/64
server # [   14.258372] dhcpcd[726]: eth0: adding route to fec0::/64
server # [   14.259586] dhcpcd[726]: eth0: adding default route via fe80::2
host1 # [   17.756862] dhcpcd[699]: eth0: leased 10.0.2.15 for 86400 seconds
host1 # [   17.759510] dhcpcd[699]: eth0: adding route to 10.0.2.0/24
host1 # [   17.762305] dhcpcd[699]: eth0: adding default route via 10.0.2.2
host1 # [   17.867042] systemd[1]: Started DHCP Client.
host1 # [   17.869144] systemd[1]: Reached target Network is Online.
host1 # [   17.873030] systemd[1]: Starting fetch-ca-root.service...
host1 # [   17.917249] fetch-ca-root-start[841]: curl: (7) Failed to connect to server:8443 after 5 ms: Could not connect to server
server # [   18.567405] dhcpcd[726]: eth0: leased 10.0.2.15 for 86400 seconds
server # [   18.567710] dhcpcd[726]: eth0: adding route to 10.0.2.0/24
server # [   18.567777] dhcpcd[726]: eth0: adding default route via 10.0.2.2
server # [   18.712637] systemd[1]: Started DHCP Client.
server # [   18.716164] systemd[1]: Reached target Network is Online.
server # [   18.719214] systemd[1]: Starting step-ca service...
host1 # [   18.920394] fetch-ca-root-start[841]: curl: (7) Failed to connect to server:8443 after 1 ms: Could not connect to server
server # [   18.995799] step-ca[912]: badger 2026/09/30 09:11:04 INFO: All 0 tables opened in 0s
server # [   19.011100] step-ca[912]: 2026/09/30 09:11:04 Building new tls configuration using step-ca x509 Signer Interface
server # [   19.025381] step-ca[912]: 2026/09/30 09:11:04 Starting Smallstep CA/0.30.2 (linux/arm64)
server # [   19.026548] step-ca[912]: 2026/09/30 09:11:04 Documentation: https://u.step.sm/docs/ca
server # [   19.027656] step-ca[912]: 2026/09/30 09:11:04 Community Discord: https://u.step.sm/discord
server # [   19.029004] step-ca[912]: 2026/09/30 09:11:04 Config file: /etc/smallstep/ca.json
server # [   19.035560] step-ca[912]: 2026/09/30 09:11:04 The primary server URL is https://server:8443
server # [   19.038939] step-ca[912]: 2026/09/30 09:11:04 Root certificates are available at https://server:8443/roots.pem
server # [   19.044138] step-ca[912]: 2026/09/30 09:11:04 X.509 Root Fingerprint: 1dcf3ae90028041510f5b9431ef864bc82c3f5b729b5ca2975903db24777e75d
server # [   19.045794] step-ca[912]: 2026/09/30 09:11:04 Serving HTTPS on 0.0.0.0:8443 ...
server # [   19.046772] systemd[1]: Started step-ca service.
server # [   19.047508] systemd[1]: Reached target Multi-User System.
server # [   19.051314] systemd[1]: Starting Order (and renew) ACME certificate for server...
server # [   19.097563] acme-order-renew-server-start[919]: Waiting to acquire lock in /run/acme/
server # [   19.103808] acme-order-renew-server-start[919]: + set -euo pipefail
server # [   19.105127] acme-order-renew-server-start[919]: + exec
server # [   19.106731] acme-order-renew-server-start[919]: + /nix/store/fxkh6pl0y4nfbhdqdnhfkvsq7pbxq655-flock-0.4.0/bin/flock 11
server # [   19.110207] acme-order-renew-server-start[927]: ++ find accounts -path '*/keys/*.key' -print -quit
server # [   19.116796] acme-order-renew-server-start[919]: + '[' -n '' ']'
server # [   19.117680] acme-order-renew-server-start[919]: + exec
server # [   19.118406] acme-order-renew-server-start[919]: + '[' -e certificates/server.key ']'
server # [   19.119452] acme-order-renew-server-start[919]: + lego run --accept-tos --path . --email root@example.org --http --http.address :80 --server https://server:8443/acme/acme/directory --key-type ec256 -d server --no-random-sleep --renew-force --ari-disable --force-cert-domains
server # [   19.322799] acme-order-renew-server-start[928]: 2026-09-30T09:11:04.841694700Z INFO  Private key saved. filepath=accounts/server_8443/root@example.org/root@example.org.key
server # [   19.337304] acme-order-renew-server-start[928]: 2026-09-30T09:11:04.857425420Z INFO  Registering the account. email=root@example.org
server # [   19.349334] acme-order-renew-server-start[928]: 2026-09-30T09:11:04.868855080Z WARN  !!!! HEADS UP !!!!
server # [   19.350785] acme-order-renew-server-start[928]: Your account credentials have been saved in your
server # [   19.352296] acme-order-renew-server-start[928]: configuration directory at "accounts".
server # [   19.353743] acme-order-renew-server-start[928]: You should make a secure backup of this folder now. This
server # [   19.355274] acme-order-renew-server-start[928]: configuration directory will also contain private keys
server # [   19.356775] acme-order-renew-server-start[928]: generated by lego and certificates obtained from the ACME
server # [   19.358127] acme-order-renew-server-start[928]: server. Making regular backups of this folder is ideal.
server # [   19.359738] acme-order-renew-server-start[928]: 2026-09-30T09:11:04.869049860Z INFO  Obtaining bundled SAN certificate. domains=server
server # [   19.431070] acme-order-renew-server-start[928]: 2026-09-30T09:11:04.950340820Z INFO  Use solver. domain=server type=http-01
server # [   19.435166] acme-order-renew-server-start[928]: 2026-09-30T09:11:04.950405780Z INFO  http01: Trying to solve HTTP-01. domain=server
server # [   19.447189] acme-order-renew-server-start[928]: 2026-09-30T09:11:04.966295740Z INFO  The server validated our request. domain=server
server # [   19.450810] acme-order-renew-server-start[928]: 2026-09-30T09:11:04.966448680Z INFO  Validations succeeded; requesting certificates. domains=server
server # [   19.473296] acme-order-renew-server-start[928]: 2026-09-30T09:11:04.989291640Z INFO  Server responded with a certificate. domains=server
server # [   19.475878] acme-order-renew-server-start[928]: 2026-09-30T09:11:04.989439320Z INFO  Writing file. filepath=certificates/server.crt
server # [   19.478634] acme-order-renew-server-start[928]: 2026-09-30T09:11:04.989657160Z INFO  Writing file. filepath=certificates/server.issuer.crt
server # [   19.481542] acme-order-renew-server-start[928]: 2026-09-30T09:11:04.989749300Z INFO  Writing file. filepath=certificates/server.key
server # [   19.485786] acme-order-renew-server-start[928]: 2026-09-30T09:11:04.989931080Z INFO  Writing file. filepath=certificates/server.json
server # [   19.488427] acme-order-renew-server-start[919]: + touch out/acme-success
server # [   19.491631] acme-order-renew-server-start[919]: + cmp -s certificates/server.crt out/fullchain.pem
server # [   19.496801] acme-order-renew-server-start[919]: + touch out/renewed
server # [   19.501022] acme-order-renew-server-start[919]: + echo Installing new certificate
server # [   19.502264] acme-order-renew-server-start[919]: Installing new certificate
server # [   19.503314] acme-order-renew-server-start[919]: + cp -vp certificates/server.crt out/fullchain.pem
server # [   19.508451] acme-order-renew-server-start[937]: 'certificates/server.crt' -> 'out/fullchain.pem'
server # [   19.510328] acme-order-renew-server-start[919]: + cp -vp certificates/server.key out/key.pem
server # [   19.514828] acme-order-renew-server-start[938]: 'certificates/server.key' -> 'out/key.pem'
server # [   19.517076] acme-order-renew-server-start[919]: + cp -vp certificates/server.issuer.crt out/chain.pem
server # [   19.521126] acme-order-renew-server-start[939]: 'certificates/server.issuer.crt' -> 'out/chain.pem'
server # [   19.522741] acme-order-renew-server-start[919]: + ln -sf fullchain.pem out/cert.pem
server # [   19.526937] acme-order-renew-server-start[919]: + cat out/key.pem out/fullchain.pem
server # [   19.531251] acme-order-renew-server-start[919]: + for fixpath in out certificates
server # [   19.532584] acme-order-renew-server-start[919]: + '[' -d out ']'
server # [   19.533421] acme-order-renew-server-start[919]: + chmod -R u=rwX,g=rX,o= out
server # [   19.537629] acme-order-renew-server-start[919]: + chown -R acme:nix-grpc-daemon out
server # [   19.543640] acme-order-renew-server-start[919]: + for fixpath in out certificates
server # [   19.545010] acme-order-renew-server-start[919]: + '[' -d certificates ']'
server # [   19.545957] acme-order-renew-server-start[919]: + chmod -R u=rwX,g=rX,o= certificates
server # [   19.549836] acme-order-renew-server-start[919]: + chown -R acme:nix-grpc-daemon certificates
server # [   19.555284] acme-order-renew-server-start[919]: + chmod -R u=rwX,g=,o= accounts/.
server # [   19.597975] systemd[1]: acme-order-renew-server.service: Deactivated successfully.
server # [   19.600095] systemd[1]: Finished Order (and renew) ACME certificate for server.
server # [   19.613178] systemd[1]: Startup finished in 1.055s (kernel) + 4.816s (initrd) + 13.739s (userspace) = 19.612s.
server # [   19.614670] systemd[1]: acme-order-renew-server.service: Consumed 227ms CPU time over 561ms wall clock time, 48.2M memory peak, 4K written to disk, 10.2K incoming IP traffic, 8.1K outgoing IP traffic.
server: (finished: waiting for unit step-ca.service, in 20.02 seconds)
server: waiting for unit nix-grpc-daemon.socket
server: (finished: waiting for unit nix-grpc-daemon.socket, in 0.04 seconds)
server: waiting for TCP port 50051 on localhost
server # Connection to localhost (127.0.0.1) 50051 port [tcp/*] succeeded!
server # [   19.800651] systemd[1]: Starting Order (and renew) ACME certificate for server...
server: (finished: waiting for TCP port 50051 on localhost, in 0.05 seconds)

running 4 tests
host1 # [   19.809320] systemd[1]: Created slice Slice /system/sshd-vsock.
host1 # [   19.813532] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.
host1 # [   19.817534] systemd[1]: Started SSH per-connection Daemon (vsock:2:0).
server # [   19.859219] acme-order-renew-server-start[969]: Waiting to acquire lock in /run/acme/
server # [   19.865783] acme-order-renew-server-start[969]: + set -euo pipefail
server # [   19.866759] acme-order-renew-server-start[969]: + exec
server # [   19.867560] acme-order-renew-server-start[969]: + /nix/store/fxkh6pl0y4nfbhdqdnhfkvsq7pbxq655-flock-0.4.0/bin/flock 11
server # [   19.871385] acme-order-renew-server-start[977]: ++ find accounts -path '*/keys/*.key' -print -quit
server # [   19.876647] acme-order-renew-server-start[969]: + '[' -n '' ']'
server # [   19.877562] acme-order-renew-server-start[969]: + exec
server # [   19.878292] acme-order-renew-server-start[969]: + '[' -e certificates/server.key ']'
server # [   19.879351] acme-order-renew-server-start[969]: + '[' -e certificates/server.crt ']'
server # [   19.881377] acme-order-renew-server-start[978]: ++ find accounts -name root@example.org.key
server # [   19.886292] acme-order-renew-server-start[969]: + '[' -n accounts/server_8443/root@example.org/root@example.org.key ']'
server # [   19.887828] acme-order-renew-server-start[969]: + lego run --accept-tos --path . --email root@example.org --http --http.address :80 --server https://server:8443/acme/acme/directory --key-type ec256 -d server --no-random-sleep --force-cert-domains
server # [   19.917927] acme-order-renew-server-start[979]: 2026-09-30T09:11:05.437107080Z INFO  Renewing certificate cert-name=server
server # [   19.924824] acme-order-renew-server-start[979]: 2026-09-30T09:11:05.444908940Z WARN  The server does not advertise a renewal info endpoint. cert-name=server error="renewalInfo[get/post]: server does not advertise a renewal info endpoint"
server # [   19.927689] acme-order-renew-server-start[979]: 2026-09-30T09:11:05.444987600Z INFO  Skip renewal: The certificate expires at 2026-10-01T09:11:04Z, the renewal can be performed in 11h59m28s555025340ns. cert-name=server
server # [   19.934178] acme-order-renew-server-start[969]: + touch out/acme-success
server # [   19.937670] acme-order-renew-server-start[969]: + cmp -s certificates/server.crt out/fullchain.pem
server # [   19.941180] acme-order-renew-server-start[969]: + for fixpath in out certificates
server # [   19.942270] acme-order-renew-server-start[969]: + '[' -d out ']'
server # [   19.943111] acme-order-renew-server-start[969]: + chmod -R u=rwX,g=rX,o= out
host1 # [   19.921281] sshd-session[845]: pam_unix(sshd:auth): user [root] has blank password; authenticated without it
server # [   19.947749] acme-order-renew-server-start[969]: + chown -R acme:nix-grpc-daemon out
server # [   19.954567] acme-order-renew-server-start[969]: + for fixpath in out certificates
server # [   19.955763] acme-order-renew-server-start[969]: + '[' -d certificates ']'
server # [   19.956981] acme-order-renew-server-start[969]: + chmod -R u=rwX,g=rX,o= certificates
server # [   19.962192] acme-order-renew-server-start[969]: + chown -R acme:nix-grpc-daemon certificates
host1 # [   19.940728] sshd-session[845]: Accepted none for root from UNKNOWN port 65535 ssh2
server # [   19.967868] acme-order-renew-server-start[969]: + chmod -R u=rwX,g=,o= accounts/.
host1 # [   19.949208] systemd[1]: Finished fetch-ca-root.service.
host1 # [   19.952548] systemd[1]: Reached target Multi-User System.
host1 # [   19.959633] systemd[1]: Starting Order (and renew) ACME certificate for host1...
host1 # [   19.964106] sshd-session[845]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)
server # [   20.005897] systemd[1]: acme-order-renew-server.service: Deactivated successfully.
server # [   20.008551] systemd[1]: Finished Order (and renew) ACME certificate for server.
server # [   20.009650] systemd[1]: acme-order-renew-server.service: Consumed 122ms CPU time over 207ms wall clock time, 8M memory peak, 3.3K incoming IP traffic, 2.2K outgoing IP traffic.
server # [   20.017940] systemd[1]: Starting Nix worker-protocol over gRPC...
host1 # [   19.995121] systemd[1]: Created slice Slice /user/0.
host1 # [   20.003604] systemd[1]: Starting User Runtime Directory /run/user/0...
host1 # [   20.038823] systemd-logind[531]: New session '1' of user 'root' with class 'user' and type 'tty'.
host1 # [   20.058067] acme-order-renew-host1-start[852]: Waiting to acquire lock in /run/acme/
host1 # [   20.070780] systemd[1]: Finished User Runtime Directory /run/user/0.
host1 # [   20.076576] systemd[1]: Starting User Manager for UID 0...
host1 # [   20.082359] acme-order-renew-host1-start[852]: + set -euo pipefail
host1 # [   20.083308] acme-order-renew-host1-start[852]: + exec
host1 # [   20.085488] acme-order-renew-host1-start[852]: + /nix/store/fxkh6pl0y4nfbhdqdnhfkvsq7pbxq655-flock-0.4.0/bin/flock 11
host1 # [   20.088421] acme-order-renew-host1-start[864]: ++ find accounts -path '*/keys/*.key' -print -quit
host1 # [   20.096861] acme-order-renew-host1-start[852]: + '[' -n '' ']'
host1 # [   20.098941] acme-order-renew-host1-start[852]: + exec
host1 # [   20.099707] acme-order-renew-host1-start[852]: + '[' -e certificates/host1.key ']'
host1 # [   20.101836] acme-order-renew-host1-start[852]: + lego run --accept-tos --path . --email root@example.org --http --http.address :80 --server https://server:8443/acme/acme/directory --key-type ec256 -d host1 --no-random-sleep --renew-force --ari-disable --force-cert-domains
server # [   20.135262] nix-grpc-daemon[999]: ts=2026-09-30T09:11:05Z level=info event=roles builder=1 scheduler=1 scheduler_addr="" advertise=0.0.0.0:50051 niks3="" max_jobs=1
host1 # [   20.119132] (systemd)[861]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0)
host1 # [   20.124813] systemd-logind[531]: New session '2' of user 'root' with class 'manager-early' and type 'unspecified'.
server # [   20.149247] nix-grpc-daemon[999]: WARNING: All log messages before absl::InitializeLog() is called are written to STDERR
server # [   20.152251] nix-grpc-daemon[999]: E0000 00:00:1790759465.668964     999 tls_security_connector.cc:771] TlsServerCertificateWatcher getting identity_cert_error: UNKNOWN:Unable to get latest identity certificates.
server # [   20.157745] nix-grpc-daemon[999]: E0000 00:00:1790759465.677615     999 tls_security_connector.cc:767] TlsServerCertificateWatcher getting root_cert_error: UNKNOWN:Unable to get latest root certificates.
server # [   20.163576] nix-grpc-daemon[999]: ts=2026-09-30T09:11:05Z level=info event=startup listen=systemd proxy_socket=/nix/var/nix/daemon-socket/socket
server # [   20.165498] nix-grpc-daemon[999]: ts=2026-09-30T09:11:05Z level=info event=worker_hello addr=0.0.0.0:50051 systems=aarch64-linux max_jobs=1 running=0
server # [   20.167250] nix-grpc-daemon[999]: ts=2026-09-30T09:11:05Z level=info event=unhealthy reason=min_free
server # [   20.168553] systemd[1]: Started Nix worker-protocol over gRPC.
host1 # [   20.243778] systemd[861]: Failed to adjust io pressure threshold, ignoring: Device or resource busy
host1 # [   20.380644] acme-order-renew-host1-start[865]: 2026-09-30T09:11:05.927376420Z INFO  Private key saved. filepath=accounts/server_8443/root@example.org/root@example.org.key
host1 # [   20.394005] acme-order-renew-host1-start[865]: 2026-09-30T09:11:05.942423820Z INFO  Registering the account. email=root@example.org
host1 # [   20.404333] acme-order-renew-host1-start[865]: 2026-09-30T09:11:05.952555120Z WARN  !!!! HEADS UP !!!!
host1 # [   20.406837] acme-order-renew-host1-start[865]: Your account credentials have been saved in your
host1 # [   20.409034] acme-order-renew-host1-start[865]: configuration directory at "accounts".
host1 # [   20.410113] acme-order-renew-host1-start[865]: You should make a secure backup of this folder now. This
host1 # [   20.411359] acme-order-renew-host1-start[865]: configuration directory will also contain private keys
host1 # [   20.413215] acme-order-renew-host1-start[865]: generated by lego and certificates obtained from the ACME
host1 # [   20.414483] acme-order-renew-host1-start[865]: server. Making regular backups of this folder is ideal.
host1 # [   20.415729] acme-order-renew-host1-start[865]: 2026-09-30T09:11:05.954768160Z INFO  Obtaining bundled SAN certificate. domains=host1
host1 # [   20.473652] systemd[861]: Queued start job for default target Main User Target.
host1 # [   20.483336] acme-order-renew-host1-start[865]: 2026-09-30T09:11:06.028331200Z INFO  Use solver. domain=host1 type=http-01
host1 # [   20.489126] acme-order-renew-host1-start[865]: 2026-09-30T09:11:06.028377720Z INFO  http01: Trying to solve HTTP-01. domain=host1
host1 # [   20.490778] systemd[861]: Failed to adjust io pressure threshold, ignoring: Device or resource busy
host1 # [   20.499620] systemd[861]: Created slice User Application Slice.
host1 # [   20.502209] systemd[861]: Started Daily Cleanup of User's Temporary Directories.
host1 # [   20.508488] acme-order-renew-host1-start[865]: 2026-09-30T09:11:06.050062120Z INFO  The server validated our request. domain=host1
host1 # [   20.511192] systemd[861]: Reached target Paths.
host1 # [   20.513995] acme-order-renew-host1-start[865]: 2026-09-30T09:11:06.058950040Z INFO  Validations succeeded; requesting certificates. domains=host1
host1 # [   20.519837] systemd[861]: Reached target Timers.
host1 # [   20.527549] systemd[861]: Starting D-Bus User Message Bus Socket...
host1 # [   20.531878] systemd[861]: Starting Create User Files and Directories...
host1 # [   20.539493] acme-order-renew-host1-start[865]: 2026-09-30T09:11:06.087967260Z INFO  Server responded with a certificate. domains=host1
host1 # [   20.547606] acme-order-renew-host1-start[865]: 2026-09-30T09:11:06.091485760Z INFO  Writing file. filepath=certificates/host1.crt
host1 # [   20.549554] acme-order-renew-host1-start[865]: 2026-09-30T09:11:06.091647240Z INFO  Writing file. filepath=certificates/host1.issuer.crt
host1 # [   20.551240] acme-order-renew-host1-start[865]: 2026-09-30T09:11:06.091710200Z INFO  Writing file. filepath=certificates/host1.key
host1 # [   20.553040] acme-order-renew-host1-start[865]: 2026-09-30T09:11:06.091824160Z INFO  Writing file. filepath=certificates/host1.json
host1 # [   20.555394] acme-order-renew-host1-start[852]: + touch out/acme-success
host1 # [   20.563397] systemd[861]: Finished Create User Files and Directories.
host1 # [   20.570736] acme-order-renew-host1-start[852]: + cmp -s certificates/host1.crt out/fullchain.pem
host1 # [   20.577587] acme-order-renew-host1-start[852]: + touch out/renewed
host1 # [   20.583921] acme-order-renew-host1-start[852]: + echo Installing new certificate
host1 # [   20.590499] acme-order-renew-host1-start[852]: Installing new certificate
host1 # [   20.591506] acme-order-renew-host1-start[852]: + cp -vp certificates/host1.crt out/fullchain.pem
host1 # [   20.593018] systemd[861]: Listening on D-Bus User Message Bus Socket.
host1 # [   20.593954] systemd[861]: Reached target Sockets.
host1 # [   20.594663] systemd[861]: Reached target Basic System.
host1 # [   20.595391] systemd[861]: Run user-specific NixOS activation skipped, unmet condition check ConditionUser=!@system
host1 # [   20.601905] systemd[861]: Reached target Main User Target.
host1 # [   20.602749] systemd[861]: Startup finished in 430ms.
host1 # [   20.603501] systemd[1]: Started User Manager for UID 0.
host1 # [   20.611644] systemd[1]: Started Session 1 of User root.
host1 # [   20.617351] acme-order-renew-host1-start[884]: 'certificates/host1.crt' -> 'out/fullchain.pem'
host1 # [   20.618549] acme-order-renew-host1-start[852]: + cp -vp certificates/host1.key out/key.pem
host1 # [   20.620960] acme-order-renew-host1-start[887]: 'certificates/host1.key' -> 'out/key.pem'
host1 # [   20.623028] acme-order-renew-host1-start[852]: + cp -vp certificates/host1.issuer.crt out/chain.pem
host1 # [   20.630256] acme-order-renew-host1-start[888]: 'certificates/host1.issuer.crt' -> 'out/chain.pem'
host1 # [   20.631976] acme-order-renew-host1-start[852]: + ln -sf fullchain.pem out/cert.pem
host1 # [   20.634557] sshd-session[885]: Received disconnect from UNKNOWN port 65535:11: disconnected by user
host1 # [   20.635831] sshd-session[885]: Disconnected from user root UNKNOWN port 65535
host1 # [   20.638698] sshd-session[845]: pam_unix(sshd:session): session closed for user root
host1 # [   20.645861] systemd[1]: sshd-vsock@0-1-3:22-2:0.service: Deactivated successfully.
host1 # [   20.649228] systemd[1]: session-1.scope: Deactivated successfully.
host1 # [   20.654519] systemd-logind[531]: Session 1 logged out. Waiting for processes to exit.
host1 # [   20.655653] acme-order-renew-host1-start[852]: + cat out/key.pem out/fullchain.pem
host1 # [   20.658297] systemd-logind[531]: Removed session 1.
host1 # [   20.660985] acme-order-renew-host1-start[852]: + for fixpath in out certificates
host1 # [   20.662053] acme-order-renew-host1-start[852]: + '[' -d out ']'
host1 # [   20.662865] acme-order-renew-host1-start[852]: + chmod -R u=rwX,g=rX,o= out
host1 # [   20.666898] acme-order-renew-host1-start[852]: + chown -R acme:acme out
host1 # [   20.672774] acme-order-renew-host1-start[852]: + for fixpath in out certificates
host1 # [   20.673832] acme-order-renew-host1-start[852]: + '[' -d certificates ']'
host1 # [   20.674730] acme-order-renew-host1-start[852]: + chmod -R u=rwX,g=rX,o= certificates
host1 # [   20.678500] acme-order-renew-host1-start[852]: + chown -R acme:acme certificates
host1 # [   20.683695] acme-order-renew-host1-start[852]: + chmod -R u=rwX,g=,o= accounts/.
server # [   20.733445] systemd[1]: Created slice Slice /system/sshd-vsock.
host1 # [   20.711498] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.
server # [   20.736360] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.
host1 # [   20.717152] systemd[1]: Started SSH per-connection Daemon (vsock:2:1).
server # [   20.742503] systemd[1]: Started SSH per-connection Daemon (vsock:2:0).
host1 # [   20.764193] systemd[1]: acme-order-renew-host1.service: Deactivated successfully.
host1 # [   20.770828] systemd[1]: Finished Order (and renew) ACME certificate for host1.
host1 # [   20.778227] systemd[1]: Startup finished in 1.023s (kernel) + 3.980s (initrd) + 15.764s (userspace) = 20.768s.
host1 # [   20.779964] systemd[1]: acme-order-renew-host1.service: Consumed 232ms CPU time over 806ms wall clock time, 49.7M memory peak, 4K written to disk, 10.4K incoming IP traffic, 8.2K outgoing IP traffic.
server # [   20.848472] sshd-session[1012]: pam_unix(sshd:auth): user [root] has blank password; authenticated without it
server # [   20.856804] sshd-session[1012]: Accepted none for root from UNKNOWN port 65535 ssh2
host1 # [   20.837346] sshd-session[904]: pam_unix(sshd:auth): user [root] has blank password; authenticated without it
server # [   20.864859] sshd-session[1012]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)
host1 # [   20.844108] sshd-session[904]: Accepted none for root from UNKNOWN port 65535 ssh2
host1 # [   20.850519] sshd-session[904]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)
host1 # [   20.859378] systemd-logind[531]: New session '3' of user 'root' with class 'user' and type 'tty'.
server # [   20.885484] systemd[1]: Created slice Slice /user/0.
host1 # [   20.865381] systemd[1]: Started Session 3 of User root.
server # [   20.889096] systemd[1]: Starting User Runtime Directory /run/user/0...
server # [   20.899681] systemd-logind[537]: New session '1' of user 'root' with class 'user' and type 'tty'.
server # [   20.928237] systemd[1]: Finished User Runtime Directory /run/user/0.
server # [   20.934326] systemd[1]: Starting User Manager for UID 0...
host1 # [   20.912713] sshd-session[915]: Received disconnect from UNKNOWN port 65535:11: disconnected by user
host1 # [   20.915115] sshd-session[915]: Disconnected from user root UNKNOWN port 65535
host1 # [   20.916578] sshd-session[904]: pam_unix(sshd:session): session closed for user root
host1 # [   20.920624] systemd[1]: sshd-vsock@1-2-3:22-2:1.service: Deactivated successfully.
host1 # [   20.925568] systemd[1]: session-3.scope: Deactivated successfully.
host1 # [   20.929524] systemd-logind[531]: Session 3 logged out. Waiting for processes to exit.
host1 # [   20.931563] systemd-logind[531]: Removed session 3.
server # [   20.961394] (systemd)[1020]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0)
server # [   20.967314] systemd-logind[537]: New session '2' of user 'root' with class 'manager-early' and type 'unspecified'.
server # [   21.058713] systemd[1020]: Failed to adjust io pressure threshold, ignoring: Device or resource busy
server # [   21.187041] systemd[1020]: Queued start job for default target Main User Target.
server # [   21.196917] systemd[1020]: Failed to adjust io pressure threshold, ignoring: Device or resource busy
server # [   21.200853] systemd[1020]: Created slice User Application Slice.
server # [   21.201832] systemd[1020]: Started Daily Cleanup of User's Temporary Directories.
server # [   21.202885] systemd[1020]: Reached target Paths.
server # [   21.203578] systemd[1020]: Reached target Timers.
server # [   21.206820] systemd[1020]: Starting D-Bus User Message Bus Socket...
server # [   21.207815] systemd[1020]: Starting Create User Files and Directories...
server # [   21.250404] systemd[1020]: Finished Create User Files and Directories.
server # [   21.265488] systemd[1020]: Listening on D-Bus User Message Bus Socket.
server # [   21.266548] systemd[1020]: Reached target Sockets.
server # [   21.267266] systemd[1020]: Reached target Basic System.
server # [   21.271084] systemd[1020]: Run user-specific NixOS activation skipped, unmet condition check ConditionUser=!@system
server # [   21.272725] systemd[1020]: Reached target Main User Target.
server # [   21.273545] systemd[1020]: Startup finished in 283ms.
server # [   21.274286] systemd[1]: Started User Manager for UID 0.
server # [   21.277123] systemd[1]: Started Session 1 of User root.
server # [   21.291744] sshd-session[1033]: Received disconnect from UNKNOWN port 65535:11: disconnected by user
server # [   21.294165] sshd-session[1033]: Disconnected from user root UNKNOWN port 65535
server # [   21.295212] sshd-session[1012]: pam_unix(sshd:session): session closed for user root
server # [   21.299206] systemd[1]: sshd-vsock@0-1-4:22-2:0.service: Deactivated successfully.
server # [   21.303520] systemd[1]: session-1.scope: Deactivated successfully.
server # [   21.307142] systemd-logind[537]: Session 1 logged out. Waiting for processes to exit.
server # [   21.309376] systemd-logind[537]: Removed session 1.
server # [   21.329836] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.
server # [   21.334590] systemd[1]: Started SSH per-connection Daemon (vsock:2:1).
host1 # [   21.314467] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.
host1 # [   21.324429] systemd[1]: Started SSH per-connection Daemon (vsock:2:2).
host1 # [   21.340120] systemd[1]: Started SSH per-connection Daemon (vsock:2:3).
host1 # [   21.341843] systemd[1]: Started SSH per-connection Daemon (vsock:2:4).
server # [   21.402745] sshd-session[1038]: pam_unix(sshd:auth): user [root] has blank password; authenticated without it
server # [   21.408249] sshd-session[1038]: Accepted none for root from UNKNOWN port 65535 ssh2
server # [   21.416203] sshd-session[1038]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)
server # [   21.423377] systemd-logind[537]: New session '3' of user 'root' with class 'user' and type 'tty'.
server # [   21.428456] systemd[1]: Started Session 3 of User root.
host1 # [   21.518949] sshd-session[923]: pam_unix(sshd:auth): user [root] has blank password; authenticated without it
host1 # [   21.526051] sshd-session[922]: pam_unix(sshd:auth): user [root] has blank password; authenticated without it
host1 # [   21.536449] sshd-session[924]: pam_unix(sshd:auth): user [root] has blank password; authenticated without it
host1 # [   21.538208] sshd-session[922]: Accepted none for root from UNKNOWN port 65535 ssh2
host1 # [   21.539305] sshd-session[923]: Accepted none for root from UNKNOWN port 65535 ssh2
host1 # [   21.550309] sshd-session[924]: Accepted none for root from UNKNOWN port 65535 ssh2
host1 # [   21.558369] sshd-session[922]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)
host1 # [   21.559743] sshd-session[923]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)
host1 # [   21.571688] sshd-session[924]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)
host1 # [   21.578000] systemd-logind[531]: New session '4' of user 'root' with class 'user' and type 'tty'.
host1 # [   21.584514] systemd[1]: Started Session 4 of User root.
host1 # [   21.590176] systemd-logind[531]: New session '5' of user 'root' with class 'user' and type 'tty'.
host1 # [   21.595550] systemd[1]: Started Session 5 of User root.
host1 # [   21.601748] systemd-logind[531]: New session '6' of user 'root' with class 'user' and type 'tty'.
host1 # [   21.603021] systemd[1]: Started Session 6 of User root.
host1 # [   21.644070] sshd-session[942]: Received disconnect from UNKNOWN port 65535:11: disconnected by user
host1 # [   21.646827] sshd-session[942]: Disconnected from user root UNKNOWN port 65535
host1 # [   21.650051] sshd-session[923]: pam_unix(sshd:session): session closed for user root
host1 # [   21.661960] systemd[1]: sshd-vsock@3-4-3:22-2:3.service: Deactivated successfully.
host1 # [   21.668165] systemd[1]: session-5.scope: Deactivated successfully.
host1 # [   21.673344] systemd-logind[531]: Session 5 logged out. Waiting for processes to exit.
host1 # [   21.678539] systemd-logind[531]: Removed session 5.
host1 # [   21.717558] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.
host1 # [   21.722740] systemd[1]: Started SSH per-connection Daemon (vsock:2:5).
host1 # [   21.773375] sshd-session[940]: Received disconnect from UNKNOWN port 65535:11: disconnected by user
host1 # [   21.774707] sshd-session[940]: Disconnected from user root UNKNOWN port 65535
host1 # [   21.789348] sshd-session[922]: pam_unix(sshd:session): session closed for user root
host1 # [   21.790578] systemd[1]: sshd-vsock@2-3-3:22-2:2.service: Deactivated successfully.
host1 # [   21.791733] sshd-session[943]: Received disconnect from UNKNOWN port 65535:11: disconnected by user
host1 # [   21.798971] sshd-session[943]: Disconnected from user root UNKNOWN port 65535
host1 # [   21.808465] systemd[1]: sshd-vsock@4-5-3:22-2:4.service: Deactivated successfully.
host1 # [   21.809632] sshd-session[924]: pam_unix(sshd:session): session closed for user root
host1 # [   21.810701] systemd[1]: session-6.scope: Deactivated successfully.
host1 # [   21.811557] systemd[1]: session-4.scope: Deactivated successfully.
host1 # [   21.813564] systemd-logind[531]: Session 4 logged out. Waiting for processes to exit.
host1 # [   21.818670] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.
host1 # [   21.825106] systemd-logind[531]: Removed session 4.
host1 # [   21.825924] systemd[1]: Started SSH per-connection Daemon (vsock:2:6).
host1 # [   21.826862] systemd-logind[531]: Session 6 logged out. Waiting for processes to exit.
host1 # [   21.842491] systemd-logind[531]: Removed session 6.
host1 # [   21.927165] sshd-session[955]: pam_unix(sshd:auth): user [root] has blank password; authenticated without it
host1 # [   21.937931] sshd-session[955]: Accepted none for root from UNKNOWN port 65535 ssh2
host1 # [   21.946356] sshd-session[955]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)
host1 # [   21.959711] systemd-logind[531]: New session '7' of user 'root' with class 'user' and type 'tty'.
host1 # [   21.965451] systemd[1]: Started Session 7 of User root.
host1 # [   21.973921] sshd-session[960]: pam_unix(sshd:auth): user [root] has blank password; authenticated without it
host1 # [   21.987028] sshd-session[960]: Accepted none for root from UNKNOWN port 65535 ssh2
host1 # [   21.999996] sshd-session[960]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)
host1 # [   22.013801] systemd-logind[531]: New session '8' of user 'root' with class 'user' and type 'tty'.
host1 # [   22.020819] systemd[1]: Started Session 8 of User root.
host1 # [   22.100382] sshd-session[975]: Received disconnect from UNKNOWN port 65535:11: disconnected by user
host1 # [   22.102666] sshd-session[975]: Disconnected from user root UNKNOWN port 65535
host1 # [   22.104436] sshd-session[960]: pam_unix(sshd:session): session closed for user root
host1 # [   22.111062] systemd[1]: sshd-vsock@6-7-3:22-2:6.service: Deactivated successfully.
host1 # [   22.115976] systemd[1]: session-8.scope: Deactivated successfully.
host1 # [   22.122177] systemd-logind[531]: Session 8 logged out. Waiting for processes to exit.
host1 # [   22.126135] systemd-logind[531]: Removed session 8.
test acme::host1_obtains_a_certificate_via_acme ... ok
server # [   22.237034] systemd[1]: Started Nix Daemon.
server # [   22.285250] sshd-session[1044]: Received disconnect from UNKNOWN port 65535:11: disconnected by user
server # [   22.286635] sshd-session[1044]: Disconnected from user root UNKNOWN port 65535
server # [   22.288314] sshd-session[1038]: pam_unix(sshd:session): session closed for user root
server # [   22.295972] systemd[1]: sshd-vsock@1-2-4:22-2:1.service: Deactivated successfully.
server # [   22.299568] systemd[1]: session-3.scope: Deactivated successfully.
server # [   22.302744] systemd[1]: session-3.scope: Consumed 320ms CPU time over 869ms wall clock time, 48.1M memory peak, 1.4K outgoing IP traffic.
server # [   22.308314] systemd-logind[537]: Session 3 logged out. Waiting for processes to exit.
server # [   22.309851] systemd-logind[537]: Removed session 3.
server # [   22.339912] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.
server # [   22.345123] systemd[1]: Started SSH per-connection Daemon (vsock:2:2).
server # [   22.457588] sshd-session[1066]: pam_unix(sshd:auth): user [root] has blank password; authenticated without it
server # [   22.467430] sshd-session[1066]: Accepted none for root from UNKNOWN port 65535 ssh2
server # [   22.480583] sshd-session[1066]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)
server # [   22.483745] nix-daemon[1060]: accepted connection from pid 999, user nix-grpc-daemon (trusted)
server # [   22.493423] systemd-logind[537]: New session '4' of user 'root' with class 'user' and type 'tty'.
server # [   22.503829] nix-grpc-daemon[999]: ts=2026-09-30T09:11:08Z level=info event=denied method=Connect cn=host1 role=read-only
server # [   22.508158] systemd[1]: Started Session 4 of User root.
host1 # [   22.515920] sshd-session[971]: Received disconnect from UNKNOWN port 65535:11: disconnected by user
host1 # [   22.519707] sshd-session[971]: Disconnected from user root UNKNOWN port 65535
host1 # [   22.522267] sshd-session[955]: pam_unix(sshd:session): session closed for user root
host1 # [   22.527399] systemd[1]: sshd-vsock@5-6-3:22-2:5.service: Deactivated successfully.
host1 # [   22.534639] systemd[1]: session-7.scope: Deactivated successfully.
host1 # [   22.539272] systemd[1]: session-7.scope: Consumed 156ms CPU time over 571ms wall clock time, 39M memory peak, 5.2K incoming IP traffic, 4.1K outgoing IP traffic.
host1 # [   22.543690] systemd-logind[531]: Session 7 logged out. Waiting for processes to exit.
host1 # [   22.546545] systemd-logind[531]: Removed session 7.
server # [   22.633259] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.
server # [   22.637894] systemd[1]: Started SSH per-connection Daemon (vsock:2:3).
server # [   22.647925] sshd-session[1075]: Received disconnect from UNKNOWN port 65535:11: disconnected by user
server # [   22.649545] sshd-session[1075]: Disconnected from user root UNKNOWN port 65535
server # [   22.651263] sshd-session[1066]: pam_unix(sshd:session): session closed for user root
server # [   22.656716] systemd[1]: sshd-vsock@2-3-4:22-2:2.service: Deactivated successfully.
server # [   22.661547] systemd[1]: session-4.scope: Deactivated successfully.
server # [   22.666432] systemd-logind[537]: Session 4 logged out. Waiting for processes to exit.
server # [   22.670931] systemd-logind[537]: Removed session 4.
server # [   22.721428] sshd-session[1080]: pam_unix(sshd:auth): user [root] has blank password; authenticated without it
server # [   22.726692] sshd-session[1080]: Accepted none for root from UNKNOWN port 65535 ssh2
host1 # [   22.710069] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.
server # [   22.734018] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.
server # [   22.739772] systemd[1]: Started SSH per-connection Daemon (vsock:2:4).
host1 # [   22.718553] systemd[1]: Started SSH per-connection Daemon (vsock:2:7).
server # [   22.748509] sshd-session[1080]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)
server # [   22.759028] systemd-logind[537]: New session '5' of user 'root' with class 'user' and type 'tty'.
server # [   22.763613] systemd[1]: Started Session 5 of User root.
server # [   22.805647] sshd-session[1089]: Received disconnect from UNKNOWN port 65535:11: disconnected by user
server # [   22.807913] sshd-session[1089]: Disconnected from user root UNKNOWN port 65535
server # [   22.813130] sshd-session[1080]: pam_unix(sshd:session): session closed for user root
test acme::a_read_only_host_cannot_write ... ok
server # [   22.819086] systemd[1]: sshd-vsock@3-4-4:22-2:3.service: Deactivated successfully.
server # [   22.823935] systemd[1]: session-5.scope: Deactivated successfully.
server # [   22.830212] systemd-logind[537]: Session 5 logged out. Waiting for processes to exit.
host1 # [   22.809734] sshd-session[992]: pam_unix(sshd:auth): user [root] has blank password; authenticated without it
server # [   22.835938] systemd-logind[537]: Removed session 5.
host1 # [   22.819714] sshd-session[992]: Accepted none for root from UNKNOWN port 65535 ssh2
host1 # [   22.828968] sshd-session[992]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)
server # [   22.859826] sshd-session[1088]: pam_unix(sshd:auth): user [root] has blank password; authenticated without it
host1 # [   22.841030] systemd-logind[531]: New session '9' of user 'root' with class 'user' and type 'tty'.
server # [   22.866530] sshd-session[1088]: Accepted none for root from UNKNOWN port 65535 ssh2
host1 # [   22.844642] systemd[1]: Started Session 9 of User root.
server # [   22.873103] sshd-session[1088]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)
server # [   22.882001] systemd-logind[537]: New session '6' of user 'root' with class 'user' and type 'tty'.
host1 # [   22.862337] sshd-session[998]: Received disconnect from UNKNOWN port 65535:11: disconnected by user
server # [   22.888433] systemd[1]: Started Session 6 of User root.
host1 # [   22.865063] sshd-session[998]: Disconnected from user root UNKNOWN port 65535
host1 # [   22.866657] sshd-session[992]: pam_unix(sshd:session): session closed for user root
host1 # [   22.871566] systemd[1]: sshd-vsock@7-8-3:22-2:7.service: Deactivated successfully.
host1 # [   22.876841] systemd[1]: session-9.scope: Deactivated successfully.
host1 # [   22.880949] systemd-logind[531]: Session 9 logged out. Waiting for processes to exit.
host1 # [   22.883445] systemd-logind[531]: Removed session 9.
host1 # [   22.911549] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.
host1 # [   22.919203] systemd[1]: Started SSH per-connection Daemon (vsock:2:8).
server # [   23.013018] sshd-session[1101]: Received disconnect from UNKNOWN port 65535:11: disconnected by user
server # [   23.015131] sshd-session[1101]: Disconnected from user root UNKNOWN port 65535
server # [   23.016661] sshd-session[1088]: pam_unix(sshd:session): session closed for user root
test acme::server_builds_and_signs_a_path ... ok
server # [   23.020984] systemd[1]: sshd-vsock@4-5-4:22-2:4.service: Deactivated successfully.
host1 # [   22.997149] sshd-session[1003]: pam_unix(sshd:auth): user [root] has blank password; authenticated without it
server # [   23.025446] systemd[1]: session-6.scope: Deactivated successfully.
host1 # [   23.005025] sshd-session[1003]: Accepted none for root from UNKNOWN port 65535 ssh2
server # [   23.029232] systemd-logind[537]: Session 6 logged out. Waiting for processes to exit.
server # [   23.031212] systemd-logind[537]: Removed session 6.
host1 # [   23.012324] sshd-session[1003]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)
host1 # [   23.019694] systemd-logind[531]: New session '10' of user 'root' with class 'user' and type 'tty'.
host1 # [   23.024963] systemd[1]: Started Session 10 of User root.
server # [   23.975256] nix-grpc-daemon[999]: ts=2026-09-30T09:11:09Z level=info event=rpc method=FetchNars cn=host1 peer=ipv4:192.168.1.1:46712 duration_s=0 paths=1 nar_bytes_out=128
host1 # [   23.987651] sshd-session[1009]: Received disconnect from UNKNOWN port 65535:11: disconnected by user
host1 # [   23.987872] sshd-session[1009]: Disconnected from user root UNKNOWN port 65535
host1 # [   23.992998] sshd-session[1003]: pam_unix(sshd:session): session closed for user root
host1 # [   23.998693] systemd[1]: sshd-vsock@8-9-3:22-2:8.service: Deactivated successfully.
host1 # [   24.007139] systemd[1]: session-10.scope: Deactivated successfully.
host1 # [   24.011428] systemd[1]: session-10.scope: Consumed 179ms CPU time over 984ms wall clock time, 38.8M memory peak, 10.1K incoming IP traffic, 9.1K outgoing IP traffic.
host1 # [   24.018543] systemd-logind[531]: Session 10 logged out. Waiting for processes to exit.
host1 # [   24.021944] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.
host1 # [   24.029755] systemd[1]: Started SSH per-connection Daemon (vsock:2:9).
host1 # [   24.036456] systemd-logind[531]: Removed session 10.
host1 # [   24.104755] sshd-session[1028]: pam_unix(sshd:auth): user [root] has blank password; authenticated without it
host1 # [   24.111642] sshd-session[1028]: Accepted none for root from UNKNOWN port 65535 ssh2
host1 # [   24.118188] sshd-session[1028]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)
host1 # [   24.127199] systemd-logind[531]: New session '11' of user 'root' with class 'user' and type 'tty'.
host1 # [   24.133145] systemd[1]: Started Session 11 of User root.
host1 # [   24.152319] sshd-session[1034]: Received disconnect from UNKNOWN port 65535:11: disconnected by user
host1 # [   24.154601] sshd-session[1034]: Disconnected from user root UNKNOWN port 65535
host1 # [   24.155934] sshd-session[1028]: pam_unix(sshd:session): session closed for user root
host1 # [   24.160309] systemd[1]: sshd-vsock@9-10-3:22-2:9.service: Deactivated successfully.
host1 # [   24.165273] systemd[1]: session-11.scope: Deactivated successfully.
host1 # [   24.169021] systemd-logind[531]: Session 11 logged out. Waiting for processes to exit.
host1 # [   24.171587] systemd-logind[531]: Removed session 11.
test acme::host1_substitutes_the_signed_path_with_a_read_only_cert ... ok

test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 49 filtered out; finished in 4.41s

(finished: run the VM test script, in 24.99 seconds)
test script finished in 25.04s
cleanup
kill QemuMachine (pid 54)
host1 # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)
host1 # [2026-09-30T09:11:09Z INFO  virtiofsd] Client disconnected, shutting down
host1 # [2026-09-30T09:11:09Z INFO  virtiofsd] Client disconnected, shutting down
host1 # [2026-09-30T09:11:09Z INFO  virtiofsd] Client disconnected, shutting down
kill QemuMachine (pid 55)
server # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)
server # [2026-09-30T09:11:09Z INFO  virtiofsd] Client disconnected, shutting down
server # [2026-09-30T09:11:09Z INFO  virtiofsd] Client disconnected, shutting down
server # [2026-09-30T09:11:09Z INFO  virtiofsd] Client disconnected, shutting down
(finished: cleanup, in 0.45 seconds)
SSH backdoor enabled, the machines can be accessed like this:
Note: this requires systemd-ssh-proxy(1) to be enabled (default on NixOS 25.05 and newer).
    host1:   ssh -o User=root vsock-mux//build/tmplffcpo_i/host1_host.socket
    server:  ssh -o User=root vsock-mux//build/tmplffcpo_i/server_host.socket
additionally exposed symbols:
    host1, server,
    vlan1,
    start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh