vm-test-run-nix-grpc-store-acme-substituter
checks.aarch64-linux.acme-vm
· build #477
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 600.0 seconds6run the VM test script7start all VMs8host1: starting vm9server: starting vm10server # Disk image does not exist, creating the virtualisation disk image...11server: QEMU running (pid 48)12server # Formatting '/build/vm-state-server/tmp.KbKJZOsrTd', fmt=raw size=107374182413host1 # Disk image does not exist, creating the virtualisation disk image...14server # mke2fs 1.47.4 (6-Mar-2025)15host1 # Formatting '/build/vm-state-host1/tmp.hXrgXdrMaA', fmt=raw size=107374182416server # Discarding device blocks: 0/262144 done17host1 # mke2fs 1.47.4 (6-Mar-2025)18server # Creating filesystem with 262144 4k blocks and 65536 inodes19host1 # Discarding device blocks: 0/262144 done20server # Filesystem UUID: 4749a991-77d9-4a8f-8bad-67f06a2a49b321host1 # Creating filesystem with 262144 4k blocks and 65536 inodes22server # Superblock backups stored on blocks:23host1 # Filesystem UUID: 51e8bc18-1023-43e1-ae96-0c635174dbe224server # 32768, 98304, 163840, 22937625host1 # Superblock backups stored on blocks:26server # 27host1 # 32768, 98304, 163840, 22937628server # Allocating group tables: 0/8 done29host1 # 30server # Writing inode tables: 0/8 done31host1 # Allocating group tables: 0/8 done32server # Creating journal (8192 blocks): done33host1 # Writing inode tables: 0/8 done34server # Writing superblocks and filesystem accounting information: 0/8 done35host1 # Creating journal (8192 blocks): done36server # 37host1 # Writing superblocks and filesystem accounting information: 0/8 done38server # Virtualisation disk image created.39host1 # 40host1: QEMU running (pid 47)41host1 # Virtualisation disk image created.42(finished: start all VMs, in 0.55 seconds)43server: waiting for unit step-ca.service44server: waiting for the VM to finish booting45server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]46server # [ 0.000000] Linux version 6.18.50 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 7 15:23:00 UTC 202647server # [ 0.000000] KASLR enabled48server # [ 0.000000] random: crng init done49server # [ 0.000000] Machine model: linux,dummy-virt50server # [ 0.000000] efi: UEFI not found.51server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT52server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x00000000bfffffff]53server # [ 0.000000] NODE_DATA(0) allocated [mem 0xbfdec700-0xbfdefe7f]54server # [ 0.000000] Zone ranges:55server # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000bfffffff]56server # [ 0.000000] DMA32 empty57server # [ 0.000000] Normal empty58server # [ 0.000000] Device empty59server # [ 0.000000] Movable zone start for each node60server # [ 0.000000] Early memory node ranges61server # [ 0.000000] node 0: [mem 0x0000000040000000-0x00000000bfffffff]62server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x00000000bfffffff]63server # [ 0.000000] cma: Reserved 32 MiB at 0x00000000bbc0000064server # [ 0.000000] psci: probing for conduit method from DT.65server # [ 0.000000] psci: PSCIv1.3 detected in firmware.66server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs67server # [ 0.000000] psci: Trusted OS migration not required68server # [ 0.000000] psci: SMC Calling Convention v1.169server # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)70server # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u31129671server # [ 0.000000] Detected PIPT I-cache on CPU072server # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)73server # [ 0.000000] CPU features: detected: GICv3 CPU interface74server # [ 0.000000] CPU features: detected: Spectre-v475server # [ 0.000000] CPU features: detected: Spectre-BHB76server # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_3877server # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_2378server # [ 0.000000] alternatives: applying boot alternatives79server # [ 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/4sgbb71nw078lpy3940kgcqaqc98ppqm-nixos-system-server-test/init regInfo=/nix/store/8kpzqb0wms92f03i5ylz6n4438d8aa1b-closure-info/registration console=ttyAMA0,115200n8 console=tty080server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/8kpzqb0wms92f03i5ylz6n4438d8aa1b-closure-info/registration", will be passed to user space.81server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes82server # [ 0.000000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)83server # [ 0.000000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)84server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB85server # [ 0.000000] software IO TLB: area num 1.86server # [ 0.000000] software IO TLB: mapped [mem 0x00000000bb800000-0x00000000bba00000] (2MB)87server # [ 0.000000] Fallback order for Node 0: 088server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 52428889server # [ 0.000000] Policy zone: DMA90server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off91host1 # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]92server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=193server # [ 0.000000] allocated 4194304 bytes of page_ext94host1 # [ 0.000000] Linux version 6.18.50 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 7 15:23:00 UTC 202695host1 # [ 0.000000] KASLR enabled96server # [ 0.000000] ftrace: allocating 74885 entries in 294 pages97host1 # [ 0.000000] random: crng init done98server # [ 0.000000] ftrace: allocated 294 pages with 4 groups99host1 # [ 0.000000] Machine model: linux,dummy-virt100server # [ 0.000000] rcu: Hierarchical RCU implementation.101host1 # [ 0.000000] efi: UEFI not found.102server # [ 0.000000] rcu: RCU event tracing is enabled.103host1 # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT104server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.105host1 # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x00000000bfffffff]106server # [ 0.000000] Trampoline variant of Tasks RCU enabled.107host1 # [ 0.000000] NODE_DATA(0) allocated [mem 0xbfdec700-0xbfdefe7f]108server # [ 0.000000] Rude variant of Tasks RCU enabled.109host1 # [ 0.000000] Zone ranges:110server # [ 0.000000] Tracing variant of Tasks RCU enabled.111host1 # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000bfffffff]112host1 # [ 0.000000] DMA32 empty113server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.114host1 # [ 0.000000] Normal empty115host1 # [ 0.000000] Device empty116server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1117host1 # [ 0.000000] Movable zone start for each node118host1 # [ 0.000000] Early memory node ranges119server # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.120host1 # [ 0.000000] node 0: [mem 0x0000000040000000-0x00000000bfffffff]121server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.122host1 # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x00000000bfffffff]123host1 # [ 0.000000] cma: Reserved 32 MiB at 0x00000000bbc00000124server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.125host1 # [ 0.000000] psci: probing for conduit method from DT.126server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0127host1 # [ 0.000000] psci: PSCIv1.3 detected in firmware.128server # [ 0.000000] GICv3: 256 SPIs implemented129host1 # [ 0.000000] psci: Using standard PSCI v0.2 function IDs130server # [ 0.000000] GICv3: 0 Extended SPIs implemented131host1 # [ 0.000000] psci: Trusted OS migration not required132server # [ 0.000000] Root IRQ handler: gic_handle_irq133host1 # [ 0.000000] psci: SMC Calling Convention v1.1134server # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI135server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0136host1 # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)137server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000138host1 # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u311296139server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]140host1 # [ 0.000000] Detected PIPT I-cache on CPU0141server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ae0000 (indirect, esz 8, psz 64K, shr 1)142host1 # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)143host1 # [ 0.000000] CPU features: detected: GICv3 CPU interface144server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44af0000 (flat, esz 8, psz 64K, shr 1)145host1 # [ 0.000000] CPU features: detected: Spectre-v4146host1 # [ 0.000000] CPU features: detected: Spectre-BHB147server # [ 0.000000] GICv3: using LPI property table @0x0000000044b10000148host1 # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38149server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044b20000150host1 # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23151server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.152host1 # [ 0.000000] alternatives: applying boot alternatives153server # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns154server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).155server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns156host1 # [ 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/gs9wxvhqnagxz34vl4cmjirjiipkv6ss-nixos-system-host1-test/init regInfo=/nix/store/jb5xlsfl8dnmdaw4sldki64rjjzvimbj-closure-info/registration console=ttyAMA0,115200n8 console=tty0157server # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns158server # [ 0.000032] arm-pv: using stolen time PV159server # [ 0.000443] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)160host1 # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/jb5xlsfl8dnmdaw4sldki64rjjzvimbj-closure-info/registration", will be passed to user space.161server # [ 0.000632] Console: colour dummy device 80x25162server # [ 0.000640] printk: legacy console [tty0] enabled163host1 # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes164host1 # [ 0.000000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)165server # [ 0.000836] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)166server # [ 0.000843] pid_max: default: 32768 minimum: 301167host1 # [ 0.000000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)168server # [ 0.000919] LSM: initializing lsm=capability,landlock,yama,bpf,ima169host1 # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB170server # [ 0.001055] landlock: Up and running.171host1 # [ 0.000000] software IO TLB: area num 1.172server # [ 0.001058] Yama: becoming mindful.173server # [ 0.001537] LSM support for eBPF active174host1 # [ 0.000000] software IO TLB: mapped [mem 0x00000000bb800000-0x00000000bba00000] (2MB)175server # [ 0.001677] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)176host1 # [ 0.000000] Fallback order for Node 0: 0177host1 # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 524288178server # [ 0.001715] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)179host1 # [ 0.000000] Policy zone: DMA180server # [ 0.002841] cacheinfo: Unable to detect cache hierarchy for CPU 0181host1 # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off182server # [ 0.003559] rcu: Hierarchical SRCU implementation.183host1 # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1184server # [ 0.003564] rcu: Max phase no-delay instances is 1000.185host1 # [ 0.000000] allocated 4194304 bytes of page_ext186server # [ 0.005042] fsl-mc MSI: its@8080000 domain created187host1 # [ 0.000000] ftrace: allocating 74885 entries in 294 pages188server # [ 0.005134] EFI services will not be available.189host1 # [ 0.000000] ftrace: allocated 294 pages with 4 groups190server # [ 0.005202] smp: Bringing up secondary CPUs ...191server # [ 0.005210] smp: Brought up 1 node, 1 CPU192host1 # [ 0.000000] rcu: Hierarchical RCU implementation.193server # [ 0.005214] SMP: Total of 1 processors activated.194host1 # [ 0.000000] rcu: RCU event tracing is enabled.195server # [ 0.005216] CPU: All CPU(s) started at EL1196host1 # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.197server # [ 0.005231] CPU features: detected: Branch Target Identification198host1 # [ 0.000000] Trampoline variant of Tasks RCU enabled.199server # [ 0.005236] CPU features: detected: ARMv8.4 Translation Table Level200host1 # [ 0.000000] Rude variant of Tasks RCU enabled.201host1 # [ 0.000000] Tracing variant of Tasks RCU enabled.202server # [ 0.005239] CPU features: detected: Instruction cache invalidation not required for I/D coherence203host1 # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.204server # [ 0.005242] CPU features: detected: Data cache clean to the PoU not required for I/D coherence205host1 # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1206server # [ 0.005246] CPU features: detected: Common not Private translations207server # [ 0.005249] CPU features: detected: CRC32 instructions208host1 # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.209server # [ 0.005252] CPU features: detected: Data cache clean to Point of Deep Persistence210host1 # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.211server # [ 0.005255] CPU features: detected: Data cache clean to Point of Persistence212host1 # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.213server # [ 0.005259] CPU features: detected: Data independent timing control (DIT)214host1 # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0215server # [ 0.005262] CPU features: detected: E0PD216host1 # [ 0.000000] GICv3: 256 SPIs implemented217server # [ 0.005264] CPU features: detected: Enhanced Counter Virtualization218host1 # [ 0.000000] GICv3: 0 Extended SPIs implemented219host1 # [ 0.000000] Root IRQ handler: gic_handle_irq220server # [ 0.005267] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)221host1 # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI222server # [ 0.005271] CPU features: detected: Enhanced Virtualization Traps223host1 # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0224server # [ 0.005274] CPU features: detected: Fine Grained Traps225host1 # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000226host1 # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]227server # [ 0.005277] CPU features: detected: Generic authentication (architected QARMA5 algorithm)228server # [ 0.005282] CPU features: detected: RCpc load-acquire (LDAPR)229host1 # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44af0000 (indirect, esz 8, psz 64K, shr 1)230server # [ 0.005285] CPU features: detected: LSE atomic instructions231server # [ 0.005288] CPU features: detected: Privileged Access Never232host1 # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44b00000 (flat, esz 8, psz 64K, shr 1)233server # [ 0.005291] CPU features: detected: PMUv3234host1 # [ 0.000000] GICv3: using LPI property table @0x0000000044b10000235host1 # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044b20000236server # [ 0.005293] CPU features: detected: RAS Extension Support237server # [ 0.005296] CPU features: detected: RASv1p1 Extension Support238host1 # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.239server # [ 0.005299] CPU features: detected: Random Number Generator240host1 # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns241server # [ 0.005301] CPU features: detected: Speculation barrier (SB)242host1 # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).243server # [ 0.005304] CPU features: detected: Stage-2 Force Write-Back244server # [ 0.005307] CPU features: detected: TLB range maintenance instructions245host1 # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns246server # [ 0.005312] CPU features: detected: Speculative Store Bypassing Safe (SSBS)247host1 # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns248server # [ 0.005349] alternatives: applying system-wide alternatives249host1 # [ 0.000029] arm-pv: using stolen time PV250server # [ 0.008408] CPU features: detected: BBM Level 2 without TLB conflict abort251host1 # [ 0.000386] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)252host1 # [ 0.000552] Console: colour dummy device 80x25253server # [ 0.008674] Memory: 1921600K/2097152K available (24448K kernel code, 7090K rwdata, 26348K rodata, 4736K init, 1107K bss, 131956K reserved, 32768K cma-reserved)254host1 # [ 0.000560] printk: legacy console [tty0] enabled255server # [ 0.009101] devtmpfs: initialized256server # [ 0.011074] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)257host1 # [ 0.000752] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)258host1 # [ 0.000758] pid_max: default: 32768 minimum: 301259server # [ 0.011097] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).260host1 # [ 0.000827] LSM: initializing lsm=capability,landlock,yama,bpf,ima261server # [ 0.011292] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL262host1 # [ 0.000995] landlock: Up and running.263server # [ 0.011296] 0 pages in range for non-PLT usage264host1 # [ 0.000998] Yama: becoming mindful.265host1 # [ 0.001431] LSM support for eBPF active266server # [ 0.011297] 508288 pages in range for PLT usage267server # [ 0.011401] pinctrl core: initialized pinctrl subsystem268host1 # [ 0.001549] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)269server # [ 0.012260] DMI not present or invalid.270host1 # [ 0.001582] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)271server # [ 0.015441] NET: Registered PF_NETLINK/PF_ROUTE protocol family272host1 # [ 0.002687] cacheinfo: Unable to detect cache hierarchy for CPU 0273server # [ 0.017817] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations274host1 # [ 0.003578] rcu: Hierarchical SRCU implementation.275server # [ 0.018001] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations276host1 # [ 0.003583] rcu: Max phase no-delay instances is 1000.277host1 # [ 0.004801] fsl-mc MSI: its@8080000 domain created278server # [ 0.018208] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations279host1 # [ 0.004890] EFI services will not be available.280server # [ 0.018229] audit: initializing netlink subsys (disabled)281host1 # [ 0.004952] smp: Bringing up secondary CPUs ...282host1 # [ 0.004960] smp: Brought up 1 node, 1 CPU283server # [ 0.018791] thermal_sys: Registered thermal governor 'fair_share'284host1 # [ 0.004963] SMP: Total of 1 processors activated.285server # [ 0.018794] thermal_sys: Registered thermal governor 'bang_bang'286host1 # [ 0.004966] CPU: All CPU(s) started at EL1287server # [ 0.018797] thermal_sys: Registered thermal governor 'step_wise'288host1 # [ 0.004979] CPU features: detected: Branch Target Identification289server # [ 0.018800] thermal_sys: Registered thermal governor 'user_space'290host1 # [ 0.004984] CPU features: detected: ARMv8.4 Translation Table Level291server # [ 0.018805] thermal_sys: Registered thermal governor 'power_allocator'292host1 # [ 0.004988] CPU features: detected: Instruction cache invalidation not required for I/D coherence293server # [ 0.018836] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1294server # [ 0.018845] cpuidle: using governor ladder295host1 # [ 0.004991] CPU features: detected: Data cache clean to the PoU not required for I/D coherence296server # [ 0.018850] cpuidle: using governor menu297host1 # [ 0.004995] CPU features: detected: Common not Private translations298server # [ 0.019053] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.299host1 # [ 0.004998] CPU features: detected: CRC32 instructions300server # [ 0.019069] ASID allocator initialised with 65536 entries301server # [ 0.020314] Serial: AMBA PL011 UART driver302host1 # [ 0.005001] CPU features: detected: Data cache clean to Point of Deep Persistence303host1 # [ 0.005005] CPU features: detected: Data cache clean to Point of Persistence304server # [ 0.025519] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1305server # [ 0.025676] printk: console [ttyAMA0] enabled306host1 # [ 0.005008] CPU features: detected: Data independent timing control (DIT)307host1 # [ 0.005011] CPU features: detected: E0PD308host1 # [ 0.005014] CPU features: detected: Enhanced Counter Virtualization309host1 # [ 0.005016] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)310host1 # [ 0.005019] CPU features: detected: Enhanced Virtualization Traps311host1 # [ 0.005022] CPU features: detected: Fine Grained Traps312host1 # [ 0.005026] CPU features: detected: Generic authentication (architected QARMA5 algorithm)313host1 # [ 0.005031] CPU features: detected: RCpc load-acquire (LDAPR)314host1 # [ 0.005034] CPU features: detected: LSE atomic instructions315host1 # [ 0.005038] CPU features: detected: Privileged Access Never316host1 # [ 0.005040] CPU features: detected: PMUv3317host1 # [ 0.005043] CPU features: detected: RAS Extension Support318host1 # [ 0.005046] CPU features: detected: RASv1p1 Extension Support319server # [ 0.148713] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages320host1 # [ 0.005049] CPU features: detected: Random Number Generator321server # [ 0.148731] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page322host1 # [ 0.005051] CPU features: detected: Speculation barrier (SB)323server # [ 0.148737] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages324host1 # [ 0.005054] CPU features: detected: Stage-2 Force Write-Back325server # [ 0.148741] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page326host1 # [ 0.005057] CPU features: detected: TLB range maintenance instructions327server # [ 0.148745] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages328host1 # [ 0.005062] CPU features: detected: Speculative Store Bypassing Safe (SSBS)329server # [ 0.148750] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page330host1 # [ 0.005099] alternatives: applying system-wide alternatives331server # [ 0.148754] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages332host1 # [ 0.007962] CPU features: detected: BBM Level 2 without TLB conflict abort333server # [ 0.148758] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page334host1 # [ 0.008113] Memory: 1921564K/2097152K available (24448K kernel code, 7090K rwdata, 26348K rodata, 4736K init, 1107K bss, 131976K reserved, 32768K cma-reserved)335host1 # [ 0.008532] devtmpfs: initialized336server # [ 0.156361] fbcon: Taking over console337server # [ 0.156377] ACPI: Interpreter disabled.338host1 # [ 0.010426] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)339server # [ 0.165008] iommu: Default domain type: Translated340host1 # [ 0.010449] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).341server # [ 0.165020] iommu: DMA domain TLB invalidation policy: strict mode342host1 # [ 0.010614] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL343server # [ 0.165424] SCSI subsystem initialized344host1 # [ 0.010619] 0 pages in range for non-PLT usage345host1 # [ 0.010620] 508288 pages in range for PLT usage346server # [ 0.167497] usbcore: registered new interface driver usbfs347host1 # [ 0.010697] pinctrl core: initialized pinctrl subsystem348server # [ 0.167530] usbcore: registered new interface driver hub349host1 # [ 0.011462] DMI not present or invalid.350server # [ 0.167555] usbcore: registered new device driver usb351host1 # [ 0.014574] NET: Registered PF_NETLINK/PF_ROUTE protocol family352server # [ 0.167832] pps_core: LinuxPPS API ver. 1 registered353host1 # [ 0.016834] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations354server # [ 0.167841] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>355host1 # [ 0.017027] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations356server # [ 0.167850] PTP clock support registered357server # [ 0.167905] EDAC MC: Ver: 3.0.0358host1 # [ 0.017235] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations359server # [ 0.172686] scmi_core: SCMI protocol bus registered360host1 # [ 0.017256] audit: initializing netlink subsys (disabled)361host1 # [ 0.017919] thermal_sys: Registered thermal governor 'fair_share'362server # [ 0.173704] FPGA manager framework363server # [ 0.174664] vgaarb: loaded364host1 # [ 0.017921] thermal_sys: Registered thermal governor 'bang_bang'365host1 # [ 0.017925] thermal_sys: Registered thermal governor 'step_wise'366server # [ 0.175329] clocksource: Switched to clocksource arch_sys_counter367host1 # [ 0.017927] thermal_sys: Registered thermal governor 'user_space'368host1 # [ 0.017933] thermal_sys: Registered thermal governor 'power_allocator'369host1 # [ 0.017957] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1370host1 # [ 0.017966] cpuidle: using governor ladder371host1 # [ 0.017972] cpuidle: using governor menu372host1 # [ 0.018154] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.373server # [ 0.179850] VFS: Disk quotas dquot_6.6.0374host1 # [ 0.018169] ASID allocator initialised with 65536 entries375host1 # [ 0.019293] Serial: AMBA PL011 UART driver376server # [ 0.179899] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)377host1 # [ 0.024411] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1378server # [ 0.181615] netfs: FS-Cache loaded379server # [ 0.181740] pnp: PnP ACPI: disabled380host1 # [ 0.024529] printk: console [ttyAMA0] enabled381host1 # [ 0.148434] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages382host1 # [ 0.148457] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page383host1 # [ 0.148462] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages384host1 # [ 0.148466] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page385host1 # [ 0.148471] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages386server # [ 0.186095] NET: Registered PF_INET protocol family387host1 # [ 0.148475] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page388server # [ 0.186359] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)389host1 # [ 0.148479] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages390host1 # [ 0.148483] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page391host1 # [ 0.156190] fbcon: Taking over console392host1 # [ 0.156209] ACPI: Interpreter disabled.393host1 # [ 0.158098] iommu: Default domain type: Translated394host1 # [ 0.158108] iommu: DMA domain TLB invalidation policy: strict mode395host1 # [ 0.159734] SCSI subsystem initialized396host1 # [ 0.164885] usbcore: registered new interface driver usbfs397host1 # [ 0.164917] usbcore: registered new interface driver hub398host1 # [ 0.164941] usbcore: registered new device driver usb399host1 # [ 0.165227] pps_core: LinuxPPS API ver. 1 registered400host1 # [ 0.165236] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>401host1 # [ 0.165247] PTP clock support registered402host1 # [ 0.165303] EDAC MC: Ver: 3.0.0403host1 # [ 0.169914] scmi_core: SCMI protocol bus registered404host1 # [ 0.170888] FPGA manager framework405host1 # [ 0.171871] vgaarb: loaded406host1 # [ 0.172521] clocksource: Switched to clocksource arch_sys_counter407host1 # [ 0.176574] VFS: Disk quotas dquot_6.6.0408host1 # [ 0.176605] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)409host1 # [ 0.180336] netfs: FS-Cache loaded410host1 # [ 0.180457] pnp: PnP ACPI: disabled411host1 # [ 0.184841] NET: Registered PF_INET protocol family412server # [ 0.216488] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear)413host1 # [ 0.185113] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)414server # [ 0.216532] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)415server # [ 0.216561] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)416server # [ 0.216637] TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear)417server # [ 0.216786] TCP: Hash tables configured (established 16384 bind 16384)418server # [ 0.216879] MPTCP token hash table entries: 2048 (order: 4, 49152 bytes, linear)419server # [ 0.216915] UDP hash table entries: 1024 (order: 4, 65536 bytes, linear)420server # [ 0.216953] UDP-Lite hash table entries: 1024 (order: 4, 65536 bytes, linear)421server # [ 0.217055] NET: Registered PF_UNIX/PF_LOCAL protocol family422server # [ 0.217092] NET: Registered PF_XDP protocol family423server # [ 0.217112] PCI: CLS 0 bytes, default 64424server # [ 0.217367] Trying to unpack rootfs image as initramfs...425server # [ 0.233171] kvm [1]: HYP mode not available426host1 # [ 0.215359] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear)427host1 # [ 0.215407] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)428host1 # [ 0.215436] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)429host1 # [ 0.215512] TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear)430host1 # [ 0.215657] TCP: Hash tables configured (established 16384 bind 16384)431host1 # [ 0.215760] MPTCP token hash table entries: 2048 (order: 4, 49152 bytes, linear)432host1 # [ 0.215793] UDP hash table entries: 1024 (order: 4, 65536 bytes, linear)433host1 # [ 0.215854] UDP-Lite hash table entries: 1024 (order: 4, 65536 bytes, linear)434host1 # [ 0.215964] NET: Registered PF_UNIX/PF_LOCAL protocol family435host1 # [ 0.215994] NET: Registered PF_XDP protocol family436host1 # [ 0.216019] PCI: CLS 0 bytes, default 64437host1 # [ 0.216291] Trying to unpack rootfs image as initramfs...438host1 # [ 0.234417] kvm [1]: HYP mode not available439server # [ 0.332084] Initialise system trusted keyrings440server # [ 0.332856] workingset: timestamp_bits=42 max_order=19 bucket_order=0441server # [ 0.334250] squashfs: version 4.0 (2009/01/31) Phillip Lougher442server # [ 0.335028] 9p: Installing v9fs 9p2000 file system support443server # [ 0.363898] Key type asymmetric registered444server # [ 0.363927] Asymmetric key parser 'x509' registered445server # [ 0.364022] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)446host1 # [ 0.331188] Initialise system trusted keyrings447server # [ 0.366183] io scheduler mq-deadline registered448server # [ 0.366198] io scheduler kyber registered449host1 # [ 0.331975] workingset: timestamp_bits=42 max_order=19 bucket_order=0450host1 # [ 0.333364] squashfs: version 4.0 (2009/01/31) Phillip Lougher451host1 # [ 0.334157] 9p: Installing v9fs 9p2000 file system support452server # [ 0.379742] pl061_gpio 9030000.pl061: PL061 GPIO chip registered453server # [ 0.380448] ledtrig-cpu: registered to indicate activity on CPUs454server # [ 0.380878] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:455host1 # [ 0.354252] Key type asymmetric registered456host1 # [ 0.354280] Asymmetric key parser 'x509' registered457server # [ 0.380898] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000458host1 # [ 0.354354] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)459server # [ 0.380911] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000460server # [ 0.380919] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000461server # [ 0.380960] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits462server # [ 0.380991] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]463server # [ 0.381099] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00464host1 # [ 0.360593] io scheduler mq-deadline registered465host1 # [ 0.360610] io scheduler kyber registered466server # [ 0.381112] pci_bus 0000:00: root bus resource [bus 00-ff]467server # [ 0.381118] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]468server # [ 0.381123] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]469server # [ 0.381128] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]470server # [ 0.381227] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint471server # [ 0.381689] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint472server # [ 0.381890] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]473server # [ 0.381908] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]474server # [ 0.381937] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]475server # [ 0.381953] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]476server # [ 0.382422] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint477server # [ 0.382611] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]478server # [ 0.382633] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]479server # [ 0.382663] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]480server # [ 0.383116] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint481server # [ 0.383298] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]482host1 # [ 0.372699] pl061_gpio 9030000.pl061: PL061 GPIO chip registered483server # [ 0.383319] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]484server # [ 0.383377] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]485server # [ 0.383825] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint486host1 # [ 0.374177] ledtrig-cpu: registered to indicate activity on CPUs487server # [ 0.384017] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]488server # [ 0.384034] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]489host1 # [ 0.374567] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:490server # [ 0.384063] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]491host1 # [ 0.374586] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000492server # [ 0.384531] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint493host1 # [ 0.374606] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000494server # [ 0.384711] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]495server # [ 0.384727] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]496host1 # [ 0.374615] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000497server # [ 0.384757] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]498host1 # [ 0.374646] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits499server # [ 0.385215] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint500server # [ 0.385395] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]501host1 # [ 0.374670] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]502server # [ 0.385411] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]503host1 # [ 0.374777] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00504host1 # [ 0.374876] pci_bus 0000:00: root bus resource [bus 00-ff]505server # [ 0.385440] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]506host1 # [ 0.374894] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]507server # [ 0.385879] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint508host1 # [ 0.374909] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]509server # [ 0.386061] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]510server # [ 0.386081] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]511host1 # [ 0.374915] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]512server # [ 0.386109] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]513host1 # [ 0.374972] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint514server # [ 0.386125] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]515host1 # [ 0.375413] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint516server # [ 0.386630] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint517host1 # [ 0.375616] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]518server # [ 0.386815] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]519host1 # [ 0.375638] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]520server # [ 0.386845] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]521host1 # [ 0.375667] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]522host1 # [ 0.375683] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]523server # [ 0.387303] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint524host1 # [ 0.376145] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint525host1 # [ 0.376332] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]526host1 # [ 0.376348] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]527host1 # [ 0.376377] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]528server # [ 0.435582] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]529server # [ 0.435630] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]530server # [ 0.436097] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint531server # [ 0.436281] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]532server # [ 0.436536] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint533server # [ 0.436825] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]534server # [ 0.436844] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]535server # [ 0.436874] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]536server # [ 0.437330] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint537server # [ 0.437510] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]538server # [ 0.437526] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]539host1 # [ 0.404959] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint540host1 # [ 0.405164] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]541server # [ 0.437555] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]542host1 # [ 0.405182] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]543server # [ 0.438121] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned544host1 # [ 0.405211] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]545server # [ 0.438133] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned546host1 # [ 0.405686] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint547server # [ 0.438138] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned548host1 # [ 0.405870] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]549server # [ 0.438184] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned550host1 # [ 0.405887] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]551host1 # [ 0.405923] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]552server # [ 0.438232] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned553host1 # [ 0.406395] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint554server # [ 0.438279] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned555host1 # [ 0.406579] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]556server # [ 0.438325] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned557host1 # [ 0.406595] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]558server # [ 0.438372] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned559host1 # [ 0.406624] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]560server # [ 0.438426] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned561host1 # [ 0.407096] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint562host1 # [ 0.407282] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]563server # [ 0.438475] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned564host1 # [ 0.407298] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]565server # [ 0.438523] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned566host1 # [ 0.407328] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]567server # [ 0.438570] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned568host1 # [ 0.407781] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint569server # [ 0.438640] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned570host1 # [ 0.407969] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]571server # [ 0.438687] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned572host1 # [ 0.407985] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]573server # [ 0.438709] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned574host1 # [ 0.408015] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]575server # [ 0.438731] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned576host1 # [ 0.408031] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]577server # [ 0.438753] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned578host1 # [ 0.408545] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint579server # [ 0.438774] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned580host1 # [ 0.408734] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]581server # [ 0.438799] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned582host1 # [ 0.408764] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]583server # [ 0.438821] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned584host1 # [ 0.409243] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint585server # [ 0.438843] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned586host1 # [ 0.409432] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]587server # [ 0.438865] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned588host1 # [ 0.409462] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]589server # [ 0.438887] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned590host1 # [ 0.409868] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint591server # [ 0.438910] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned592host1 # [ 0.410052] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]593server # [ 0.438932] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned594host1 # [ 0.410299] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint595server # [ 0.438954] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned596host1 # [ 0.410593] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]597server # [ 0.438976] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned598host1 # [ 0.410611] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]599server # [ 0.439004] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned600host1 # [ 0.410641] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]601server # [ 0.439025] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned602server # [ 0.439046] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned603host1 # [ 0.411100] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint604host1 # [ 0.411284] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]605server # [ 0.439068] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned606host1 # [ 0.411300] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]607server # [ 0.439090] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned608server # [ 0.439112] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned609host1 # [ 0.411329] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]610server # [ 0.439134] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned611host1 # [ 0.411914] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned612server # [ 0.439161] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]613host1 # [ 0.411925] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned614server # [ 0.439171] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]615host1 # [ 0.411931] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned616server # [ 0.439175] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]617host1 # [ 0.411976] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned618host1 # [ 0.412022] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned619host1 # [ 0.412069] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned620server # [ 0.482052] pci 0000:00:0a.0: enabling device (0000 -> 0002)621host1 # [ 0.412114] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned622host1 # [ 0.412167] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned623host1 # [ 0.412215] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned624host1 # [ 0.412262] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned625host1 # [ 0.412313] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned626host1 # [ 0.412359] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned627host1 # [ 0.412433] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned628host1 # [ 0.412480] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned629host1 # [ 0.412501] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned630host1 # [ 0.460553] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned631host1 # [ 0.460593] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned632host1 # [ 0.460624] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned633host1 # [ 0.460650] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned634host1 # [ 0.460674] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned635host1 # [ 0.460697] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned636host1 # [ 0.460719] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned637host1 # [ 0.460741] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned638host1 # [ 0.460764] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned639host1 # [ 0.460787] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned640host1 # [ 0.460810] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned641host1 # [ 0.460831] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned642server # [ 0.506649] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)643host1 # [ 0.460853] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned644host1 # [ 0.460873] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned645host1 # [ 0.460896] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned646host1 # [ 0.460917] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned647host1 # [ 0.460939] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned648host1 # [ 0.460960] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned649host1 # [ 0.460982] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned650host1 # [ 0.461014] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]651host1 # [ 0.461024] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]652host1 # [ 0.461029] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]653host1 # [ 0.462198] pci 0000:00:0a.0: enabling device (0000 -> 0002)654server # [ 0.516838] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)655server # [ 0.527996] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)656server # [ 0.530363] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)657server # [ 0.532455] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)658server # [ 0.534739] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)659host1 # [ 0.507503] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)660host1 # [ 0.510598] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)661server # [ 0.544799] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)662server # [ 0.546911] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)663server # [ 0.558365] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)664host1 # [ 0.520747] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)665host1 # [ 0.522811] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)666host1 # [ 0.524800] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)667host1 # [ 0.526941] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)668server # [ 0.564379] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)669server # [ 0.566691] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)670host1 # [ 0.537110] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)671host1 # [ 0.539512] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)672server # [ 0.584034] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled673server # [ 0.586784] msm_serial: driver initialized674host1 # [ 0.549716] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)675server # [ 0.586978] SuperH (H)SCI(F) driver initialized676server # [ 0.587035] STM32 USART driver initialized677host1 # [ 0.551764] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)678host1 # [ 0.563541] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)679host1 # [ 0.574049] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled680host1 # [ 0.581989] msm_serial: driver initialized681host1 # [ 0.582142] SuperH (H)SCI(F) driver initialized682host1 # [ 0.582194] STM32 USART driver initialized683server # [ 0.621241] loop: module loaded684server # [ 0.621436] virtio_blk virtio5: 1/0/0 default/read/poll queues685server # [ 0.622216] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)686server # [ 0.628077] megasas: 07.734.00.00-rc1687server # [ 0.628821] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]688server # [ 0.630815] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000689server # [ 0.630842] Intel/Sharp Extended Query Table at 0x0031690server # [ 0.640682] Using buffer write method691server # [ 0.640789] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]692server # [ 0.642492] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000693server # [ 0.642526] Intel/Sharp Extended Query Table at 0x0031694host1 # [ 0.611920] loop: module loaded695host1 # [ 0.612101] virtio_blk virtio5: 1/0/0 default/read/poll queues696host1 # [ 0.612873] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)697server # [ 0.656079] Using buffer write method698server # [ 0.656131] Concatenating MTD devices:699server # [ 0.656135] (0): "0.flash"700server # [ 0.656140] (1): "0.flash"701server # [ 0.656143] into device "0.flash"702host1 # [ 0.625249] megasas: 07.734.00.00-rc1703host1 # [ 0.626062] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]704host1 # [ 0.628431] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000705host1 # [ 0.628457] Intel/Sharp Extended Query Table at 0x0031706host1 # [ 0.633454] Using buffer write method707host1 # [ 0.633529] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]708host1 # [ 0.635216] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000709host1 # [ 0.635239] Intel/Sharp Extended Query Table at 0x0031710host1 # [ 0.652620] Using buffer write method711host1 # [ 0.652672] Concatenating MTD devices:712host1 # [ 0.652677] (0): "0.flash"713host1 # [ 0.652693] (1): "0.flash"714host1 # [ 0.652699] into device "0.flash"715server # [ 0.940496] Freeing initrd memory: 26140K716server # [ 0.947180] tun: Universal TUN/TAP device driver, 1.6717server # [ 0.951139] thunder_xcv, ver 1.0718server # [ 0.951188] thunder_bgx, ver 1.0719server # [ 0.951213] nicpf, ver 1.0720server # [ 0.953027] e1000: Intel(R) PRO/1000 Network Driver721server # [ 0.953040] e1000: Copyright (c) 1999-2006 Intel Corporation.722server # [ 0.953065] e1000e: Intel(R) PRO/1000 Network Driver723server # [ 0.953073] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.724server # [ 0.953103] igb: Intel(R) Gigabit Ethernet Network Driver725server # [ 0.953108] igb: Copyright (c) 2007-2014 Intel Corporation.726server # [ 0.953142] igbvf: Intel(R) Gigabit Virtual Function Network Driver727server # [ 0.953151] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.728server # [ 0.953303] sky2: driver version 1.30729server # [ 0.954995] usbcore: registered new interface driver usb-storage730server # [ 0.955131] usbcore: registered new interface driver usbserial_generic731server # [ 0.955148] usbserial: USB Serial support registered for generic732server # [ 0.956150] ehci-pci 0000:00:0a.0: EHCI Host Controller733server # [ 0.956190] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1734server # [ 0.956420] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000735server # [ 0.967754] hv_vmbus: registering driver hyperv_keyboard736server # [ 0.969269] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00737server # [ 0.970325] hub 1-0:1.0: USB hub found738server # [ 0.970819] hub 1-0:1.0: 6 ports detected739host1 # [ 0.935344] Freeing initrd memory: 26160K740server # [ 0.972021] rtc-pl031 9010000.pl031: registered as rtc0741server # [ 0.972053] rtc-pl031 9010000.pl031: setting system clock to 2026-09-21T13:48:30 UTC (1789998510)742server # [ 0.972449] i2c_dev: i2c /dev entries driver743host1 # [ 0.942361] tun: Universal TUN/TAP device driver, 1.6744server # [ 0.977657] sdhci: Secure Digital Host Controller Interface driver745server # [ 0.977673] sdhci: Copyright(c) Pierre Ossman746server # [ 0.977959] Synopsys Designware Multimedia Card Interface Driver747server # [ 0.978374] sdhci-pltfm: SDHCI platform and OF driver helper748host1 # [ 0.946418] thunder_xcv, ver 1.0749server # [ 0.983032] hid: raw HID events driver (C) Jiri Kosina750host1 # [ 0.946470] thunder_bgx, ver 1.0751host1 # [ 0.946496] nicpf, ver 1.0752server # [ 0.983333] usbcore: registered new interface driver usbhid753host1 # [ 0.947152] e1000: Intel(R) PRO/1000 Network Driver754host1 # [ 0.947164] e1000: Copyright (c) 1999-2006 Intel Corporation.755server # [ 0.984697] usbhid: USB HID core driver756host1 # [ 0.947199] e1000e: Intel(R) PRO/1000 Network Driver757host1 # [ 0.947207] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.758host1 # [ 0.947240] igb: Intel(R) Gigabit Ethernet Network Driver759server # [ 0.986456] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available760host1 # [ 0.947247] igb: Copyright (c) 2007-2014 Intel Corporation.761host1 # [ 0.947279] igbvf: Intel(R) Gigabit Virtual Function Network Driver762host1 # [ 0.947291] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.763server # [ 0.989325] drop_monitor: Initializing network drop monitor service764host1 # [ 0.947436] sky2: driver version 1.30765server # [ 0.989598] NET: Registered PF_INET6 protocol family766server # [ 0.991666] Segment Routing with IPv6767server # [ 0.991686] In-situ OAM (IOAM) with IPv6768host1 # [ 0.957279] ehci-pci 0000:00:0a.0: EHCI Host Controller769server # [ 0.991721] NET: Registered PF_PACKET protocol family770host1 # [ 0.957319] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1771host1 # [ 0.957618] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000772server # [ 0.993465] 9pnet: Installing 9P2000 support773host1 # [ 0.960620] usbcore: registered new interface driver usb-storage774host1 # [ 0.960712] usbcore: registered new interface driver usbserial_generic775host1 # [ 0.960730] usbserial: USB Serial support registered for generic776host1 # [ 0.961403] hv_vmbus: registering driver hyperv_keyboard777server # [ 0.996569] Key type dns_resolver registered778host1 # [ 0.965463] rtc-pl031 9010000.pl031: registered as rtc0779host1 # [ 0.965496] rtc-pl031 9010000.pl031: setting system clock to 2026-09-21T13:48:30 UTC (1789998510)780host1 # [ 0.965855] i2c_dev: i2c /dev entries driver781host1 # [ 0.968878] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00782host1 # [ 0.969939] hub 1-0:1.0: USB hub found783host1 # [ 0.970472] hub 1-0:1.0: 6 ports detected784server # [ 1.003424] registered taskstats version 1785server # [ 1.003657] Loading compiled-in X.509 certificates786host1 # [ 0.973646] sdhci: Secure Digital Host Controller Interface driver787host1 # [ 0.973668] sdhci: Copyright(c) Pierre Ossman788host1 # [ 0.973953] Synopsys Designware Multimedia Card Interface Driver789host1 # [ 0.974444] sdhci-pltfm: SDHCI platform and OF driver helper790server # [ 1.012925] Demotion targets for Node 0: null791host1 # [ 0.976356] hid: raw HID events driver (C) Jiri Kosina792server # [ 1.013063] Key type .fscrypt registered793server # [ 1.013070] Key type fscrypt-provisioning registered794server # [ 1.013181] ima: No TPM chip found, activating TPM-bypass!795host1 # [ 0.980007] usbcore: registered new interface driver usbhid796host1 # [ 0.980020] usbhid: USB HID core driver797server # [ 1.013203] ima: Allocated hash algorithm: sha1798server # [ 1.013233] ima: No architecture policies found799server # [ 1.017870] input: gpio-keys as /devices/platform/gpio-keys/input/input0800host1 # [ 0.982447] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available801host1 # [ 0.984043] drop_monitor: Initializing network drop monitor service802host1 # [ 0.984244] NET: Registered PF_INET6 protocol family803host1 # [ 0.987373] Segment Routing with IPv6804host1 # [ 0.987393] In-situ OAM (IOAM) with IPv6805host1 # [ 0.987424] NET: Registered PF_PACKET protocol family806host1 # [ 0.989119] 9pnet: Installing 9P2000 support807host1 # [ 0.991393] Key type dns_resolver registered808host1 # [ 0.998966] registered taskstats version 1809host1 # [ 0.999164] Loading compiled-in X.509 certificates810server # [ 1.037840] clk: Disabling unused clocks811server # [ 1.037871] PM: genpd: Disabling unused power domains812host1 # [ 1.008153] Demotion targets for Node 0: null813server # [ 1.042772] Freeing unused kernel memory: 4736K814host1 # [ 1.008285] Key type .fscrypt registered815server # [ 1.043033] Run /init as init process816host1 # [ 1.008292] Key type fscrypt-provisioning registered817host1 # [ 1.008397] ima: No TPM chip found, activating TPM-bypass!818host1 # [ 1.008417] ima: Allocated hash algorithm: sha1819host1 # [ 1.008444] ima: No architecture policies found820host1 # [ 1.012657] input: gpio-keys as /devices/platform/gpio-keys/input/input0821server # [ 1.061743] systemd[1]: Successfully made /usr/ read-only.822host1 # [ 1.031959] clk: Disabling unused clocks823host1 # [ 1.031990] PM: genpd: Disabling unused power domains824host1 # [ 1.036764] Freeing unused kernel memory: 4736K825host1 # [ 1.037023] Run /init as init process826host1 # [ 1.055077] systemd[1]: Successfully made /usr/ read-only.827server # [ 1.219424] usb 1-1: new high-speed USB device number 2 using ehci-pci828host1 # [ 1.216619] usb 1-1: new high-speed USB device number 2 using ehci-pci829server # [ 1.372003] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1830server # [ 1.396453] 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)831server # [ 1.403216] systemd[1]: Detected virtualization qemu.832server # [ 1.404452] systemd[1]: Detected architecture arm64.833server # [ 1.405571] systemd[1]: Running in initrd.834host1 # [ 1.368880] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1835server # [ 1.407172] systemd[1]: Initializing machine ID from random generator.836server # [ 1.408934] systemd[1]: Hostname set to <server>.837host1 # [ 1.390288] 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)838host1 # [ 1.402801] systemd[1]: Detected virtualization qemu.839host1 # [ 1.404957] systemd[1]: Detected architecture arm64.840host1 # [ 1.406927] systemd[1]: Running in initrd.841host1 # [ 1.410039] systemd[1]: Initializing machine ID from random generator.842host1 # [ 1.413277] systemd[1]: Hostname set to <host1>.843server # [ 1.467686] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0844host1 # [ 1.465019] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0845server # [ 1.591386] usb 1-2: new high-speed USB device number 3 using ehci-pci846host1 # [ 1.588580] usb 1-2: new high-speed USB device number 3 using ehci-pci847server # [ 1.751968] systemd[1]: bpf-restrict-fs: LSM BPF program attached848server # [ 1.757009] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2849server # [ 1.757397] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0850host1 # [ 1.746936] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2851host1 # [ 1.756836] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0852host1 # [ 1.787687] systemd[1]: bpf-restrict-fs: LSM BPF program attached853server # [ 1.865072] systemd[1]: Queued start job for default target Initrd Default Target.854server # [ 1.874058] systemd[1]: Created slice Slice /system/modprobe.855server # [ 1.875284] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.856server # [ 1.876715] systemd[1]: Expecting device /dev/disk/by-label/nixos...857server # [ 1.877752] systemd[1]: Reached target Path Units.858server # [ 1.878545] systemd[1]: Reached target Slice Units.859server # [ 1.879381] systemd[1]: Reached target Swaps.860server # [ 1.880116] systemd[1]: Reached target Timer Units.861server # [ 1.881135] systemd[1]: Listening on D-Bus System Message Bus Socket.862server # [ 1.882383] systemd[1]: Listening on Journal Socket (/dev/log).863server # [ 1.883574] systemd[1]: Listening on Journal Sockets.864server # [ 1.884608] systemd[1]: Listening on udev Control Socket.865server # [ 1.885659] systemd[1]: Listening on udev Kernel Socket.866server # [ 1.886556] systemd[1]: Reached target Socket Units.867server # [ 1.889227] systemd[1]: Starting Create List of Static Device Nodes...868server # [ 1.896183] systemd[1]: Starting Load Kernel Module 9pnet_virtio...869server # [ 1.897246] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs870server # [ 1.908386] systemd[1]: Mounting Kernel Configuration File System...871host1 # [ 1.892188] systemd[1]: Queued start job for default target Initrd Default Target.872host1 # [ 1.903360] systemd[1]: Created slice Slice /system/modprobe.873server # [ 1.939638] systemd[1]: Starting Journal Service...874host1 # [ 1.904746] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.875host1 # [ 1.904792] systemd[1]: Expecting device /dev/disk/by-label/nixos...876host1 # [ 1.904824] systemd[1]: Reached target Path Units.877host1 # [ 1.904871] systemd[1]: Reached target Slice Units.878host1 # [ 1.904899] systemd[1]: Reached target Swaps.879host1 # [ 1.904921] systemd[1]: Reached target Timer Units.880server # [ 1.947573] systemd[1]: Starting Load Kernel Modules...881host1 # [ 1.905149] systemd[1]: Listening on D-Bus System Message Bus Socket.882host1 # [ 1.905372] systemd[1]: Listening on Journal Socket (/dev/log).883server # [ 1.948492] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os884host1 # [ 1.905582] systemd[1]: Listening on Journal Sockets.885host1 # [ 1.905771] systemd[1]: Listening on udev Control Socket.886host1 # [ 1.905937] systemd[1]: Listening on udev Kernel Socket.887host1 # [ 1.905963] systemd[1]: Reached target Socket Units.888host1 # [ 1.918689] systemd[1]: Starting Create List of Static Device Nodes...889host1 # [ 1.925945] systemd[1]: Starting Load Kernel Module 9pnet_virtio...890server # [ 1.960982] systemd[1]: Starting Coldplug All udev Devices...891host1 # [ 1.927208] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs892host1 # [ 1.938019] systemd[1]: Mounting Kernel Configuration File System...893server # [ 1.983623] systemd[1]: Finished Create List of Static Device Nodes.894server # [ 1.984862] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.895server # [ 1.985229] systemd[1]: Finished Load Kernel Module 9pnet_virtio.896server # [ 1.985545] systemd[1]: Mounted Kernel Configuration File System.897host1 # [ 1.967289] systemd[1]: Starting Journal Service...898server # [ 2.003750] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...899host1 # [ 1.976013] systemd[1]: Starting Load Kernel Modules...900host1 # [ 1.977037] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os901server # [ 2.012342] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.902server # [ 2.018094] systemd-journald[73]: Collecting audit messages is disabled.903host1 # [ 1.984793] systemd[1]: Starting Coldplug All udev Devices...904server # [ 2.027517] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev905host1 # [ 2.006374] systemd[1]: Finished Create List of Static Device Nodes.906host1 # [ 2.007372] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.907host1 # [ 2.007703] systemd[1]: Finished Load Kernel Module 9pnet_virtio.908host1 # [ 2.007987] systemd[1]: Mounted Kernel Configuration File System.909server # [ 2.042420] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0910server # [ 2.042690] [drm] features: -virgl +edid -resource_blob -host_visible911server # [ 2.042700] [drm] features: -context_init912server # [ 2.052898] [drm] number of scanouts: 1913server # [ 2.052933] [drm] number of cap sets: 0914server # [ 2.054631] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic915server # [ 2.054652] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0916host1 # [ 2.025755] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...917server # [ 2.080195] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.918host1 # [ 2.047190] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.919server # [ 2.087773] systemd[1]: Starting Create Static Device Nodes in /dev...920host1 # [ 2.061106] systemd-journald[73]: Collecting audit messages is disabled.921host1 # [ 2.068647] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev922host1 # [ 2.076981] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0923server # [ 2.111455] Console: switching to colour frame buffer device 160x50924host1 # [ 2.077259] [drm] features: -virgl +edid -resource_blob -host_visible925server # [ 2.112185] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device926host1 # [ 2.077268] [drm] features: -context_init927host1 # [ 2.078014] [drm] number of scanouts: 1928host1 # [ 2.078032] [drm] number of cap sets: 0929server # [ 2.125147] systemd[1]: Finished Load Kernel Modules.930host1 # [ 2.093328] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.931host1 # [ 2.096086] systemd[1]: Starting Create Static Device Nodes in /dev...932server # [ 2.131812] systemd[1]: Starting Apply Kernel Variables...933host1 # [ 2.105060] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic934host1 # [ 2.105081] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0935server # [ 2.163717] systemd[1]: Finished Create Static Device Nodes in /dev.936server # [ 2.164028] systemd[1]: Reached target Preparation for Local File Systems.937server # [ 2.164086] systemd[1]: Reached target Local File Systems.938host1 # [ 2.128900] Console: switching to colour frame buffer device 160x50939server # [ 2.169306] systemd[1]: Starting Rule-based Manager for Device Events and Files...940host1 # [ 2.162293] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device941host1 # [ 2.166539] systemd[1]: Finished Load Kernel Modules.942host1 # [ 2.169790] systemd[1]: Starting Apply Kernel Variables...943server # [ 2.205683] systemd[1]: Finished Apply Kernel Variables.944host1 # [ 2.188699] systemd[1]: Finished Create Static Device Nodes in /dev.945host1 # [ 2.189242] systemd[1]: Reached target Preparation for Local File Systems.946host1 # [ 2.189287] systemd[1]: Reached target Local File Systems.947server # [ 2.227515] systemd[1]: Started Journal Service.948host1 # [ 2.200971] systemd[1]: Starting Rule-based Manager for Device Events and Files...949server # [ 2.224363] systemd-modules-load[74]: Inserted module 'dm_mod'950server # [ 2.228474] systemd-modules-load[74]: Module 'virtio_balloon' is built in951server # [ 2.229607] systemd-modules-load[74]: Module 'virtio_console' is built in952server # [ 2.230688] systemd-modules-load[74]: Inserted module 'virtio_gpu'953server # [ 2.231677] systemd-modules-load[74]: Module 'virtio_rng' is built in954server # [ 2.244193] systemd[1]: Starting Create System Files and Directories...955server # [ 2.252459] systemd-udevd[80]: Using default interface naming scheme 'v261'.956host1 # [ 2.236865] systemd[1]: Finished Apply Kernel Variables.957server # [ 2.281450] systemd[1]: Finished Create System Files and Directories.958host1 # [ 2.264821] systemd[1]: Started Journal Service.959host1 # [ 2.256171] systemd-modules-load[74]: Inserted module 'dm_mod'960host1 # [ 2.260443] systemd-modules-load[74]: Module 'virtio_balloon' is built in961host1 # [ 2.261550] systemd-modules-load[74]: Module 'virtio_console' is built in962host1 # [ 2.262585] systemd-modules-load[74]: Inserted module 'virtio_gpu'963host1 # [ 2.263560] systemd-modules-load[74]: Module 'virtio_rng' is built in964server # [ 2.304913] systemd[1]: Started Rule-based Manager for Device Events and Files.965host1 # [ 2.282024] systemd[1]: Starting Create System Files and Directories...966host1 # [ 2.295061] systemd-udevd[81]: Using default interface naming scheme 'v261'.967host1 # [ 2.306874] systemd[1]: Finished Create System Files and Directories.968server # [ 2.358958] systemd[1]: Starting Virtual Console Setup...969host1 # [ 2.334003] systemd[1]: Started Rule-based Manager for Device Events and Files.970server # [ 2.420545] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.971server # [ 2.424373] systemd[1]: Finished Virtual Console Setup.972host1 # [ 2.398964] systemd[1]: Starting Virtual Console Setup...973host1 # [ 2.452505] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.974host1 # [ 2.456084] systemd[1]: Finished Virtual Console Setup.975server # [ 3.169824] systemd[1]: Finished Coldplug All udev Devices.976server # [ 3.170832] systemd[1]: Reached target System Initialization.977server # [ 3.171666] systemd[1]: Reached target Basic System.978host1 # [ 3.139317] systemd[1]: Finished Coldplug All udev Devices.979host1 # [ 3.140370] systemd[1]: Reached target System Initialization.980host1 # [ 3.141203] systemd[1]: Reached target Basic System.981server # [ 3.335527] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.982host1 # [ 3.327523] (udev-worker)[98]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.983server # [ 3.378843] (udev-worker)[93]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.984server # [ 3.383725] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.985host1 # [ 3.359306] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.986host1 # [ 3.362565] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line.987server # [ 3.471789] systemd[1]: Found device /dev/disk/by-label/nixos.988server # [ 3.474038] systemd[1]: Reached target Initrd Root Device.989host1 # [ 3.438203] systemd[1]: Found device /dev/disk/by-label/nixos.990server # [ 3.477077] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...991host1 # [ 3.447102] systemd[1]: Reached target Initrd Root Device.992host1 # [ 3.453235] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...993server # [ 3.529380] systemd-fsck[116]: nixos: clean, 12/65536 files, 13019/262144 blocks994server # [ 3.539171] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.995server # [ 3.544382] systemd[1]: Mounting /sysroot...996host1 # [ 3.510048] systemd-fsck[116]: nixos: clean, 12/65536 files, 13019/262144 blocks997host1 # [ 3.520178] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.998host1 # [ 3.524640] systemd[1]: Mounting /sysroot...999server # [ 3.603029] EXT4-fs (vda): mounted filesystem 4749a991-77d9-4a8f-8bad-67f06a2a49b3 r/w with ordered data mode. Quota mode: none.1000server # [ 3.593631] systemd[1]: Mounted /sysroot.1001server # [ 3.597156] systemd[1]: Reached target Initrd Root File System.1002server # [ 3.604125] systemd[1]: Starting Mountpoints Configured in the Real Root...1003host1 # [ 3.585051] EXT4-fs (vda): mounted filesystem 51e8bc18-1023-43e1-ae96-0c635174dbe2 r/w with ordered data mode. Quota mode: none.1004host1 # [ 3.577950] systemd[1]: Mounted /sysroot.1005host1 # [ 3.579855] systemd[1]: Reached target Initrd Root File System.1006host1 # [ 3.588125] systemd[1]: Starting Mountpoints Configured in the Real Root...1007server # [ 3.632387] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload.1008server # [ 3.637731] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)...1009server # [ 3.643394] systemd[1]: Reloading...1010host1 # [ 3.615331] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload.1011host1 # [ 3.623495] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)...1012host1 # [ 3.626252] systemd[1]: Reloading...1013server # [ 3.888198] systemd[1]: Reloading finished in 248 ms.1014host1 # [ 3.864145] systemd[1]: Reloading finished in 242 ms.1015server # [ 3.923416] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace...1016server # [ 3.927653] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace...1017server # [ 3.931031] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1018host1 # [ 3.897689] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace...1019server # [ 3.938512] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1020host1 # [ 3.904279] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace...1021server # [ 3.940689] systemd[1]: Finished Mountpoints Configured in the Real Root.1022server # [ 3.943038] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1023host1 # [ 3.907755] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1024host1 # [ 3.912831] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1025host1 # [ 3.923349] systemd[1]: Finished Mountpoints Configured in the Real Root.1026host1 # [ 3.927812] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1027server # [ 3.967340] systemd[1]: Mounting /sysroot/nix/.rw-store...1028server # [ 3.977934] systemd[1]: Mounting /sysroot/run...1029host1 # [ 3.944536] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1030host1 # [ 3.946769] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1031server # [ 3.992499] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1032server # [ 3.997255] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1033server # [ 4.020127] systemd[1]: Mounting /sysroot/nix/.ro-store...1034host1 # [ 3.999176] systemd[1]: Mounting /sysroot/nix/.ro-store...1035server # [ 4.040908] systemd[1]: Mounting /sysroot/tmp/shared...1036host1 # [ 4.015519] systemd[1]: Mounting /sysroot/nix/.rw-store...1037host1 # [ 4.020085] systemd[1]: Mounting /sysroot/run...1038host1 # [ 4.029890] systemd[1]: Mounting /sysroot/tmp/shared...1039server # [ 4.073448] systemd[1]: Mounting /sysroot/tmp/xchg...1040server # [ 4.079193] systemd[1]: Mounted /sysroot/nix/.rw-store.1041server # [ 4.080153] systemd[1]: Mounted /sysroot/run.1042host1 # [ 4.062164] systemd[1]: Mounting /sysroot/tmp/xchg...1043server # [ 4.111996] systemd[1]: Starting rw-sysroot-nix-store.service...1044server # [ 4.114000] systemd[1]: Mounted /sysroot/nix/.ro-store.1045host1 # [ 4.109458] systemd[1]: Mounted /sysroot/nix/.rw-store.1046host1 # [ 4.119585] systemd[1]: Mounted /sysroot/nix/.ro-store.1047server # [ 4.155512] systemd[1]: Mounted /sysroot/tmp/shared.1048server # [ 4.158450] systemd[1]: Mounted /sysroot/tmp/xchg.1049server # [ 4.168187] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1050server # [ 4.169485] systemd[1]: Finished rw-sysroot-nix-store.service.1051host1 # [ 4.135012] systemd[1]: Mounted /sysroot/run.1052host1 # [ 4.139714] systemd[1]: Mounted /sysroot/tmp/shared.1053host1 # [ 4.147254] systemd[1]: Mounted /sysroot/tmp/xchg.1054host1 # [ 4.157358] systemd[1]: Starting rw-sysroot-nix-store.service...1055host1 # [ 4.187363] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1056host1 # [ 4.188999] systemd[1]: Finished rw-sysroot-nix-store.service.1057host1 # [ 4.834155] (udev-worker)[93]: mtd0ro: Failed to find and pin callout binary "/nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/lib/udev/mtd_probe": No such file or directory1058host1 # [ 4.840160] (udev-worker)[93]: mtd0ro: /nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1059server # [ 4.888762] (udev-worker)[100]: mtd0ro: Failed to find and pin callout binary "/nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/lib/udev/mtd_probe": No such file or directory1060server # [ 4.892098] (udev-worker)[100]: mtd0ro: /nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1061host1 # [ 4.866203] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1062host1 # [ 4.867302] systemd[1]: Stopped Virtual Console Setup.1063host1 # [ 4.873839] systemd[1]: Stopping Virtual Console Setup...1064host1 # [ 4.874683] systemd[1]: Starting Virtual Console Setup...1065server # [ 4.919183] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1066server # [ 4.921560] systemd[1]: Stopped Virtual Console Setup.1067server # [ 4.922950] systemd[1]: Stopping Virtual Console Setup...1068host1 # [ 4.888759] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1069host1 # [ 4.891242] systemd[1]: Stopped Virtual Console Setup.1070server # [ 4.927545] systemd[1]: Starting Virtual Console Setup...1071host1 # [ 4.892440] systemd[1]: Starting Virtual Console Setup...1072host1 # [ 4.913684] systemd-vconsole-setup[159]: Configuration of first virtual console was skipped, ignoring remaining ones.1073host1 # [ 4.916974] systemd[1]: Finished Virtual Console Setup.1074server # [ 4.955915] systemd[1]: Mounting /sysroot/nix/store...1075server # [ 4.960512] systemd-vconsole-setup[158]: Configuration of first virtual console was skipped, ignoring remaining ones.1076server # [ 4.964108] systemd[1]: Finished Virtual Console Setup.1077server # [ 4.965770] systemd[1]: sysroot-run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully.1078server # [ 4.985884] systemd[1]: Mounted /sysroot/nix/store.1079server # [ 4.987336] systemd[1]: Reached target Initrd File Systems.1080server # [ 4.989819] systemd[1]: Starting Find NixOS closure...1081server # [ 4.996527] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1082server # [ 5.022386] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1083server # [ 5.024604] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1084host1 # [ 4.996995] systemd[1]: Mounting /sysroot/nix/store...1085server # [ 5.036791] systemd[1]: Finished Find NixOS closure.1086server # [ 5.038781] systemd[1]: Reached target Initrd Default Target.1087server # [ 5.039958] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1088server # [ 5.063943] systemd[1]: Stopped target Initrd Default Target.1089server # [ 5.065447] systemd[1]: Stopped target Basic System.1090server # [ 5.068434] systemd[1]: Stopped target Initrd Root Device.1091server # [ 5.069289] systemd[1]: Stopped target Path Units.1092server # [ 5.069981] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1093server # [ 5.072110] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1094server # [ 5.074838] systemd[1]: Stopped target Slice Units.1095host1 # [ 5.042996] systemd[1]: Mounted /sysroot/nix/store.1096server # [ 5.079526] systemd[1]: Stopped target Socket Units.1097server # [ 5.080402] systemd[1]: Stopped target System Initialization.1098server # [ 5.081335] systemd[1]: Stopped target Swaps.1099server # [ 5.082000] systemd[1]: Stopped target Timer Units.1100host1 # [ 5.045495] systemd[1]: Reached target Initrd File Systems.1101server # [ 5.082708] systemd[1]: dbus.socket: Deactivated successfully.1102host1 # [ 5.049531] systemd[1]: Starting Find NixOS closure...1103server # [ 5.088909] systemd[1]: Closed D-Bus System Message Bus Socket.1104host1 # [ 5.056985] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1105server # [ 5.094403] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1106server # [ 5.100995] systemd[1]: Stopped Find NixOS closure.1107server # [ 5.102974] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1108server # [ 5.104984] systemd[1]: Starting rw-sysroot-nix-store.service...1109server # [ 5.113141] systemd[1]: systemd-sysctl.service: Deactivated successfully.1110server # [ 5.116176] systemd[1]: Stopped Apply Kernel Variables.1111server # [ 5.119857] systemd[1]: systemd-modules-load.service: Deactivated successfully.1112server # [ 5.124439] systemd[1]: Stopped Load Kernel Modules.1113server # [ 5.126909] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1114server # [ 5.128608] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1115server # [ 5.129700] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1116server # [ 5.130690] systemd[1]: Stopped Create System Files and Directories.1117server # [ 5.131563] systemd[1]: Stopped target Local File Systems.1118server # [ 5.136309] systemd[1]: Stopped target Preparation for Local File Systems.1119host1 # [ 5.101023] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1120server # [ 5.137624] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1121server # [ 5.140316] systemd[1]: Stopped Coldplug All udev Devices.1122host1 # [ 5.104340] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1123server # [ 5.141614] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1124server # [ 5.143925] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1125server # [ 5.146534] systemd[1]: Stopped Virtual Console Setup.1126server # [ 5.148466] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1127server # [ 5.151939] systemd[1]: Finished rw-sysroot-nix-store.service.1128server # [ 5.153074] systemd[1]: initrd-cleanup.service: Deactivated successfully.1129server # [ 5.153997] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1130host1 # [ 5.118582] systemd[1]: Finished Find NixOS closure.1131server # [ 5.155096] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1132server # [ 5.156193] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1133server # [ 5.157048] systemd[1]: systemd-udevd.service: Deactivated successfully.1134host1 # [ 5.121402] systemd[1]: Reached target Initrd Default Target.1135server # [ 5.157960] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1136host1 # [ 5.122983] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1137server # [ 5.158947] systemd[1]: systemd-udevd.service: Consumed 1.574s CPU time over 2.985s wall clock time, 27.4M memory peak.1138server # [ 5.164258] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1139server # [ 5.165365] systemd[1]: Closed udev Control Socket.1140server # [ 5.168388] systemd[1]: Starting Cleanup udev Database...1141server # [ 5.169347] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1142server # [ 5.172271] systemd[1]: Stopped Create Static Device Nodes in /dev.1143server # [ 5.173209] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1144server # [ 5.176120] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1145server # [ 5.177136] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1146server # [ 5.180169] systemd[1]: Stopped Create List of Static Device Nodes.1147host1 # [ 5.151950] systemd[1]: Stopped target Initrd Default Target.1148host1 # [ 5.156418] systemd[1]: Stopped target Basic System.1149host1 # [ 5.157447] systemd[1]: Stopped target Initrd Root Device.1150host1 # [ 5.158423] systemd[1]: Stopped target Path Units.1151host1 # [ 5.160138] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1152host1 # [ 5.163660] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1153host1 # [ 5.168222] systemd[1]: Stopped target Slice Units.1154host1 # [ 5.169108] systemd[1]: Stopped target Socket Units.1155host1 # [ 5.169900] systemd[1]: Stopped target System Initialization.1156host1 # [ 5.170777] systemd[1]: Stopped target Swaps.1157host1 # [ 5.171472] systemd[1]: Stopped target Timer Units.1158server # [ 5.207251] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1159host1 # [ 5.174360] systemd[1]: dbus.socket: Deactivated successfully.1160server # [ 5.212644] systemd[1]: Finished Cleanup udev Database.1161server # [ 5.213411] systemd[1]: Reached target Switch Root.1162server # [ 5.214101] systemd[1]: Starting NixOS Activation...1163host1 # [ 5.178905] systemd[1]: Closed D-Bus System Message Bus Socket.1164host1 # [ 5.179870] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1165host1 # [ 5.181223] systemd[1]: Stopped Find NixOS closure.1166host1 # [ 5.182697] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1167host1 # [ 5.185626] systemd[1]: Starting rw-sysroot-nix-store.service...1168host1 # [ 5.200337] systemd[1]: systemd-sysctl.service: Deactivated successfully.1169host1 # [ 5.202844] systemd[1]: Stopped Apply Kernel Variables.1170host1 # [ 5.204958] systemd[1]: systemd-modules-load.service: Deactivated successfully.1171host1 # [ 5.210322] systemd[1]: Stopped Load Kernel Modules.1172host1 # [ 5.213042] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1173host1 # [ 5.218879] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1174host1 # [ 5.221981] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1175host1 # [ 5.225679] systemd[1]: Stopped Create System Files and Directories.1176host1 # [ 5.227503] systemd[1]: Stopped target Local File Systems.1177host1 # [ 5.230863] systemd[1]: Stopped target Preparation for Local File Systems.1178host1 # [ 5.231855] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1179host1 # [ 5.236296] systemd[1]: Stopped Coldplug All udev Devices.1180host1 # [ 5.237157] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1181host1 # [ 5.238183] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1182host1 # [ 5.240406] systemd[1]: Stopped Virtual Console Setup.1183host1 # [ 5.241891] systemd[1]: initrd-cleanup.service: Deactivated successfully.1184host1 # [ 5.242930] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1185host1 # [ 5.243843] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1186host1 # [ 5.245021] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1187host1 # [ 5.245865] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1188host1 # [ 5.246864] systemd[1]: Finished rw-sysroot-nix-store.service.1189host1 # [ 5.247676] systemd[1]: systemd-udevd.service: Deactivated successfully.1190host1 # [ 5.252289] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1191host1 # [ 5.253374] systemd[1]: systemd-udevd.service: Consumed 1.549s CPU time over 3.044s wall clock time, 26.9M memory peak.1192host1 # [ 5.256437] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1193host1 # [ 5.260198] systemd[1]: Closed udev Control Socket.1194host1 # [ 5.260996] systemd[1]: Starting Cleanup udev Database...1195host1 # [ 5.264119] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1196host1 # [ 5.265204] systemd[1]: Stopped Create Static Device Nodes in /dev.1197host1 # [ 5.266066] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1198host1 # [ 5.268148] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1199host1 # [ 5.272263] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1200host1 # [ 5.273277] systemd[1]: Stopped Create List of Static Device Nodes.1201host1 # [ 5.295803] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1202host1 # [ 5.299137] systemd[1]: Finished Cleanup udev Database.1203host1 # [ 5.300468] systemd[1]: Reached target Switch Root.1204host1 # [ 5.304461] systemd[1]: Starting NixOS Activation...1205server # [ 5.372830] initrd-nixos-activation-start[184]: booting system configuration /nix/store/4sgbb71nw078lpy3940kgcqaqc98ppqm-nixos-system-server-test1206server # [ 5.436551] initrd-nixos-activation-start[184]: running activation script...1207host1 # [ 5.466947] initrd-nixos-activation-start[184]: booting system configuration /nix/store/gs9wxvhqnagxz34vl4cmjirjiipkv6ss-nixos-system-host1-test1208host1 # [ 5.531855] initrd-nixos-activation-start[184]: running activation script...1209server # [ 5.841504] initrd-nixos-activation-start[207]: setting up /etc...1210host1 # [ 5.935786] initrd-nixos-activation-start[207]: setting up /etc...1211server # [ 6.095291] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1212server # [ 6.098113] systemd[1]: Finished NixOS Activation.1213server # [ 6.100093] systemd[1]: Starting Switch Root...1214server # [ 6.115435] systemd[1]: Switching root.1215host1 # [ 6.179588] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1216host1 # [ 6.182464] systemd[1]: Finished NixOS Activation.1217host1 # [ 6.183620] systemd[1]: Starting Switch Root...1218host1 # [ 6.207881] systemd[1]: Switching root.1219server # [ 6.306597] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1220host1 # [ 6.399999] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1221server # [ 6.906719] 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)1222server # [ 6.919234] systemd[1]: Detected virtualization qemu.1223server # [ 6.923122] systemd[1]: Detected architecture arm64.1224server # [ 6.925376] systemd[1]: Detected first boot.1225server # [ 6.932574] systemd[1]: Initializing machine ID from random generator.1226host1 # [ 6.977738] 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)1227host1 # [ 6.990074] systemd[1]: Detected virtualization qemu.1228host1 # [ 6.994132] systemd[1]: Detected architecture arm64.1229host1 # [ 6.996209] systemd[1]: Detected first boot.1230host1 # [ 7.003366] systemd[1]: Initializing machine ID from random generator.1231server # [ 7.267573] systemd[1]: bpf-restrict-fs: LSM BPF program attached1232host1 # [ 7.336491] systemd[1]: bpf-restrict-fs: LSM BPF program attached1233server # [ 7.449552] systemd[1]: Applying preset policy.1234host1 # [ 7.520383] systemd[1]: Applying preset policy.1235server # [ 7.905048] systemd[1]: Populated /etc with preset unit settings.1236host1 # [ 7.999347] systemd[1]: Populated /etc with preset unit settings.1237server # [ 8.423855] systemd[1]: initrd-switch-root.service: Deactivated successfully.1238server # [ 8.425172] systemd[1]: Stopped initrd-switch-root.service.1239server # [ 8.428969] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1240server # [ 8.432882] systemd[1]: Created slice Slice /system/getty.1241server # [ 8.434903] systemd[1]: Created slice User and Session Slice.1242server # [ 8.436280] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1243server # [ 8.438674] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1244server # [ 8.440127] systemd[1]: Expecting device /dev/hvc0...1245server # [ 8.441439] systemd[1]: Expecting device /dev/ttyAMA0...1246server # [ 8.442833] systemd[1]: Reached target Local Encrypted Volumes.1247server # [ 8.444402] systemd[1]: Stopped target initrd-fs.target.1248server # [ 8.445741] systemd[1]: Stopped target initrd-root-fs.target.1249server # [ 8.447159] systemd[1]: Stopped target initrd-switch-root.target.1250server # [ 8.448644] systemd[1]: Reached target Virtual Machines and Containers.1251server # [ 8.450174] systemd[1]: Reached target Path Units.1252server # [ 8.451514] systemd[1]: Reached target Remote File Systems.1253server # [ 8.452918] systemd[1]: Reached target Slice Units.1254server # [ 8.454248] systemd[1]: Reached target Swaps.1255server # [ 8.458361] systemd[1]: Listening on Query the User Interactively for a Password.1256server # [ 8.463177] systemd[1]: Listening on Process Core Dump Socket.1257server # [ 8.467007] systemd[1]: Listening on Credential Encryption/Decryption.1258server # [ 8.470856] systemd[1]: Listening on Factory Reset Management.1259server # [ 8.471266] systemd[1]: Listening on Hostname Service Socket.1260server # [ 8.477197] systemd[1]: Starting Journal Log Access Socket...1261server # [ 8.479259] systemd[1]: Listening on Journal Audit Socket.1262server # [ 8.483631] systemd[1]: Listening on Console Output Muting Service Socket.1263server # [ 8.485254] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1264server # [ 8.487476] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1265server # [ 8.490232] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1266server # [ 8.500375] systemd[1]: Listening on Disk Repartitioning Service Socket.1267server # [ 8.500806] systemd[1]: Listening on udev Control Socket.1268server # [ 8.501161] systemd[1]: Listening on udev Varlink Socket.1269server # [ 8.507422] systemd[1]: Mounting Huge Pages File System...1270server # [ 8.511811] systemd[1]: Mounting POSIX Message Queue File System...1271server # [ 8.524519] systemd[1]: Mounting Kernel Debug File System...1272server # [ 8.537511] systemd[1]: Mounting Kernel Trace File System...1273host1 # [ 8.507186] systemd[1]: initrd-switch-root.service: Deactivated successfully.1274host1 # [ 8.508462] systemd[1]: Stopped initrd-switch-root.service.1275server # [ 8.543973] systemd[1]: Starting Create List of Static Device Nodes...1276host1 # [ 8.511976] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1277host1 # [ 8.515875] systemd[1]: Created slice Slice /system/getty.1278host1 # [ 8.517984] systemd[1]: Created slice User and Session Slice.1279host1 # [ 8.519769] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1280host1 # [ 8.521287] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1281host1 # [ 8.522633] systemd[1]: Expecting device /dev/hvc0...1282server # [ 8.554916] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1283host1 # [ 8.523748] systemd[1]: Expecting device /dev/ttyAMA0...1284server # [ 8.555267] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1285host1 # [ 8.524997] systemd[1]: Reached target Local Encrypted Volumes.1286host1 # [ 8.526213] systemd[1]: Stopped target initrd-fs.target.1287host1 # [ 8.527325] systemd[1]: Stopped target initrd-root-fs.target.1288host1 # [ 8.528482] systemd[1]: Stopped target initrd-switch-root.target.1289host1 # [ 8.529727] systemd[1]: Reached target Virtual Machines and Containers.1290host1 # [ 8.531432] systemd[1]: Reached target Path Units.1291host1 # [ 8.532917] systemd[1]: Reached target Remote File Systems.1292server # [ 8.567001] systemd[1]: Mounting Kernel Configuration File System...1293host1 # [ 8.534436] systemd[1]: Reached target Slice Units.1294host1 # [ 8.535878] systemd[1]: Reached target Swaps.1295server # [ 8.569869] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1296host1 # [ 8.539401] systemd[1]: Listening on Query the User Interactively for a Password.1297server # [ 8.575694] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1298host1 # [ 8.543674] systemd[1]: Listening on Process Core Dump Socket.1299host1 # [ 8.547129] systemd[1]: Listening on Credential Encryption/Decryption.1300server # [ 8.581702] systemd[1]: Starting Load Kernel Module fuse...1301host1 # [ 8.550665] systemd[1]: Listening on Factory Reset Management.1302host1 # [ 8.552392] systemd[1]: Listening on Hostname Service Socket.1303server # [ 8.586456] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671304host1 # [ 8.557421] systemd[1]: Starting Journal Log Access Socket...1305host1 # [ 8.559948] systemd[1]: Listening on Journal Audit Socket.1306host1 # [ 8.563635] systemd[1]: Listening on Console Output Muting Service Socket.1307host1 # [ 8.565137] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1308server # [ 8.600746] systemd[1]: Starting Journal Service...1309host1 # [ 8.567330] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1310host1 # [ 8.569647] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1311host1 # [ 8.581051] systemd[1]: Listening on Disk Repartitioning Service Socket.1312host1 # [ 8.582453] systemd[1]: Listening on udev Control Socket.1313host1 # [ 8.584005] systemd[1]: Listening on udev Varlink Socket.1314host1 # [ 8.588325] systemd[1]: Mounting Huge Pages File System...1315host1 # [ 8.593588] systemd[1]: Mounting POSIX Message Queue File System...1316host1 # [ 8.605262] systemd[1]: Mounting Kernel Debug File System...1317host1 # [ 8.610930] systemd[1]: Mounting Kernel Trace File System...1318host1 # [ 8.621987] systemd[1]: Starting Create List of Static Device Nodes...1319server # [ 8.663801] systemd[1]: Starting Load Kernel Modules...1320host1 # [ 8.636436] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1321host1 # [ 8.637993] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1322server # [ 8.684902] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1323host1 # [ 8.657109] systemd[1]: Mounting Kernel Configuration File System...1324host1 # [ 8.663302] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1325host1 # [ 8.666723] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1326server # [ 8.709065] systemd[1]: Starting Remount Root and Kernel File Systems...1327server # [ 8.711756] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1328server # [ 8.723763] systemd[1]: Starting Coldplug All udev Devices...1329server # [ 8.726337] systemd[1]: Listening on Journal Log Access Socket.1330server # [ 8.726747] systemd[1]: Mounted Huge Pages File System.1331server # [ 8.727141] systemd[1]: Mounted POSIX Message Queue File System.1332server # [ 8.735690] systemd[1]: Mounted Kernel Debug File System.1333server # [ 8.737762] systemd[1]: Mounted Kernel Trace File System.1334server # [ 8.743325] systemd[1]: Mounted Kernel Configuration File System.1335host1 # [ 8.733402] systemd[1]: Starting Load Kernel Module fuse...1336host1 # [ 8.743990] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671337server # [ 8.801652] systemd[1]: Finished Create List of Static Device Nodes.1338host1 # [ 8.772787] systemd[1]: Starting Journal Service...1339server # [ 8.810721] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1340host1 # [ 8.790298] systemd[1]: Starting Load Kernel Modules...1341server # [ 8.848605] EXT4-fs (vda): re-mounted 4749a991-77d9-4a8f-8bad-67f06a2a49b3.1342host1 # [ 8.815979] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1343server # [ 8.855458] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1344host1 # [ 8.823760] fuse: init (API version 7.45)1345server # [ 8.857975] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1346host1 # [ 8.828937] systemd[1]: Starting Remount Root and Kernel File Systems...1347host1 # [ 8.832047] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1348server # [ 8.872727] systemd[1]: Finished Remount Root and Kernel File Systems.1349server # [ 8.875010] systemd[1]: Listening on Disk Image Download Service Socket.1350server # [ 8.877058] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1351server # [ 8.885261] systemd[1]: Starting Load/Save OS Random Seed...1352server # [ 8.887592] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1353host1 # [ 8.854908] systemd[1]: Starting Coldplug All udev Devices...1354server # [ 8.892042] systemd[1]: Finished Load Kernel Modules.1355host1 # [ 8.863431] systemd[1]: Listening on Journal Log Access Socket.1356server # [ 8.899896] systemd[1]: Starting Firewall...1357server # [ 8.915643] systemd[1]: Starting Apply Kernel Variables...1358server # [ 8.918803] systemd-journald[278]: Collecting audit messages is enabled.1359host1 # [ 8.884515] systemd[1]: Mounted Huge Pages File System.1360host1 # [ 8.892946] systemd[1]: Mounted POSIX Message Queue File System.1361host1 # [ 8.895698] systemd-journald[278]: Collecting audit messages is enabled.1362host1 # [ 8.898903] systemd[1]: Mounted Kernel Debug File System.1363host1 # [ 8.902520] systemd[1]: Mounted Kernel Trace File System.1364host1 # [ 8.911582] systemd[1]: Finished Create List of Static Device Nodes.1365host1 # [ 8.912333] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1366server # [ 8.949770] fuse: init (API version 7.45)1367host1 # [ 8.916049] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1368host1 # [ 8.918714] systemd[1]: Mounted Kernel Configuration File System.1369host1 # [ 8.919362] systemd[1]: modprobe@fuse.service: Deactivated successfully.1370host1 # [ 8.927774] systemd[1]: Finished Load Kernel Module fuse.1371host1 # [ 8.931109] systemd[1]: Finished Load Kernel Modules.1372server # [ 8.958812] systemd[1]: Queued start job for default target Multi-User System.1373server # [ 8.975261] systemd[1]: Started Journal Service.1374host1 # [ 8.925465] systemd[1]: Queued start job for default target Multi-User System.1375host1 # [ 8.928714] systemd[1]: systemd-journald.service: Deactivated successfully.1376host1 # [ 8.947910] systemd[1]: Mounting FUSE Control File System...1377server # [ 8.971416] systemd[1]: systemd-journald.service: Deactivated successfully.1378host1 # [ 8.938615] systemd-modules-load[279]: Module 'atkbd' is built in1379host1 # [ 8.939607] systemd-modules-load[279]: Module 'loop' is built in1380host1 # [ 8.966654] systemd[1]: Starting Firewall...1381server # [ 8.986742] systemd-modules-load[279]: Module 'atkbd' is built in1382server # [ 8.996247] systemd-modules-load[279]: Module 'loop' is built in1383host1 # [ 8.984639] EXT4-fs (vda): re-mounted 51e8bc18-1023-43e1-ae96-0c635174dbe2.1384host1 # [ 8.987074] systemd[1]: Starting Apply Kernel Variables...1385server # [ 9.012637] systemd[1]: Starting Flush Journal to Persistent Storage...1386server # [ 9.018206] systemd[1]: modprobe@fuse.service: Deactivated successfully.1387server # [ 9.025220] systemd[1]: Finished Load Kernel Module fuse.1388server # [ 9.036456] systemd[1]: Finished Load/Save OS Random Seed.1389server # [ 9.039363] systemd[1]: Reached target First Boot Complete.1390host1 # [ 9.028217] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1391server # [ 9.049744] systemd-oomd[280]: No swap; memory pressure usage will be degraded1392server # [ 9.061603] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1393host1 # [ 9.047996] systemd[1]: Started Journal Service.1394host1 # [ 9.035787] systemd-oomd[280]: No swap; memory pressure usage will be degraded1395server # [ 9.082665] systemd[1]: Finished Apply Kernel Variables.1396server # [ 9.102241] systemd-journald[278]: Received client request to flush runtime journal.1397host1 # [ 9.060707] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1398host1 # [ 9.073993] systemd[1]: Finished Remount Root and Kernel File Systems.1399server # [ 9.156868] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1400host1 # [ 9.125117] systemd[1]: Listening on Disk Image Download Service Socket.1401server # [ 9.174432] systemd[1]: Starting Create Static Device Nodes in /dev...1402host1 # [ 9.142644] systemd[1]: Starting Flush Journal to Persistent Storage...1403host1 # [ 9.146238] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1404server # [ 9.184665] systemd[1]: Finished Flush Journal to Persistent Storage.1405host1 # [ 9.157023] systemd[1]: Starting Load/Save OS Random Seed...1406host1 # [ 9.159504] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1407host1 # [ 9.169732] systemd[1]: Mounted FUSE Control File System.1408host1 # [ 9.177395] systemd[1]: Finished Apply Kernel Variables.1409host1 # [ 9.222082] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1410host1 # [ 9.230971] systemd[1]: Starting Create Static Device Nodes in /dev...1411server # [ 9.279118] systemd[1]: Finished Create Static Device Nodes in /dev.1412server # [ 9.280295] systemd[1]: Reached target Preparation for Local File Systems.1413server # [ 9.283262] systemd[1]: Starting Rule-based Manager for Device Events and Files...1414host1 # [ 9.268874] systemd-journald[278]: Received client request to flush runtime journal.1415host1 # [ 9.325557] systemd[1]: Finished Load/Save OS Random Seed.1416host1 # [ 9.328992] systemd[1]: Reached target First Boot Complete.1417host1 # [ 9.333915] systemd[1]: Finished Flush Journal to Persistent Storage.1418server # [ 9.396683] systemd-udevd[319]: Using default interface naming scheme 'v261'.1419host1 # [ 9.362842] systemd[1]: Finished Create Static Device Nodes in /dev.1420host1 # [ 9.363952] systemd[1]: Reached target Preparation for Local File Systems.1421host1 # [ 9.366760] systemd[1]: Starting Rule-based Manager for Device Events and Files...1422server # [ 9.413107] systemd[1]: Mounting /run/wrappers...1423server # [ 9.426463] systemd[1]: Mounting FUSE Control File System...1424server # [ 9.464849] systemd[1]: Mounted FUSE Control File System.1425server # [ 9.487002] systemd[1]: Mounted /run/wrappers.1426server # [ 9.491268] systemd[1]: Reached target Local File Systems.1427server # [ 9.492497] systemd[1]: Listening on Boot Loader Control Service Socket.1428server # [ 9.495180] systemd[1]: Starting register-nix-paths.service...1429server # [ 9.500380] systemd[1]: Starting Create SUID/SGID Wrappers...1430server # [ 9.507505] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1431server # [ 9.523662] systemd[1]: Starting Save Transient machine-id to Disk...1432server # [ 9.525634] systemd[1]: Starting Create System Files and Directories...1433host1 # [ 9.492468] systemd-udevd[321]: Using default interface naming scheme 'v261'.1434host1 # [ 9.499383] systemd[1]: Mounting /run/wrappers...1435host1 # [ 9.551855] systemd[1]: Mounted /run/wrappers.1436host1 # [ 9.557311] systemd[1]: Reached target Local File Systems.1437host1 # [ 9.563019] systemd[1]: Listening on Boot Loader Control Service Socket.1438host1 # [ 9.566574] systemd[1]: Starting register-nix-paths.service...1439host1 # [ 9.583790] systemd[1]: Starting Create SUID/SGID Wrappers...1440host1 # [ 9.589166] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1441server # [ 9.628375] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1442server # [ 9.638705] systemd[1]: Finished Save Transient machine-id to Disk.1443host1 # [ 9.612223] systemd[1]: Starting Save Transient machine-id to Disk...1444host1 # [ 9.615801] systemd[1]: Starting Create System Files and Directories...1445server # [ 9.727833] systemd[1]: Started Rule-based Manager for Device Events and Files.1446host1 # [ 9.723763] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1447server # [ 9.766318] systemd[1]: Finished Create System Files and Directories.1448server # [ 9.771822] systemd[1]: Starting Rebuild Journal Catalog...1449host1 # [ 9.739683] systemd[1]: Finished Save Transient machine-id to Disk.1450server # [ 9.784963] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1451host1 # [ 9.789155] systemd[1]: Started Rule-based Manager for Device Events and Files.1452host1 # [ 9.841840] systemd[1]: Finished Create System Files and Directories.1453server # [ 9.895542] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1454host1 # [ 9.868384] systemd[1]: Starting Rebuild Journal Catalog...1455host1 # [ 9.878611] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1456server # [ 9.966468] systemd[1]: Finished Rebuild Journal Catalog.1457server # [ 9.971940] systemd[1]: Starting Update is Completed...1458host1 # [ 9.991689] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1459server # [ 10.046823] systemd[1]: Finished Update is Completed.1460host1 # [ 10.062420] systemd[1]: Finished Rebuild Journal Catalog.1461host1 # [ 10.074698] systemd[1]: Starting Update is Completed...1462host1 # [ 10.139226] systemd[1]: Finished Update is Completed.1463server # [ 10.472860] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1464server # [ 10.474346] systemd[1]: Finished Create SUID/SGID Wrappers.1465host1 # [ 10.529213] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1466host1 # [ 10.534295] systemd[1]: Finished Create SUID/SGID Wrappers.1467server # [ 10.976401] systemd[1]: Finished Firewall.1468host1 # [ 11.057069] systemd[1]: Finished Firewall.1469host1 # [ 11.070782] systemd[1]: Finished Coldplug All udev Devices.1470server # [ 11.110373] systemd[1]: Finished Coldplug All udev Devices.1471server # [ 11.170345] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1472host1 # [ 11.142794] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1473host1 # [ 11.301601] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1474server # [ 11.354645] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1475host1 # [ 11.357382] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1476server # [ 11.415790] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1477server # [ 11.440283] systemd[1]: Finished register-nix-paths.service.1478server # [ 11.441742] systemd[1]: Reached target System Initialization.1479server # [ 11.443844] systemd[1]: Started Renew ACME Certificate for server.1480server # [ 11.446075] systemd[1]: Started Discard unused filesystem blocks once a week.1481server # [ 11.448215] systemd[1]: Started Daily Cleanup of Temporary Directories.1482server # [ 11.453781] systemd[1]: Reached target Timer Units.1483server # [ 11.455536] systemd[1]: Listening on D-Bus System Message Bus Socket.1484server # [ 11.462672] systemd[1]: Listening on Nix Daemon Socket.1485server # [ 11.468364] systemd[1]: Listening on Nix worker-protocol over gRPC.1486server # [ 11.477630] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1487server # [ 11.489297] systemd[1]: Reached target Socket Units.1488server # [ 11.498606] systemd[1]: Reached target Basic System.1489host1 # [ 11.462935] systemd[1]: Finished register-nix-paths.service.1490host1 # [ 11.464290] systemd[1]: Reached target System Initialization.1491host1 # [ 11.466250] systemd[1]: Started Renew ACME Certificate for host1.1492server # [ 11.505195] systemd[1]: Starting Set up the ACME certificate renewal infrastructure...1493host1 # [ 11.471355] systemd[1]: Started Discard unused filesystem blocks once a week.1494host1 # [ 11.473473] systemd[1]: Started Daily Cleanup of Temporary Directories.1495host1 # [ 11.475266] systemd[1]: Reached target Timer Units.1496server # [ 11.514581] systemd[1]: Started backdoor.service.1497host1 # [ 11.477228] systemd[1]: Listening on D-Bus System Message Bus Socket.1498host1 # [ 11.481304] systemd[1]: Listening on Nix Daemon Socket.1499server # [ 11.521161] systemd[1]: Starting Import lastlog data into lastlog2 database...1500host1 # [ 11.483053] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1501host1 # [ 11.488287] systemd[1]: Reached target Socket Units.1502host1 # [ 11.490009] systemd[1]: Reached target Basic System.1503host1 # [ 11.496338] systemd[1]: Starting Set up the ACME certificate renewal infrastructure...1504host1 # [ 11.500996] systemd[1]: Started backdoor.service.1505server # [ 11.537067] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1506host1 # [ 11.504367] systemd[1]: Starting Import lastlog data into lastlog2 database...1507server # [ 11.549531] systemd[1]: Starting Post-Boot Actions...1508host1 # [ 11.523780] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1509server # [ 11.560480] systemd[1]: Started Reset console on configuration changes.1510server # [ 11.572707] systemd[1]: Starting resolvconf update...1511host1 # [ 11.544258] systemd[1]: Starting Post-Boot Actions...1512server # [ 11.583961] systemd[1]: Starting step-ca-pki.service...1513server # [ 11.596192] systemd[1]: Starting D-Bus System Message Bus...1514host1 # [ 11.559739] systemd[1]: Started Reset console on configuration changes.1515host1 # [ 11.608525] systemd[1]: Starting resolvconf update...1516server # [ 11.651323] systemd[1]: Finished Post-Boot Actions.1517host1 # connecting to host...1518server # [ 11.693227] acme-setup-privileged[507]: + set -euo pipefail1519server # [ 11.701225] acme-setup-privileged[507]: + cd /var/lib/acme1520server # [ 11.706746] acme-setup-privileged[507]: + chmod -R u=rwX,g=,o= .lego/accounts1521host1 # [ 11.675684] acme-setup-privileged[501]: + set -euo pipefail1522server # [ 11.720354] systemd[1]: Started Name Service Cache Daemon (nsncd).1523host1 # [ 11.689933] acme-setup-privileged[501]: + cd /var/lib/acme1524host1 # [ 11.690741] acme-setup-privileged[501]: + chmod -R u=rwX,g=,o= .lego/accounts1525host1 # [ 11.701078] acme-setup-privileged[501]: + chown -R acme .lego/accounts1526server # [ 11.730850] nsncd[510]: Sep 21 13:48:41.262 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1527server # [ 11.750504] acme-setup-privileged[507]: + chown -R acme .lego/accounts1528server # [ 11.751419] systemd[1]: Reached target Host and Network Name Lookups.1529host1 # [ 11.714520] nsncd[504]: Sep 21 13:48:41.261 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1530server # connecting to host...1531host1 # [ 11.727339] systemd[1]: Started Name Service Cache Daemon (nsncd).1532host1 # [ 11.732843] acme-setup-privileged[501]: + for fixpath in host1 .lego/host11533server # [ 11.769562] acme-setup-privileged[507]: + for fixpath in server .lego/server1534server # [ 11.770567] acme-setup-privileged[507]: + '[' -d server ']'1535server # [ 11.771307] acme-setup-privileged[507]: + for fixpath in server .lego/server1536host1 # [ 11.737757] acme-setup-privileged[501]: + '[' -d host1 ']'1537host1 # [ 11.743128] acme-setup-privileged[501]: + for fixpath in host1 .lego/host11538host1 # [ 11.748880] acme-setup-privileged[501]: + '[' -d .lego/host1 ']'1539server # [ 11.789306] acme-setup-privileged[507]: + '[' -d .lego/server ']'1540host1 # [ 11.755761] systemd[1]: Reached target Host and Network Name Lookups.1541server # [ 11.796256] systemd[1]: Reached target User and Group Name Lookups.1542host1 # [ 11.761169] systemd[1]: Reached target User and Group Name Lookups.1543server # [ 11.805721] systemd[1]: Starting User Login Management...1544server # [ 11.813001] systemd[1]: Finished Import lastlog data into lastlog2 database.1545host1 # [ 11.780118] systemd[1]: Starting D-Bus System Message Bus...1546server: Guest shell says: b'Spawning backdoor root shell...\n'1547host1 # [ 11.804950] systemd[1]: Starting User Login Management...1548server: connected to guest root shell1549host1 # [ 11.817795] systemd[1]: Finished Post-Boot Actions.1550server: (connecting took 12.13 seconds)1551server: (finished: waiting for the VM to finish booting, in 12.13 seconds)1552host1 # [ 11.830573] systemd[1]: Finished Import lastlog data into lastlog2 database.1553server # [ 11.878312] dbus-broker-launch[518]: Looking up NSS user entry for 'systemd-timesync'...1554server # [ 11.889218] dbus-broker-launch[518]: NSS returned no entry for 'systemd-timesync'1555server # [ 11.896389] dbus-broker-launch[518]: Invalid user-name in /nix/store/pilyp4chpidfsxm2sh90rz8rhf5i0nka-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1556server # [ 11.952558] systemd[1]: Started D-Bus System Message Bus.1557server # [ 12.039045] dbus-broker-launch[518]: Ready1558host1 # [ 12.013531] dbus-broker-launch[536]: Looking up NSS user entry for 'systemd-timesync'...1559host1 # [ 12.023178] dbus-broker-launch[536]: NSS returned no entry for 'systemd-timesync'1560host1 # [ 12.029489] dbus-broker-launch[536]: Invalid user-name in /nix/store/a9ijqx61hpssavy5d9lh27li51m8hnl7-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1561host1 # [ 12.040484] systemd-logind[538]: New seat seat0.1562server # [ 12.075759] systemd-logind[533]: New seat seat0.1563host1 # [ 12.045441] acme-setup-start[533]: + set -euo pipefail1564host1 # [ 12.049116] acme-setup-start[533]: + test -e ca/key.pem1565server # [ 12.087631] systemd[1]: Started User Login Management.1566host1 # [ 12.053429] acme-setup-start[533]: + minica --ca-key ca/key.pem --ca-cert ca/cert.pem --domains selfsigned.local1567server # [ 12.094261] systemd[1]: Starting linger-users.service...1568host1 # [ 12.060552] systemd[1]: Stopped target Host and Network Name Lookups.1569host1 # [ 12.067719] systemd[1]: Stopping Host and Network Name Lookups...1570host1 # [ 12.071509] systemd[1]: Stopped target User and Group Name Lookups.1571host1 # [ 12.074674] systemd[1]: Stopping User and Group Name Lookups...1572host1 # [ 12.080711] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1573host1 # [ 12.081640] systemd[1]: Started User Login Management.1574host1 # [ 12.082343] systemd[1]: nscd.service: Deactivated successfully.1575server # [ 12.118331] acme-setup-start[546]: + set -euo pipefail1576server # [ 12.119159] acme-setup-start[546]: + test -e ca/key.pem1577host1 # [ 12.083158] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1578server # [ 12.119873] acme-setup-start[546]: + minica --ca-key ca/key.pem --ca-cert ca/cert.pem --domains selfsigned.local1579host1 # [ 12.096271] systemd[1]: Starting linger-users.service...1580host1 # [ 12.099913] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1581host1 # [ 12.104422] systemd[1]: Started D-Bus System Message Bus.1582server # [ 12.149109] systemd[1]: Stopped target Host and Network Name Lookups.1583server # [ 12.150091] systemd[1]: Stopping Host and Network Name Lookups...1584server # [ 12.150926] systemd[1]: Stopped target User and Group Name Lookups.1585server # [ 12.151805] systemd[1]: Stopping User and Group Name Lookups...1586host1 # [ 12.124518] dbus-broker-launch[536]: Ready1587server # [ 12.184677] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1588server # [ 12.194349] systemd[1]: nscd.service: Deactivated successfully.1589server # [ 12.195276] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1590server # [ 12.206244] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1591host1 # [ 12.173390] systemd-logind[538]: Watching system buttons on /dev/input/event0 (gpio-keys)1592host1 # [ 12.181456] (udev-worker)[464]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1593host1 # [ 12.183586] (udev-worker)[464]: Network interface NamePolicy= disabled on kernel command line.1594host1 # [ 12.200295] systemd[1]: Finished Set up the ACME certificate renewal infrastructure.1595host1 # [ 12.209030] systemd[1]: Starting Ensure certificate for host1...1596server # [ 12.246015] systemd[1]: linger-users.service: Deactivated successfully.1597server # [ 12.252166] systemd[1]: Finished linger-users.service.1598host1 # [ 12.221648] (udev-worker)[468]: Network interface NamePolicy= disabled on kernel command line.1599host1 # [ 12.229324] nsncd[590]: Sep 21 13:48:41.777 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1600host1 # [ 12.240681] systemd[1]: Started Name Service Cache Daemon (nsncd).1601host1 # [ 12.246525] systemd[1]: linger-users.service: Deactivated successfully.1602host1 # [ 12.250172] systemd[1]: Finished linger-users.service.1603host1 # [ 12.254274] systemd[1]: Reached target Host and Network Name Lookups.1604host1 # [ 12.260315] systemd[1]: Reached target User and Group Name Lookups.1605server # [ 12.306668] systemd[1]: Finished Set up the ACME certificate renewal infrastructure.1606server # [ 12.312986] systemd[1]: Starting Ensure certificate for server...1607server # [ 12.324622] systemd[1]: Started Name Service Cache Daemon (nsncd).1608server # [ 12.327504] nsncd[612]: Sep 21 13:48:41.865 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1609server # [ 12.336462] systemd[1]: Reached target Host and Network Name Lookups.1610host1 # [ 12.305989] systemd[1]: Finished resolvconf update.1611server # [ 12.342802] systemd[1]: Reached target User and Group Name Lookups.1612host1 # [ 12.309372] systemd[1]: Reached target Preparation for Network.1613host1 # [ 12.322025] systemd[1]: Starting DHCP Client...1614host1 # [ 12.328266] systemd[1]: Starting Extra networking commands....1615server # [ 12.380889] systemd[1]: Finished resolvconf update.1616host1 # [ 12.350440] acme-host1-start[605]: Waiting to acquire lock in /run/acme/1617server # [ 12.382970] systemd[1]: Reached target Preparation for Network.1618server # [ 12.390247] systemd[1]: Starting DHCP Client...1619server # [ 12.398933] systemd[1]: Starting Extra networking commands....1620host1 # [ 12.373835] acme-host1-start[605]: + '[' -e out/acme-success ']'1621host1 # [ 12.377137] acme-host1-start[605]: + minica --ca-cert=ca/cert.pem --ca-key=ca/key.pem --domains=host1 --ip-addresses=1622host1 # [ 12.420581] acme-host1-start[639]: + cd host11623host1 # [ 12.421298] acme-host1-start[639]: + cp -vp cert.pem ../out/cert.pem1624host1 # [ 12.433140] acme-host1-start[641]: 'cert.pem' -> '../out/cert.pem'1625server # [ 12.476123] (udev-worker)[375]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1626host1 # [ 12.439289] acme-host1-start[639]: + cp -vp key.pem ../out/key.pem1627host1 # [ 12.450177] acme-host1-start[639]: 'key.pem' -> '../out/key.pem'1628server # [ 12.490309] (udev-worker)[375]: Network interface NamePolicy= disabled on kernel command line.1629host1 # [ 12.460181] acme-host1-start[605]: + cat out/cert.pem ca/cert.pem1630server # [ 12.495092] (udev-worker)[380]: Network interface NamePolicy= disabled on kernel command line.1631host1 # [ 12.476159] acme-host1-start[605]: + cp ca/cert.pem out/chain.pem1632host1 # [ 12.493894] systemd[1]: Condition check resulted in Virtio network device being skipped.1633host1 # [ 12.497652] acme-host1-start[605]: + cat out/key.pem out/fullchain.pem1634server # [ 12.537976] acme-server-start[631]: Waiting to acquire lock in /run/acme/1635host1 # [ 12.509153] systemd[1]: Starting Address configuration of eth1...1636host1 # [ 12.514889] acme-host1-start[605]: + for fixpath in out certificates1637host1 # [ 12.515789] acme-host1-start[605]: + '[' -d out ']'1638host1 # [ 12.517552] acme-host1-start[605]: + chmod -R u=rwX,g=rX,o= out1639server # [ 12.572207] acme-server-start[631]: + '[' -e out/acme-success ']'1640host1 # [ 12.536520] acme-host1-start[605]: + chown -R acme:acme out1641server # [ 12.576255] acme-server-start[631]: + minica --ca-cert=ca/cert.pem --ca-key=ca/key.pem --domains=server --ip-addresses=1642host1 # [ 12.569000] acme-host1-start[605]: + for fixpath in out certificates1643host1 # [ 12.569932] acme-host1-start[605]: + '[' -d certificates ']'1644host1 # [ 12.578228] systemd[1]: Finished Ensure certificate for host1.1645server # [ 12.624251] acme-server-start[670]: + cd server1646server # [ 12.624991] acme-server-start[670]: + cp -vp cert.pem ../out/cert.pem1647server # [ 12.641419] acme-server-start[671]: 'cert.pem' -> '../out/cert.pem'1648server # [ 12.650765] acme-server-start[670]: + cp -vp key.pem ../out/key.pem1649server # [ 12.660732] acme-server-start[670]: 'key.pem' -> '../out/key.pem'1650server # [ 12.666581] acme-server-start[631]: + cat out/cert.pem ca/cert.pem1651host1 # [ 12.649438] dhcpcd[643]: dhcpcd-10.3.2 starting1652server # [ 12.686841] systemd[1]: Condition check resulted in Virtio network device being skipped.1653server # [ 12.693951] acme-server-start[631]: + cp ca/cert.pem out/chain.pem1654host1 # [ 12.665819] dhcpcd[688]: dev: loaded udev1655server # [ 12.703602] systemd[1]: Starting Address configuration of eth1...1656host1 # [ 12.670477] network-addresses-eth1-start[651]: adding address 192.168.1.1/24... done1657server # [ 12.715511] acme-server-start[631]: + cat out/key.pem out/fullchain.pem1658host1 # [ 12.689923] network-addresses-eth1-start[651]: adding address 2001:db8:1::1/64... done1659server # [ 12.731654] acme-server-start[631]: + for fixpath in out certificates1660server # [ 12.736420] acme-server-start[631]: + '[' -d out ']'1661server # [ 12.737742] acme-server-start[631]: + chmod -R u=rwX,g=rX,o= out1662server # [ 12.752272] acme-server-start[631]: + chown -R acme:nix-grpc-daemon out1663host1 # [ 12.718163] systemd[1]: Finished Address configuration of eth1.1664host1 # [ 12.738197] 8021q: 802.1Q VLAN Support v1.81665host1 # [ 12.738530] 8021q: adding VLAN 0 to HW filter on device eth11666server # [ 12.771888] dhcpcd[655]: dhcpcd-10.3.2 starting1667host1 # [ 12.742975] systemd[1]: Finished Extra networking commands..1668server # [ 12.780717] acme-server-start[631]: + for fixpath in out certificates1669server # [ 12.781626] acme-server-start[631]: + '[' -d certificates ']'1670host1 # [ 12.746410] systemd[1]: Reached target Network.1671host1 # [ 12.754675] systemd[1]: Starting Permit User Sessions...1672server # [ 12.792632] systemd[1]: Finished Ensure certificate for server.1673server # [ 12.797781] step-ca-pki-start[531]: Your certificate has been saved in root_ca.crt.1674server # [ 12.798832] step-ca-pki-start[531]: Your private key has been saved in root_ca.key.1675server # [ 12.809272] dhcpcd[701]: dev: loaded udev1676server # [ 12.864130] 8021q: 802.1Q VLAN Support v1.81677host1 # [ 12.823401] systemd[1]: Finished Permit User Sessions.1678host1 # [ 12.830645] systemd[1]: Started Getty on tty1.1679host1 # [ 12.832449] systemd[1]: Reached target Login Prompts.1680host1 # [ 12.870992] cfg80211: Loading compiled-in X.509 certificates for regulatory database1681server # [ 12.927731] 8021q: adding VLAN 0 to HW filter on device eth11682host1 # [ 12.908010] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1683host1 # [ 12.908503] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1684host1 # [ 12.913812] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21685host1 # [ 12.914140] cfg80211: failed to load regulatory.db1686server # [ 12.953794] cfg80211: Loading compiled-in X.509 certificates for regulatory database1687server # [ 12.965241] network-addresses-eth1-start[682]: adding address 192.168.1.2/24... done1688server # [ 12.989731] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1689server # [ 12.990265] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1690server # [ 12.995518] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21691server # [ 12.995871] cfg80211: failed to load regulatory.db1692server # [ 12.995398] systemd[1]: Finished Extra networking commands..1693server # [ 12.997086] systemd[1]: Reached target Network.1694server # [ 13.004427] systemd[1]: Starting Permit User Sessions...1695host1 # [ 12.986346] 8021q: adding VLAN 0 to HW filter on device eth01696host1 # [ 12.971392] dhcpcd[688]: eth0: waiting for carrier1697host1 # [ 12.973266] dhcpcd[688]: eth0: carrier acquired1698host1 # [ 12.988103] dhcpcd[688]: DUID 00:01:00:01:32:43:f2:3a:52:54:00:12:34:561699server # [ 13.024408] network-addresses-eth1-start[682]: adding address 2001:db8:1::2/64... done1700host1 # [ 12.989089] dhcpcd[688]: eth0: IAID 00:12:34:561701host1 # [ 12.989758] dhcpcd[688]: eth0: adding address fe80::5054:ff:fe12:34561702server # [ 13.039688] step-ca-pki-start[712]: Your certificate has been saved in intermediate_ca.crt.1703server # [ 13.050301] step-ca-pki-start[712]: Your private key has been saved in intermediate_ca.key.1704server # [ 13.060662] systemd[1]: Finished Address configuration of eth1.1705host1 # [ 13.042557] mousedev: PS/2 mouse device common for all mice1706server # [ 13.093928] systemd[1]: Finished step-ca-pki.service.1707server # [ 13.099987] systemd[1]: Finished Permit User Sessions.1708server # [ 13.104712] systemd[1]: Started Getty on tty1.1709server # [ 13.109656] systemd[1]: Reached target Login Prompts.1710host1 # [ 13.091786] systemd-logind[538]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1711server # [ 13.149684] 8021q: adding VLAN 0 to HW filter on device eth01712server # [ 13.137544] dhcpcd[701]: eth0: waiting for carrier1713server # [ 13.139083] dhcpcd[701]: eth0: carrier acquired1714server # [ 13.155062] dhcpcd[701]: DUID 00:01:00:01:32:43:f2:3a:52:54:00:12:34:561715server # [ 13.158399] dhcpcd[701]: eth0: IAID 00:12:34:561716server # [ 13.159136] dhcpcd[701]: eth0: adding address fe80::5054:ff:fe12:34561717server # [ 13.182147] systemd-logind[533]: Watching system buttons on /dev/input/event0 (gpio-keys)1718server # [ 13.240403] dhcpcd[701]: eth0: soliciting a DHCP lease1719server # [ 13.251478] dhcpcd[701]: eth0: offered 10.0.2.15 from 10.0.2.21720server # [ 13.256231] dhcpcd[701]: eth0: probing address 10.0.2.15/241721server # [ 13.427671] mousedev: PS/2 mouse device common for all mice1722server # [ 13.487628] systemd-logind[533]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1723host1 # [ 13.618237] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31724host1 # [ 13.899236] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1725host1 # [ 13.921147] systemd[1]: Starting Virtual Console Setup...1726host1 # [ 13.943877] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1727host1 # [ 13.947148] systemd[1]: Stopped Virtual Console Setup.1728host1 # [ 13.958461] systemd[1]: Starting Virtual Console Setup...1729server # [ 14.037569] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31730host1 # [ 13.998492] systemd-logind[538]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1731server # [ 14.329778] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1732server # [ 14.358700] systemd[1]: Starting Virtual Console Setup...1733server # [ 14.378636] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1734server # [ 14.382936] systemd[1]: Stopped Virtual Console Setup.1735server # [ 14.388938] systemd[1]: Starting Virtual Console Setup...1736host1 # [ 14.366002] systemd-vconsole-setup[748]: Configuration of first virtual console was skipped, ignoring remaining ones.1737host1 # [ 14.370179] systemd[1]: Finished Virtual Console Setup.1738server # [ 14.476995] systemd-logind[533]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1739host1 # [ 14.598526] dhcpcd[688]: eth0: soliciting a DHCP lease1740host1 # [ 14.605637] dhcpcd[688]: eth0: offered 10.0.2.15 from 10.0.2.21741host1 # [ 14.612708] dhcpcd[688]: eth0: probing address 10.0.2.15/241742server # [ 14.829166] systemd-vconsole-setup[787]: Configuration of first virtual console was skipped, ignoring remaining ones.1743server # [ 14.834475] systemd[1]: Finished Virtual Console Setup.1744server # [ 14.969342] dhcpcd[701]: eth0: soliciting an IPv6 router1745server # [ 14.973159] dhcpcd[701]: eth0: Router Advertisement from fe80::21746server # [ 14.975813] dhcpcd[701]: eth0: adding address fec0::5054:ff:fe12:3456/641747server # [ 14.978906] dhcpcd[701]: eth0: adding route to fec0::/641748server # [ 14.981300] dhcpcd[701]: eth0: adding default route via fe80::21749host1 # [ 15.242423] dhcpcd[688]: eth0: soliciting an IPv6 router1750host1 # [ 15.245859] dhcpcd[688]: eth0: Router Advertisement from fe80::21751host1 # [ 15.249111] dhcpcd[688]: eth0: adding address fec0::5054:ff:fe12:3456/641752host1 # [ 15.251931] dhcpcd[688]: eth0: adding route to fec0::/641753host1 # [ 15.254293] dhcpcd[688]: eth0: adding default route via fe80::21754server # [ 18.050059] dhcpcd[701]: eth0: leased 10.0.2.15 for 86400 seconds1755server # [ 18.052969] dhcpcd[701]: eth0: adding route to 10.0.2.0/241756server # [ 18.055363] dhcpcd[701]: eth0: adding default route via 10.0.2.21757server # [ 18.257688] systemd[1]: Started DHCP Client.1758server # [ 18.260540] systemd[1]: Reached target Network is Online.1759server # [ 18.265702] systemd[1]: Starting step-ca service...1760server # [ 18.766336] step-ca[885]: badger 2026/09/21 13:48:48 INFO: All 0 tables opened in 0s1761server # [ 18.791979] step-ca[885]: 2026/09/21 13:48:48 Building new tls configuration using step-ca x509 Signer Interface1762server # [ 18.810758] step-ca[885]: 2026/09/21 13:48:48 Starting Smallstep CA/0.30.2 (linux/arm64)1763server # [ 18.811900] step-ca[885]: 2026/09/21 13:48:48 Documentation: https://u.step.sm/docs/ca1764server # [ 18.813049] step-ca[885]: 2026/09/21 13:48:48 Community Discord: https://u.step.sm/discord1765server # [ 18.814227] step-ca[885]: 2026/09/21 13:48:48 Config file: /etc/smallstep/ca.json1766server # [ 18.815290] step-ca[885]: 2026/09/21 13:48:48 The primary server URL is https://server:84431767server # [ 18.817967] step-ca[885]: 2026/09/21 13:48:48 Root certificates are available at https://server:8443/roots.pem1768server # [ 18.819350] step-ca[885]: 2026/09/21 13:48:48 X.509 Root Fingerprint: 10a2113621dc7ffc630a967d3ed40750bcbf38a7582b5b49ea45c401a32b6a991769server # [ 18.823236] systemd[1]: Started step-ca service.1770server # [ 18.826352] systemd[1]: Reached target Multi-User System.1771server # [ 18.831661] systemd[1]: Starting Order (and renew) ACME certificate for server...1772server # [ 18.836712] step-ca[885]: 2026/09/21 13:48:48 Serving HTTPS on 0.0.0.0:8443 ...1773server # [ 18.910704] acme-order-renew-server-start[891]: Waiting to acquire lock in /run/acme/1774server # [ 18.923912] acme-order-renew-server-start[891]: + set -euo pipefail1775server # [ 18.925422] acme-order-renew-server-start[891]: + echo 95dbbf40517986466fee1776server # [ 18.926681] acme-order-renew-server-start[891]: + cmp -s domainhash.txt certificates/domainhash.txt1777server # [ 18.942164] acme-order-renew-server-start[891]: + lego --accept-tos --path . --email root@example.org --http --http.port :80 --server https://server:8443/acme/acme/directory --key-type ec256 -d server run1778server: (finished: waiting for unit step-ca.service, in 19.24 seconds)1779server: waiting for unit nix-grpc-daemon.socket1780server: (finished: waiting for unit nix-grpc-daemon.socket, in 0.07 seconds)1781server: waiting for TCP port 50051 on localhost1782server # Connection to localhost (127.0.0.1) 50051 port [tcp/*] succeeded!1783server: (finished: waiting for TCP port 50051 on localhost, in 0.09 seconds)1784subtest: host1 obtains a certificate via ACME1785host1: waiting for success: /nix/store/9ibakafsh3z4y7q3nvs44mz5vwhh67ch-openssl-3.6.3-bin/bin/openssl x509 -in /var/lib/acme/host1/cert.pem -noout -subject -issuer | grep -q 'Test Intermediate CA'1786host1: waiting for the VM to finish booting1787host1: Guest shell says: b'Spawning backdoor root shell...\n'1788host1: connected to guest root shell1789host1: (connecting took 0.00 seconds)1790host1: (finished: waiting for the VM to finish booting, in 0.00 seconds)1791server # [ 19.350258] acme-order-renew-server-start[905]: 2026/09/21 13:48:48 No key found for account root@example.org. Generating a P256 key.1792server # [ 19.355150] acme-order-renew-server-start[905]: 2026/09/21 13:48:48 Saved key to accounts/server_8443/root@example.org/keys/root@example.org.key1793server # [ 19.390976] acme-order-renew-server-start[905]: 2026/09/21 13:48:48 [INFO] acme: Registering account for root@example.org1794server # [ 19.405305] acme-order-renew-server-start[905]: !!!! HEADS UP !!!!1795server # [ 19.407085] acme-order-renew-server-start[905]: Your account credentials have been saved in your1796server # [ 19.408596] acme-order-renew-server-start[905]: configuration directory at "accounts".1797server # [ 19.409705] acme-order-renew-server-start[905]: You should make a secure backup of this folder now. This1798server # [ 19.411015] acme-order-renew-server-start[905]: configuration directory will also contain private keys1799server # [ 19.412768] acme-order-renew-server-start[905]: generated by lego and certificates obtained from the ACME1800server # [ 19.414163] acme-order-renew-server-start[905]: server. Making regular backups of this folder is ideal.1801server # [ 19.415432] acme-order-renew-server-start[905]: 2026/09/21 13:48:48 [INFO] [server] acme: Obtaining bundled SAN certificate1802server # [ 19.490307] acme-order-renew-server-start[905]: 2026/09/21 13:48:49 [INFO] [server] AuthURL: https://server:8443/acme/acme/authz/zWnwyt6ZAPOnM7UsQ1gSKFarepkYgQ4a1803server # [ 19.493784] acme-order-renew-server-start[905]: 2026/09/21 13:48:49 [INFO] [server] acme: Could not find solver for: tls-alpn-011804server # [ 19.495572] acme-order-renew-server-start[905]: 2026/09/21 13:48:49 [INFO] [server] acme: use http-01 solver1805server # [ 19.497235] acme-order-renew-server-start[905]: 2026/09/21 13:48:49 [INFO] [server] acme: Trying to solve HTTP-011806server # [ 19.506536] acme-order-renew-server-start[905]: 2026/09/21 13:48:49 [INFO] [server] Served key authentication1807server # [ 19.512350] acme-order-renew-server-start[905]: 2026/09/21 13:48:49 [INFO] [server] The server validated our request1808server # [ 19.514290] acme-order-renew-server-start[905]: 2026/09/21 13:48:49 [INFO] [server] acme: Validations succeeded; requesting certificates1809server # [ 19.534896] acme-order-renew-server-start[905]: 2026/09/21 13:48:49 [INFO] [server] Server responded with a certificate.1810server # [ 19.537822] acme-order-renew-server-start[891]: + mv domainhash.txt certificates/1811server # [ 19.546902] acme-order-renew-server-start[891]: + touch out/acme-success1812server # [ 19.555270] acme-order-renew-server-start[891]: + cmp -s certificates/server.crt out/fullchain.pem1813server # [ 19.560939] acme-order-renew-server-start[891]: + touch out/renewed1814server # [ 19.567721] acme-order-renew-server-start[891]: + echo Installing new certificate1815server # [ 19.569213] acme-order-renew-server-start[891]: Installing new certificate1816server # [ 19.570317] acme-order-renew-server-start[891]: + cp -vp certificates/server.crt out/fullchain.pem1817server # [ 19.577914] acme-order-renew-server-start[922]: 'certificates/server.crt' -> 'out/fullchain.pem'1818server # [ 19.580064] acme-order-renew-server-start[891]: + cp -vp certificates/server.key out/key.pem1819server # [ 19.586868] acme-order-renew-server-start[923]: 'certificates/server.key' -> 'out/key.pem'1820server # [ 19.588926] acme-order-renew-server-start[891]: + cp -vp certificates/server.issuer.crt out/chain.pem1821server # [ 19.595478] acme-order-renew-server-start[924]: 'certificates/server.issuer.crt' -> 'out/chain.pem'1822server # [ 19.597453] acme-order-renew-server-start[891]: + ln -sf fullchain.pem out/cert.pem1823server # [ 19.603568] acme-order-renew-server-start[891]: + cat out/key.pem out/fullchain.pem1824server # [ 19.609968] acme-order-renew-server-start[891]: + for fixpath in out certificates1825server # [ 19.611094] acme-order-renew-server-start[891]: + '[' -d out ']'1826server # [ 19.612105] acme-order-renew-server-start[891]: + chmod -R u=rwX,g=rX,o= out1827server # [ 19.618081] acme-order-renew-server-start[891]: + chown -R acme:nix-grpc-daemon out1828server # [ 19.626289] acme-order-renew-server-start[891]: + for fixpath in out certificates1829server # [ 19.627337] acme-order-renew-server-start[891]: + '[' -d certificates ']'1830server # [ 19.628525] acme-order-renew-server-start[891]: + chmod -R u=rwX,g=rX,o= certificates1831server # [ 19.634643] acme-order-renew-server-start[891]: + chown -R acme:nix-grpc-daemon certificates1832server # [ 19.642376] acme-order-renew-server-start[891]: + chmod -R u=rwX,g=,o= accounts/.1833server # [ 19.713435] systemd[1]: acme-order-renew-server.service: Deactivated successfully.1834server # [ 19.716681] systemd[1]: Finished Order (and renew) ACME certificate for server.1835server # [ 19.720118] systemd[1]: acme-order-renew-server.service: Consumed 218ms CPU time over 883ms wall clock time, 33.6M memory peak, 10.2K incoming IP traffic, 8.2K outgoing IP traffic.1836server # [ 19.727492] systemd[1]: Starting Nix worker-protocol over gRPC...1837host1 # [ 19.877741] dhcpcd[688]: eth0: leased 10.0.2.15 for 86400 seconds1838host1 # [ 19.881974] dhcpcd[688]: eth0: adding route to 10.0.2.0/241839host1 # [ 19.884972] dhcpcd[688]: eth0: adding default route via 10.0.2.21840server # [ 19.941628] nix-grpc-daemon[942]: ts=2026-09-21T13:48:49Z level=info event=roles builder=1 scheduler=1 scheduler_addr="" advertise=0.0.0.0:50051 niks3="" max_jobs=11841server # [ 19.977712] nix-grpc-daemon[942]: WARNING: All log messages before absl::InitializeLog() is called are written to STDERR1842server # [ 19.979390] nix-grpc-daemon[942]: E0000 00:00:1789998529.520704 942 tls_security_connector.cc:771] TlsServerCertificateWatcher getting identity_cert_error: UNKNOWN:Unable to get latest identity certificates.1843server # [ 19.992615] nix-grpc-daemon[942]: E0000 00:00:1789998529.535667 942 tls_security_connector.cc:767] TlsServerCertificateWatcher getting root_cert_error: UNKNOWN:Unable to get latest root certificates.1844server # [ 19.995825] nix-grpc-daemon[942]: ts=2026-09-21T13:48:49Z level=info event=startup listen=systemd proxy_socket=/nix/var/nix/daemon-socket/socket1845server # [ 19.999788] nix-grpc-daemon[942]: ts=2026-09-21T13:48:49Z level=info event=worker_hello addr=0.0.0.0:50051 systems=aarch64-linux max_jobs=1 running=01846server # [ 20.002085] nix-grpc-daemon[942]: ts=2026-09-21T13:48:49Z level=info event=unhealthy reason=min_free1847server # [ 20.003386] systemd[1]: Started Nix worker-protocol over gRPC.1848server # [ 20.004658] systemd[1]: Startup finished in 1.032s (kernel) + 5.379s (initrd) + 13.585s (userspace) = 19.998s.1849host1 # [ 20.039555] systemd[1]: Started DHCP Client.1850host1 # [ 20.042227] systemd[1]: Reached target Network is Online.1851host1 # [ 20.046576] systemd[1]: Starting fetch-ca-root.service...1852host1 # [ 20.154782] systemd[1]: Finished fetch-ca-root.service.1853host1 # [ 20.158757] systemd[1]: Reached target Multi-User System.1854host1 # [ 20.161646] systemd[1]: Starting Order (and renew) ACME certificate for host1...1855host1 # [ 20.224769] acme-order-renew-host1-start[824]: Waiting to acquire lock in /run/acme/1856host1 # [ 20.238771] acme-order-renew-host1-start[824]: + set -euo pipefail1857host1 # [ 20.240158] acme-order-renew-host1-start[824]: + echo 76ccde284609af0e376b1858host1 # [ 20.241207] acme-order-renew-host1-start[824]: + cmp -s domainhash.txt certificates/domainhash.txt1859host1 # [ 20.251317] acme-order-renew-host1-start[824]: + lego --accept-tos --path . --email root@example.org --http --http.port :80 --server https://server:8443/acme/acme/directory --key-type ec256 -d host1 run1860host1 # [ 20.573986] acme-order-renew-host1-start[836]: 2026/09/21 13:48:50 No key found for account root@example.org. Generating a P256 key.1861host1 # [ 20.578317] acme-order-renew-host1-start[836]: 2026/09/21 13:48:50 Saved key to accounts/server_8443/root@example.org/keys/root@example.org.key1862host1 # [ 20.608088] acme-order-renew-host1-start[836]: 2026/09/21 13:48:50 [INFO] acme: Registering account for root@example.org1863host1 # [ 20.626308] acme-order-renew-host1-start[836]: !!!! HEADS UP !!!!1864host1 # [ 20.627730] acme-order-renew-host1-start[836]: Your account credentials have been saved in your1865host1 # [ 20.629000] acme-order-renew-host1-start[836]: configuration directory at "accounts".1866host1 # [ 20.630055] acme-order-renew-host1-start[836]: You should make a secure backup of this folder now. This1867host1 # [ 20.631261] acme-order-renew-host1-start[836]: configuration directory will also contain private keys1868host1 # [ 20.632506] acme-order-renew-host1-start[836]: generated by lego and certificates obtained from the ACME1869host1 # [ 20.633744] acme-order-renew-host1-start[836]: server. Making regular backups of this folder is ideal.1870host1 # [ 20.634929] acme-order-renew-host1-start[836]: 2026/09/21 13:48:50 [INFO] [host1] acme: Obtaining bundled SAN certificate1871host1 # [ 20.710508] acme-order-renew-host1-start[836]: 2026/09/21 13:48:50 [INFO] [host1] AuthURL: https://server:8443/acme/acme/authz/WiVeckiuxUcmbjoLsclm7ZFwTVHnTlzs1872host1 # [ 20.716878] acme-order-renew-host1-start[836]: 2026/09/21 13:48:50 [INFO] [host1] acme: Could not find solver for: tls-alpn-011873host1 # [ 20.720701] acme-order-renew-host1-start[836]: 2026/09/21 13:48:50 [INFO] [host1] acme: use http-01 solver1874host1 # [ 20.723769] acme-order-renew-host1-start[836]: 2026/09/21 13:48:50 [INFO] [host1] acme: Trying to solve HTTP-011875host1 # [ 20.729039] acme-order-renew-host1-start[836]: 2026/09/21 13:48:50 [INFO] [host1] Served key authentication1876host1 # [ 20.736108] acme-order-renew-host1-start[836]: 2026/09/21 13:48:50 [INFO] [host1] The server validated our request1877host1 # [ 20.739261] acme-order-renew-host1-start[836]: 2026/09/21 13:48:50 [INFO] [host1] acme: Validations succeeded; requesting certificates1878host1 # [ 20.769094] acme-order-renew-host1-start[836]: 2026/09/21 13:48:50 [INFO] [host1] Server responded with a certificate.1879host1 # [ 20.780929] acme-order-renew-host1-start[824]: + mv domainhash.txt certificates/1880host1 # [ 20.795560] acme-order-renew-host1-start[824]: + touch out/acme-success1881host1 # [ 20.809247] acme-order-renew-host1-start[824]: + cmp -s certificates/host1.crt out/fullchain.pem1882host1 # [ 20.818217] acme-order-renew-host1-start[824]: + touch out/renewed1883host1 # [ 20.828443] acme-order-renew-host1-start[824]: + echo Installing new certificate1884host1 # [ 20.831067] acme-order-renew-host1-start[824]: Installing new certificate1885host1 # [ 20.833623] acme-order-renew-host1-start[824]: + cp -vp certificates/host1.crt out/fullchain.pem1886host1 # [ 20.844735] acme-order-renew-host1-start[846]: 'certificates/host1.crt' -> 'out/fullchain.pem'1887host1 # [ 20.848777] acme-order-renew-host1-start[824]: + cp -vp certificates/host1.key out/key.pem1888host1 # [ 20.857776] acme-order-renew-host1-start[847]: 'certificates/host1.key' -> 'out/key.pem'1889host1 # [ 20.860570] acme-order-renew-host1-start[824]: + cp -vp certificates/host1.issuer.crt out/chain.pem1890host1 # [ 20.867933] acme-order-renew-host1-start[848]: 'certificates/host1.issuer.crt' -> 'out/chain.pem'1891host1 # [ 20.870438] acme-order-renew-host1-start[824]: + ln -sf fullchain.pem out/cert.pem1892host1 # [ 20.878370] acme-order-renew-host1-start[824]: + cat out/key.pem out/fullchain.pem1893host1 # [ 20.887426] acme-order-renew-host1-start[824]: + for fixpath in out certificates1894host1 # [ 20.889313] acme-order-renew-host1-start[824]: + '[' -d out ']'1895host1 # [ 20.890690] acme-order-renew-host1-start[824]: + chmod -R u=rwX,g=rX,o= out1896host1 # [ 20.898381] acme-order-renew-host1-start[824]: + chown -R acme:acme out1897host1 # [ 20.908627] acme-order-renew-host1-start[824]: + for fixpath in out certificates1898host1 # [ 20.909974] acme-order-renew-host1-start[824]: + '[' -d certificates ']'1899host1 # [ 20.911150] acme-order-renew-host1-start[824]: + chmod -R u=rwX,g=rX,o= certificates1900host1 # [ 20.917519] acme-order-renew-host1-start[824]: + chown -R acme:acme certificates1901host1 # [ 20.925913] acme-order-renew-host1-start[824]: + chmod -R u=rwX,g=,o= accounts/.1902host1 # [ 20.989381] systemd[1]: acme-order-renew-host1.service: Deactivated successfully.1903host1 # [ 20.991435] systemd[1]: Finished Order (and renew) ACME certificate for host1.1904host1 # [ 21.005060] systemd[1]: Startup finished in 1.025s (kernel) + 5.485s (initrd) + 14.493s (userspace) = 21.004s.1905host1 # [ 21.006461] systemd[1]: acme-order-renew-host1.service: Consumed 225ms CPU time over 829ms wall clock time, 33.4M memory peak, 10.3K incoming IP traffic, 8.3K outgoing IP traffic.1906host1: (finished: waiting for success: /nix/store/9ibakafsh3z4y7q3nvs44mz5vwhh67ch-openssl-3.6.3-bin/bin/openssl x509 -in /var/lib/acme/host1/cert.pem -noout -subject -issuer | grep -q 'Test Intermediate CA', in 2.31 seconds)1907host1: must succeed: /nix/store/9ibakafsh3z4y7q3nvs44mz5vwhh67ch-openssl-3.6.3-bin/bin/openssl x509 -in /var/lib/acme/host1/cert.pem -noout -subject | grep -q 'CN *= *host1'1908host1: (finished: must succeed: /nix/store/9ibakafsh3z4y7q3nvs44mz5vwhh67ch-openssl-3.6.3-bin/bin/openssl x509 -in /var/lib/acme/host1/cert.pem -noout -subject | grep -q 'CN *= *host1', in 0.06 seconds)1909(finished: subtest: host1 obtains a certificate via ACME, in 2.36 seconds)1910subtest: server builds and signs a path1911server: must succeed: nix build --impure -f /etc/hello.nix --no-link --print-out-paths1912server # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 31 ms (attempt 1/5)1913server # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 70 ms (attempt 2/5)1914server # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 48 ms (attempt 3/5)1915server # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 605 ms (attempt 4/5)1916server # disabling binary cache 'https://cache.nixos.org' for 60 seconds1917server # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org1918server # this derivation will be built:1919server # /nix/store/kkr1m31ja6qn4sc39z93j6w6mmkx0az9-hello-grpc.drv1920server # building '/nix/store/kkr1m31ja6qn4sc39z93j6w6mmkx0az9-hello-grpc.drv'...1921server: (finished: must succeed: nix build --impure -f /etc/hello.nix --no-link --print-out-paths, in 1.43 seconds)1922server: must succeed: nix store sign -k /etc/cache-key '/nix/store/yp0zv0nbwik9d6wqgsrajwwqb1fsfd0p-hello-grpc'1923server # added 1 signatures1924server: (finished: must succeed: nix store sign -k /etc/cache-key '/nix/store/yp0zv0nbwik9d6wqgsrajwwqb1fsfd0p-hello-grpc', in 0.23 seconds)1925(finished: subtest: server builds and signs a path, in 1.66 seconds)1926subtest: host1 substitutes the signed path over gRPC (read-only cert)1927host1: must fail: test -e '/nix/store/yp0zv0nbwik9d6wqgsrajwwqb1fsfd0p-hello-grpc'1928host1: (finished: must fail: test -e '/nix/store/yp0zv0nbwik9d6wqgsrajwwqb1fsfd0p-hello-grpc', in 0.03 seconds)1929host1: must succeed: nix-store -r '/nix/store/yp0zv0nbwik9d6wqgsrajwwqb1fsfd0p-hello-grpc'1930host1 # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 10 ms (attempt 1/5)1931host1 # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 55 ms (attempt 2/5)1932host1 # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 342 ms (attempt 3/5)1933host1 # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 514 ms (attempt 4/5)1934host1 # disabling binary cache 'https://cache.nixos.org' for 60 seconds1935host1 # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org1936server # [ 24.345953] systemd[1]: Started Nix Daemon.1937server # [ 24.552678] nix-daemon[978]: accepted connection from pid 942, user nix-grpc-daemon (trusted)1938host1 # this path will be fetched (0.0 KiB download, 0.1 KiB unpacked):1939host1 # /nix/store/yp0zv0nbwik9d6wqgsrajwwqb1fsfd0p-hello-grpc1940host1 # copying path '/nix/store/yp0zv0nbwik9d6wqgsrajwwqb1fsfd0p-hello-grpc' from 'grpc://server:50051'...1941server # [ 24.606932] nix-grpc-daemon[942]: ts=2026-09-21T13:48:54Z level=info event=rpc method=FetchNars cn=host1 peer=ipv4:192.168.1.1:60888 duration_s=0 paths=1 nar_bytes_out=1281942host1 # warning: you did not specify '--add-root'; the result might be removed by the garbage collector1943host1: (finished: must succeed: nix-store -r '/nix/store/yp0zv0nbwik9d6wqgsrajwwqb1fsfd0p-hello-grpc', in 1.51 seconds)1944host1: must succeed: grep -q hello-over-grpc '/nix/store/yp0zv0nbwik9d6wqgsrajwwqb1fsfd0p-hello-grpc'1945host1: (finished: must succeed: grep -q hello-over-grpc '/nix/store/yp0zv0nbwik9d6wqgsrajwwqb1fsfd0p-hello-grpc', in 0.03 seconds)1946(finished: subtest: host1 substitutes the signed path over gRPC (read-only cert), in 1.57 seconds)1947subtest: read-only host1 cannot write1948host1: must succeed: echo deny > /root/denyfile1949host1: (finished: must succeed: echo deny > /root/denyfile, in 0.02 seconds)1950host1: must fail: nix store add --store 'grpc://server:50051?ca-cert=/run/root_ca.crt&client-cert=/var/lib/acme/host1/cert.pem&client-key=/var/lib/acme/host1/key.pem' /root/denyfile1951server # [ 24.939397] nix-grpc-daemon[942]: ts=2026-09-21T13:48:54Z level=info event=denied method=Connect cn=host1 role=read-only1952host1 # error: gRPC Connect on 'server:50051' failed: role 'read-only' may not call Connect1953host1 # error: cannot open connection to remote store 'grpc://server:50051': error: Nix daemon disconnected unexpectedly (maybe it crashed?)1954host1: (finished: must fail: nix store add --store 'grpc://server:50051?ca-cert=/run/root_ca.crt&client-cert=/var/lib/acme/host1/cert.pem&client-key=/var/lib/acme/host1/key.pem' /root/denyfile, in 0.24 seconds)1955server: must succeed: journalctl -u nix-grpc-daemon.service | grep -q 'event=denied .*cn=host1 role=read-only'1956server: (finished: must succeed: journalctl -u nix-grpc-daemon.service | grep -q 'event=denied .*cn=host1 role=read-only', in 0.06 seconds)1957(finished: subtest: read-only host1 cannot write, in 0.32 seconds)1958(finished: run the VM test script, in 25.87 seconds)1959test script finished in 25.99s1960cleanup1961kill QemuMachine (pid 47)1962host1 # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14)1963kill QemuMachine (pid 48)1964server # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14)1965(finished: cleanup, in 0.46 seconds)1966additionally exposed symbols:1967 host1, server,1968 vlan1,1969 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