tribuchet: building on eliza
Machine state will be reset. To keep it, pass --keep-machine-state
start all VLans
(finished: start all VLans, in 0.00 seconds)
Test will time out and terminate in 3600 seconds
run the VM test script
start all VMs
builder: starting vm
server: starting vm
builder # Disk image does not exist, creating the virtualisation disk image...
builder: QEMU running (pid 15)
builder # Formatting '/build/vm-state-builder/tmp.oVByY9ZqqZ', fmt=raw size=1073741824
server: QEMU running (pid 14)
builder # mke2fs 1.47.4 (6-Mar-2025)
server # Disk image does not exist, creating the virtualisation disk image...
builder # Discarding device blocks:      0/262144             done
server # Formatting '/build/vm-state-server/tmp.mk5caOFJF7', fmt=raw size=1073741824
builder # Creating filesystem with 262144 4k blocks and 65536 inodes
server # mke2fs 1.47.4 (6-Mar-2025)
builder # Filesystem UUID: ce261515-8a0c-47dc-bdd7-cfb92bd25d4a
server # Discarding device blocks:      0/262144             done
builder # Superblock backups stored on blocks:
server # Creating filesystem with 262144 4k blocks and 65536 inodes
builder # 	32768, 98304, 163840, 229376
server # Filesystem UUID: d7090602-9405-4fa5-a30c-77af36abbfda
builder # 
server # Superblock backups stored on blocks:
builder # Allocating group tables: 0/8   done
server # 	32768, 98304, 163840, 229376
builder # Writing inode tables: 0/8   done
server # 
builder # Creating journal (8192 blocks): done
server # Allocating group tables: 0/8   done
builder # Writing superblocks and filesystem accounting information: 0/8   done
server # Writing inode tables: 0/8   done
builder # 
server # Creating journal (8192 blocks): done
builder # Virtualisation disk image created.
server # Writing superblocks and filesystem accounting information: 0/8   done
(finished: start all VMs, in 0.48 seconds)
server # 
server: waiting for unit postgresql.service
server # Virtualisation disk image created.
server: waiting for the VM to finish booting
builder # [    0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]
builder # [    0.000000] Linux version 6.18.38 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Jul  4 11:44:22 UTC 2026
builder # [    0.000000] KASLR enabled
builder # [    0.000000] random: crng init done
builder # [    0.000000] Machine model: linux,dummy-virt
builder # [    0.000000] efi: UEFI not found.
builder # [    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT
builder # [    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]
builder # [    0.000000] NODE_DATA(0) allocated [mem 0x7fded740-0x7fdf0ebf]
builder # [    0.000000] Zone ranges:
builder # [    0.000000]   DMA      [mem 0x0000000040000000-0x000000007fffffff]
builder # [    0.000000]   DMA32    empty
server # [    0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]
builder # [    0.000000]   Normal   empty
builder # [    0.000000]   Device   empty
builder # [    0.000000] Movable zone start for each node
builder # [    0.000000] Early memory node ranges
server # [    0.000000] Linux version 6.18.38 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Jul  4 11:44:22 UTC 2026
server # [    0.000000] KASLR enabled
builder # [    0.000000]   node   0: [mem 0x0000000040000000-0x000000007fffffff]
server # [    0.000000] random: crng init done
server # [    0.000000] Machine model: linux,dummy-virt
builder # [    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]
server # [    0.000000] efi: UEFI not found.
builder # [    0.000000] cma: Reserved 32 MiB at 0x000000007cc00000
server # [    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT
builder # [    0.000000] psci: probing for conduit method from DT.
builder # [    0.000000] psci: PSCIv1.3 detected in firmware.
server # [    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]
builder # [    0.000000] psci: Using standard PSCI v0.2 function IDs
server # [    0.000000] NODE_DATA(0) allocated [mem 0x7fded740-0x7fdf0ebf]
server # [    0.000000] Zone ranges:
builder # [    0.000000] psci: Trusted OS migration not required
server # [    0.000000]   DMA      [mem 0x0000000040000000-0x000000007fffffff]
builder # [    0.000000] psci: SMC Calling Convention v1.1
server # [    0.000000]   DMA32    empty
server # [    0.000000]   Normal   empty
server # [    0.000000]   Device   empty
builder # [    0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)
server # [    0.000000] Movable zone start for each node
builder # [    0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296
server # [    0.000000] Early memory node ranges
server # [    0.000000]   node   0: [mem 0x0000000040000000-0x000000007fffffff]
builder # [    0.000000] Detected PIPT I-cache on CPU0
builder # [    0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)
server # [    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]
builder # [    0.000000] CPU features: detected: GICv3 CPU interface
server # [    0.000000] cma: Reserved 32 MiB at 0x000000007cc00000
builder # [    0.000000] CPU features: detected: Spectre-v4
server # [    0.000000] psci: probing for conduit method from DT.
builder # [    0.000000] CPU features: detected: Spectre-BHB
server # [    0.000000] psci: PSCIv1.3 detected in firmware.
builder # [    0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38
server # [    0.000000] psci: Using standard PSCI v0.2 function IDs
builder # [    0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23
server # [    0.000000] psci: Trusted OS migration not required
server # [    0.000000] psci: SMC Calling Convention v1.1
builder # [    0.000000] alternatives: applying boot alternatives
server # [    0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)
server # [    0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296
server # [    0.000000] Detected PIPT I-cache on CPU0
server # [    0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)
server # [    0.000000] CPU features: detected: GICv3 CPU interface
builder # [    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/dqadlb8kl4mj3k5f84kx2zisp0jziy88-nixos-system-builder-test/init regInfo=/nix/store/8bmg0y3wvchipc0nplcrgr4i2s7c9q53-closure-info/registration console=ttyAMA0,115200n8 console=tty0
server # [    0.000000] CPU features: detected: Spectre-v4
server # [    0.000000] CPU features: detected: Spectre-BHB
server # [    0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38
builder # [    0.000000] Unknown kernel command line parameters "regInfo=/nix/store/8bmg0y3wvchipc0nplcrgr4i2s7c9q53-closure-info/registration", will be passed to user space.
server # [    0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23
server # [    0.000000] alternatives: applying boot alternatives
builder # [    0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes
builder # [    0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
builder # [    0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
builder # [    0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB
builder # [    0.000000] software IO TLB: area num 1.
server # [    0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/jbygigwfnd3zdilmi52zhz7z5slg24lv-nixos-system-server-test/init regInfo=/nix/store/50y4h047mlbl40zapgmbn4wsaa6v4jcv-closure-info/registration console=ttyAMA0,115200n8 console=tty0
builder # [    0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)
builder # [    0.000000] Fallback order for Node 0: 0
builder # [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 262144
builder # [    0.000000] Policy zone: DMA
server # [    0.000000] Unknown kernel command line parameters "regInfo=/nix/store/50y4h047mlbl40zapgmbn4wsaa6v4jcv-closure-info/registration", will be passed to user space.
builder # [    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
server # [    0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes
builder # [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
server # [    0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
builder # [    0.000000] allocated 2097152 bytes of page_ext
builder # [    0.000000] ftrace: allocating 74713 entries in 292 pages
server # [    0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
builder # [    0.000000] ftrace: allocated 292 pages with 3 groups
server # [    0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB
builder # [    0.000000] rcu: Hierarchical RCU implementation.
server # [    0.000000] software IO TLB: area num 1.
builder # [    0.000000] rcu: 	RCU event tracing is enabled.
server # [    0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)
builder # [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
server # [    0.000000] Fallback order for Node 0: 0
builder # [    0.000000] 	Trampoline variant of Tasks RCU enabled.
server # [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 262144
builder # [    0.000000] 	Rude variant of Tasks RCU enabled.
server # [    0.000000] Policy zone: DMA
builder # [    0.000000] 	Tracing variant of Tasks RCU enabled.
server # [    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
builder # [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
server # [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
builder # [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
builder # [    0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
server # [    0.000000] allocated 2097152 bytes of page_ext
server # [    0.000000] ftrace: allocating 74713 entries in 292 pages
server # [    0.000000] ftrace: allocated 292 pages with 3 groups
server # [    0.000000] rcu: Hierarchical RCU implementation.
builder # [    0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
server # [    0.000000] rcu: 	RCU event tracing is enabled.
builder # [    0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
builder # [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
server # [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
server # [    0.000000] 	Trampoline variant of Tasks RCU enabled.
server # [    0.000000] 	Rude variant of Tasks RCU enabled.
builder # [    0.000000] GICv3: 256 SPIs implemented
builder # [    0.000000] GICv3: 0 Extended SPIs implemented
builder # [    0.000000] Root IRQ handler: gic_handle_irq
builder # [    0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI
builder # [    0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0
server # [    0.000000] 	Tracing variant of Tasks RCU enabled.
server # [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
server # [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
builder # [    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000
server # [    0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
builder # [    0.000000] ITS [mem 0x08080000-0x0809ffff]
server # [    0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
builder # [    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)
server # [    0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
server # [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
builder # [    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)
server # [    0.000000] GICv3: 256 SPIs implemented
builder # [    0.000000] GICv3: using LPI property table @0x0000000044d00000
server # [    0.000000] GICv3: 0 Extended SPIs implemented
server # [    0.000000] Root IRQ handler: gic_handle_irq
builder # [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000
server # [    0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI
builder # [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
server # [    0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0
builder # [    0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).
server # [    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000
server # [    0.000000] ITS [mem 0x08080000-0x0809ffff]
builder # [    0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)
builder # [    0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns
builder # [    0.000029] arm-pv: using stolen time PV
server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)
server # [    0.000000] GICv3: using LPI property table @0x0000000044d00000
builder # [    0.000451] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
builder # [    0.000607] Console: colour dummy device 80x25
server # [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000
builder # [    0.000614] printk: legacy console [tty0] enabled
server # [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
server # [    0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).
builder # [    0.000799] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)
builder # [    0.000806] pid_max: default: 32768 minimum: 301
server # [    0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
builder # [    0.000882] LSM: initializing lsm=capability,landlock,yama,bpf,ima
builder # [    0.001057] landlock: Up and running.
server # [    0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns
builder # [    0.001060] Yama: becoming mindful.
server # [    0.000030] arm-pv: using stolen time PV
builder # [    0.001524] LSM support for eBPF active
builder # [    0.001655] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
server # [    0.000385] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
server # [    0.000546] Console: colour dummy device 80x25
builder # [    0.001675] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
server # [    0.000554] printk: legacy console [tty0] enabled
builder # [    0.002714] cacheinfo: Unable to detect cache hierarchy for CPU 0
builder # [    0.003472] rcu: Hierarchical SRCU implementation.
server # [    0.000735] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)
builder # [    0.003476] rcu: 	Max phase no-delay instances is 1000.
server # [    0.000742] pid_max: default: 32768 minimum: 301
builder # [    0.004705] fsl-mc MSI: its@8080000 domain created
server # [    0.000821] LSM: initializing lsm=capability,landlock,yama,bpf,ima
builder # [    0.004794] EFI services will not be available.
server # [    0.000960] landlock: Up and running.
builder # [    0.004874] smp: Bringing up secondary CPUs ...
server # [    0.000962] Yama: becoming mindful.
builder # [    0.004883] smp: Brought up 1 node, 1 CPU
server # [    0.001410] LSM support for eBPF active
builder # [    0.004886] SMP: Total of 1 processors activated.
server # [    0.001539] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
builder # [    0.004889] CPU: All CPU(s) started at EL1
builder # [    0.004900] CPU features: detected: Branch Target Identification
server # [    0.001559] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
builder # [    0.004905] CPU features: detected: ARMv8.4 Translation Table Level
server # [    0.002674] cacheinfo: Unable to detect cache hierarchy for CPU 0
server # [    0.003371] rcu: Hierarchical SRCU implementation.
builder # [    0.004911] CPU features: detected: Instruction cache invalidation not required for I/D coherence
server # [    0.003375] rcu: 	Max phase no-delay instances is 1000.
server # [    0.004601] fsl-mc MSI: its@8080000 domain created
builder # [    0.004914] CPU features: detected: Data cache clean to the PoU not required for I/D coherence
server # [    0.004690] EFI services will not be available.
server # [    0.004754] smp: Bringing up secondary CPUs ...
builder # [    0.004918] CPU features: detected: Common not Private translations
server # [    0.004763] smp: Brought up 1 node, 1 CPU
builder # [    0.004921] CPU features: detected: CRC32 instructions
server # [    0.004766] SMP: Total of 1 processors activated.
server # [    0.004769] CPU: All CPU(s) started at EL1
builder # [    0.004924] CPU features: detected: Data cache clean to Point of Deep Persistence
server # [    0.004779] CPU features: detected: Branch Target Identification
builder # [    0.004927] CPU features: detected: Data cache clean to Point of Persistence
server # [    0.004784] CPU features: detected: ARMv8.4 Translation Table Level
builder # [    0.004930] CPU features: detected: Data independent timing control (DIT)
builder # [    0.004933] CPU features: detected: E0PD
server # [    0.004790] CPU features: detected: Instruction cache invalidation not required for I/D coherence
builder # [    0.004936] CPU features: detected: Enhanced Counter Virtualization
builder # [    0.004939] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)
builder # [    0.004943] CPU features: detected: Enhanced Virtualization Traps
server # [    0.004793] CPU features: detected: Data cache clean to the PoU not required for I/D coherence
server # [    0.004797] CPU features: detected: Common not Private translations
server # [    0.004800] CPU features: detected: CRC32 instructions
builder # [    0.004946] CPU features: detected: Fine Grained Traps
server # [    0.004802] CPU features: detected: Data cache clean to Point of Deep Persistence
builder # [    0.004950] CPU features: detected: Generic authentication (architected QARMA5 algorithm)
server # [    0.004806] CPU features: detected: Data cache clean to Point of Persistence
builder # [    0.004955] CPU features: detected: RCpc load-acquire (LDAPR)
server # [    0.004809] CPU features: detected: Data independent timing control (DIT)
builder # [    0.004957] CPU features: detected: LSE atomic instructions
server # [    0.004812] CPU features: detected: E0PD
builder # [    0.004960] CPU features: detected: Privileged Access Never
server # [    0.004815] CPU features: detected: Enhanced Counter Virtualization
builder # [    0.004963] CPU features: detected: PMUv3
server # [    0.004818] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)
builder # [    0.004966] CPU features: detected: RAS Extension Support
server # [    0.004822] CPU features: detected: Enhanced Virtualization Traps
builder # [    0.004969] CPU features: detected: RASv1p1 Extension Support
server # [    0.004825] CPU features: detected: Fine Grained Traps
builder # [    0.004971] CPU features: detected: Random Number Generator
builder # [    0.004974] CPU features: detected: Speculation barrier (SB)
server # [    0.004828] CPU features: detected: Generic authentication (architected QARMA5 algorithm)
builder # [    0.004977] CPU features: detected: Stage-2 Force Write-Back
server # [    0.004833] CPU features: detected: RCpc load-acquire (LDAPR)
server # [    0.004836] CPU features: detected: LSE atomic instructions
builder # [    0.004980] CPU features: detected: TLB range maintenance instructions
builder # [    0.004985] CPU features: detected: Speculative Store Bypassing Safe (SSBS)
server # [    0.004838] CPU features: detected: Privileged Access Never
server # [    0.004841] CPU features: detected: PMUv3
builder # [    0.005021] alternatives: applying system-wide alternatives
server # [    0.004844] CPU features: detected: RAS Extension Support
server # [    0.004847] CPU features: detected: RASv1p1 Extension Support
server # [    0.004849] CPU features: detected: Random Number Generator
server # [    0.004852] CPU features: detected: Speculation barrier (SB)
server # [    0.004854] CPU features: detected: Stage-2 Force Write-Back
server # [    0.004857] CPU features: detected: TLB range maintenance instructions
server # [    0.004861] CPU features: detected: Speculative Store Bypassing Safe (SSBS)
server # [    0.004898] alternatives: applying system-wide alternatives
server # [    0.007848] CPU features: detected: BBM Level 2 without TLB conflict abort
server # [    0.008021] Memory: 895096K/1048576K available (24384K kernel code, 7086K rwdata, 26324K rodata, 4736K init, 1102K bss, 112244K reserved, 32768K cma-reserved)
builder # [    0.007993] CPU features: detected: BBM Level 2 without TLB conflict abort
builder # [    0.008246] Memory: 895068K/1048576K available (24384K kernel code, 7086K rwdata, 26324K rodata, 4736K init, 1102K bss, 112244K reserved, 32768K cma-reserved)
builder # [    0.008587] devtmpfs: initialized
builder # [    0.010276] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
builder # [    0.010300] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)
builder # [    0.010321] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).
builder # [    0.010518] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL
builder # [    0.010523] 0 pages in range for non-PLT usage
builder # [    0.010524] 508320 pages in range for PLT usage
builder # [    0.010646] pinctrl core: initialized pinctrl subsystem
server # [    0.008380] devtmpfs: initialized
builder # [    0.011449] DMI not present or invalid.
builder # [    0.014792] NET: Registered PF_NETLINK/PF_ROUTE protocol family
server # [    0.010045] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
server # [    0.010068] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)
builder # [    0.017041] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
builder # [    0.017183] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
server # [    0.010088] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).
server # [    0.010281] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL
builder # [    0.017343] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
server # [    0.010286] 0 pages in range for non-PLT usage
builder # [    0.017365] audit: initializing netlink subsys (disabled)
server # [    0.010287] 508320 pages in range for PLT usage
builder # [    0.017950] thermal_sys: Registered thermal governor 'fair_share'
server # [    0.010400] pinctrl core: initialized pinctrl subsystem
builder # [    0.017952] thermal_sys: Registered thermal governor 'bang_bang'
server # [    0.011183] DMI not present or invalid.
server # [    0.014211] NET: Registered PF_NETLINK/PF_ROUTE protocol family
builder # [    0.017956] thermal_sys: Registered thermal governor 'step_wise'
builder # [    0.017959] thermal_sys: Registered thermal governor 'user_space'
server # [    0.016689] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
builder # [    0.017962] thermal_sys: Registered thermal governor 'power_allocator'
server # [    0.016837] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
builder # [    0.017997] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1
server # [    0.016997] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
builder # [    0.018006] cpuidle: using governor ladder
server # [    0.017019] audit: initializing netlink subsys (disabled)
builder # [    0.018012] cpuidle: using governor menu
server # [    0.017601] thermal_sys: Registered thermal governor 'fair_share'
builder # [    0.018198] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.
server # [    0.017604] thermal_sys: Registered thermal governor 'bang_bang'
builder # [    0.018215] ASID allocator initialised with 65536 entries
builder # [    0.019397] Serial: AMBA PL011 UART driver
server # [    0.017607] thermal_sys: Registered thermal governor 'step_wise'
server # [    0.017610] thermal_sys: Registered thermal governor 'user_space'
builder # [    0.024590] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1
builder # [    0.024715] printk: console [ttyAMA0] enabled
server # [    0.017613] thermal_sys: Registered thermal governor 'power_allocator'
server # [    0.017637] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1
server # [    0.017647] cpuidle: using governor ladder
builder # [    0.154372] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
server # [    0.017653] cpuidle: using governor menu
builder # [    0.154397] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page
server # [    0.017864] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.
builder # [    0.154402] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages
server # [    0.017880] ASID allocator initialised with 65536 entries
server # [    0.018974] Serial: AMBA PL011 UART driver
builder # [    0.154407] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page
builder # [    0.154411] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
server # [    0.024141] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1
server # [    0.024288] printk: console [ttyAMA0] enabled
builder # [    0.154415] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page
builder # [    0.154419] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages
server # [    0.151956] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
builder # [    0.154423] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page
server # [    0.151980] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page
server # [    0.151985] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages
server # [    0.151989] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page
builder # [    0.162307] fbcon: Taking over console
builder # [    0.162323] ACPI: Interpreter disabled.
server # [    0.151994] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
builder # [    0.164211] iommu: Default domain type: Translated
server # [    0.151998] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page
builder # [    0.164222] iommu: DMA domain TLB invalidation policy: strict mode
server # [    0.152002] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages
builder # [    0.166061] SCSI subsystem initialized
server # [    0.152006] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page
server # [    0.159716] fbcon: Taking over console
server # [    0.159734] ACPI: Interpreter disabled.
server # [    0.165907] iommu: Default domain type: Translated
builder # [    0.173476] usbcore: registered new interface driver usbfs
builder # [    0.173515] usbcore: registered new interface driver hub
server # [    0.165917] iommu: DMA domain TLB invalidation policy: strict mode
builder # [    0.173539] usbcore: registered new device driver usb
server # [    0.167674] SCSI subsystem initialized
builder # [    0.173851] pps_core: LinuxPPS API ver. 1 registered
server # [    0.168347] usbcore: registered new interface driver usbfs
builder # [    0.173857] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
builder # [    0.173868] PTP clock support registered
server # [    0.168378] usbcore: registered new interface driver hub
builder # [    0.173908] EDAC MC: Ver: 3.0.0
server # [    0.168402] usbcore: registered new device driver usb
server # [    0.168666] pps_core: LinuxPPS API ver. 1 registered
builder # [    0.178721] scmi_core: SCMI protocol bus registered
server # [    0.168673] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
builder # [    0.179719] FPGA manager framework
server # [    0.168683] PTP clock support registered
builder # [    0.180810] vgaarb: loaded
server # [    0.168745] EDAC MC: Ver: 3.0.0
server # [    0.173560] scmi_core: SCMI protocol bus registered
server # [    0.174606] FPGA manager framework
server # [    0.175587] vgaarb: loaded
server # [    0.176250] clocksource: Switched to clocksource arch_sys_counter
builder # [    0.181481] clocksource: Switched to clocksource arch_sys_counter
builder # [    0.182749] VFS: Disk quotas dquot_6.6.0
builder # [    0.182781] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
server # [    0.176721] VFS: Disk quotas dquot_6.6.0
builder # [    0.184283] netfs: FS-Cache loaded
builder # [    0.184407] pnp: PnP ACPI: disabled
server # [    0.176747] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
server # [    0.181795] netfs: FS-Cache loaded
server # [    0.181910] pnp: PnP ACPI: disabled
builder # [    0.190441] NET: Registered PF_INET protocol family
builder # [    0.190602] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
server # [    0.188093] NET: Registered PF_INET protocol family
server # [    0.188925] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
builder # [    0.221052] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
builder # [    0.221104] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
builder # [    0.221132] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
builder # [    0.221180] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
builder # [    0.221256] TCP: Hash tables configured (established 8192 bind 8192)
builder # [    0.221374] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)
builder # [    0.221428] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)
builder # [    0.221477] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)
server # [    0.218588] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
server # [    0.218647] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
server # [    0.218672] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
server # [    0.218715] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
server # [    0.218795] TCP: Hash tables configured (established 8192 bind 8192)
server # [    0.218886] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)
builder # [    0.229050] NET: Registered PF_UNIX/PF_LOCAL protocol family
server # [    0.218942] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)
builder # [    0.229081] NET: Registered PF_XDP protocol family
builder # [    0.229101] PCI: CLS 0 bytes, default 64
server # [    0.218991] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)
builder # [    0.229360] Trying to unpack rootfs image as initramfs...
server # [    0.219081] NET: Registered PF_UNIX/PF_LOCAL protocol family
server # [    0.219103] NET: Registered PF_XDP protocol family
server # [    0.219123] PCI: CLS 0 bytes, default 64
server # [    0.219402] Trying to unpack rootfs image as initramfs...
builder # [    0.240187] kvm [1]: HYP mode not available
server # [    0.234209] kvm [1]: HYP mode not available
server # [    0.330472] Initialise system trusted keyrings
builder # [    0.338639] Initialise system trusted keyrings
server # [    0.331244] workingset: timestamp_bits=42 max_order=18 bucket_order=0
builder # [    0.339404] workingset: timestamp_bits=42 max_order=18 bucket_order=0
server # [    0.332549] squashfs: version 4.0 (2009/01/31) Phillip Lougher
builder # [    0.340697] squashfs: version 4.0 (2009/01/31) Phillip Lougher
server # [    0.333353] 9p: Installing v9fs 9p2000 file system support
builder # [    0.341520] 9p: Installing v9fs 9p2000 file system support
server # [    0.361364] Key type asymmetric registered
builder # [    0.369517] Key type asymmetric registered
server # [    0.361391] Asymmetric key parser 'x509' registered
builder # [    0.369546] Asymmetric key parser 'x509' registered
server # [    0.361457] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)
builder # [    0.369617] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)
server # [    0.363656] io scheduler mq-deadline registered
builder # [    0.371819] io scheduler mq-deadline registered
server # [    0.363667] io scheduler kyber registered
builder # [    0.371832] io scheduler kyber registered
builder # [    0.381710] pl061_gpio 9030000.pl061: PL061 GPIO chip registered
builder # [    0.383102] ledtrig-cpu: registered to indicate activity on CPUs
builder # [    0.383489] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:
builder # [    0.383506] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
builder # [    0.383519] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
builder # [    0.383527] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000
builder # [    0.383548] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits
builder # [    0.383573] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]
server # [    0.376930] pl061_gpio 9030000.pl061: PL061 GPIO chip registered
builder # [    0.383644] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00
server # [    0.377509] ledtrig-cpu: registered to indicate activity on CPUs
builder # [    0.383653] pci_bus 0000:00: root bus resource [bus 00-ff]
server # [    0.377883] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:
builder # [    0.383659] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
server # [    0.377899] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
builder # [    0.383665] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]
server # [    0.377919] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
builder # [    0.383670] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]
builder # [    0.383727] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint
server # [    0.377927] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000
builder # [    0.384201] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
server # [    0.377948] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits
builder # [    0.384400] pci 0000:00:01.0: BAR 0 [io  0x0000-0x001f]
server # [    0.377969] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]
builder # [    0.384416] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.378041] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00
builder # [    0.384446] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.378058] pci_bus 0000:00: root bus resource [bus 00-ff]
builder # [    0.384462] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]
server # [    0.378064] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
builder # [    0.384926] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
server # [    0.378070] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]
builder # [    0.385111] pci 0000:00:02.0: BAR 0 [io  0x0000-0x001f]
server # [    0.378075] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]
builder # [    0.385126] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]
builder # [    0.385156] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.378139] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint
server # [    0.378581] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
server # [    0.378779] pci 0000:00:01.0: BAR 0 [io  0x0000-0x001f]
server # [    0.378796] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.378827] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.378844] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]
server # [    0.379302] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
builder # [    0.405491] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
server # [    0.379490] pci 0000:00:02.0: BAR 0 [io  0x0000-0x001f]
builder # [    0.405680] pci 0000:00:03.0: BAR 0 [io  0x0000-0x003f]
server # [    0.379507] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]
builder # [    0.405696] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.379537] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
builder # [    0.405726] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.380000] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
builder # [    0.406195] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
server # [    0.380187] pci 0000:00:03.0: BAR 0 [io  0x0000-0x003f]
builder # [    0.406383] pci 0000:00:04.0: BAR 0 [io  0x0000-0x001f]
server # [    0.380204] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]
builder # [    0.406398] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.380235] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
builder # [    0.406428] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.380731] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
builder # [    0.406888] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
server # [    0.380919] pci 0000:00:04.0: BAR 0 [io  0x0000-0x001f]
builder # [    0.407075] pci 0000:00:05.0: BAR 0 [io  0x0000-0x001f]
server # [    0.380936] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]
builder # [    0.407091] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.380966] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
builder # [    0.407121] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.381437] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
builder # [    0.407620] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
server # [    0.381628] pci 0000:00:05.0: BAR 0 [io  0x0000-0x001f]
builder # [    0.407808] pci 0000:00:06.0: BAR 0 [io  0x0000-0x007f]
server # [    0.381645] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]
builder # [    0.407823] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.381675] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
builder # [    0.407853] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.382157] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
builder # [    0.408310] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
server # [    0.382346] pci 0000:00:06.0: BAR 0 [io  0x0000-0x007f]
builder # [    0.408498] pci 0000:00:07.0: BAR 0 [io  0x0000-0x001f]
server # [    0.382363] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]
builder # [    0.408514] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.382393] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
builder # [    0.408544] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
builder # [    0.408560] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]
server # [    0.382852] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
server # [    0.383040] pci 0000:00:07.0: BAR 0 [io  0x0000-0x001f]
builder # [    0.409021] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint
server # [    0.383057] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]
builder # [    0.409212] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.383087] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
builder # [    0.409242] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.383103] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]
server # [    0.383573] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint
server # [    0.383765] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.383796] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
builder # [    0.437836] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint
builder # [    0.438050] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]
builder # [    0.438080] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
builder # [    0.438483] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint
builder # [    0.438669] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]
builder # [    0.438917] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
builder # [    0.439207] pci 0000:00:0b.0: BAR 0 [io  0x0000-0x003f]
server # [    0.432308] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint
builder # [    0.439225] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.432540] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]
builder # [    0.439259] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.432572] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
builder # [    0.439730] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
server # [    0.433009] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint
builder # [    0.439913] pci 0000:00:0c.0: BAR 0 [io  0x0000-0x001f]
server # [    0.433194] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]
builder # [    0.439929] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    0.433453] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
builder # [    0.439958] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.433759] pci 0000:00:0b.0: BAR 0 [io  0x0000-0x003f]
builder # [    0.440543] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned
server # [    0.433776] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]
builder # [    0.440554] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned
server # [    0.433806] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
builder # [    0.440560] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned
server # [    0.434267] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
builder # [    0.440606] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned
server # [    0.434455] pci 0000:00:0c.0: BAR 0 [io  0x0000-0x001f]
server # [    0.434471] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]
builder # [    0.440654] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned
server # [    0.434501] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
builder # [    0.440702] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned
server # [    0.435258] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned
builder # [    0.440750] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned
server # [    0.435271] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned
builder # [    0.440798] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned
server # [    0.435276] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned
builder # [    0.440848] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned
server # [    0.435322] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned
builder # [    0.440896] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned
server # [    0.435370] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned
builder # [    0.440944] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned
server # [    0.435418] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned
builder # [    0.440991] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned
server # [    0.435467] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned
builder # [    0.441051] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned
server # [    0.435515] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned
builder # [    0.441098] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned
server # [    0.435563] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned
builder # [    0.441120] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned
server # [    0.435612] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned
builder # [    0.441141] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned
builder # [    0.441163] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned
server # [    0.435661] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned
builder # [    0.441185] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned
server # [    0.435708] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned
builder # [    0.441208] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned
server # [    0.435770] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned
builder # [    0.441232] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned
server # [    0.435818] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned
builder # [    0.441255] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned
server # [    0.435840] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned
builder # [    0.441277] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned
server # [    0.435862] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned
builder # [    0.441299] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned
server # [    0.435885] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned
builder # [    0.441322] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned
server # [    0.435907] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned
builder # [    0.441345] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned
server # [    0.435930] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned
builder # [    0.441367] pci 0000:00:06.0: BAR 0 [io  0x1000-0x107f]: assigned
server # [    0.435955] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned
builder # [    0.441389] pci 0000:00:03.0: BAR 0 [io  0x1080-0x10bf]: assigned
server # [    0.435978] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned
builder # [    0.441411] pci 0000:00:0b.0: BAR 0 [io  0x10c0-0x10ff]: assigned
server # [    0.436001] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned
builder # [    0.441432] pci 0000:00:01.0: BAR 0 [io  0x1100-0x111f]: assigned
server # [    0.436024] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned
builder # [    0.441454] pci 0000:00:02.0: BAR 0 [io  0x1120-0x113f]: assigned
server # [    0.436047] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned
builder # [    0.441475] pci 0000:00:04.0: BAR 0 [io  0x1140-0x115f]: assigned
server # [    0.436070] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned
server # [    0.436092] pci 0000:00:06.0: BAR 0 [io  0x1000-0x107f]: assigned
server # [    0.436115] pci 0000:00:03.0: BAR 0 [io  0x1080-0x10bf]: assigned
server # [    0.436137] pci 0000:00:0b.0: BAR 0 [io  0x10c0-0x10ff]: assigned
server # [    0.436159] pci 0000:00:01.0: BAR 0 [io  0x1100-0x111f]: assigned
server # [    0.436181] pci 0000:00:02.0: BAR 0 [io  0x1120-0x113f]: assigned
server # [    0.436204] pci 0000:00:04.0: BAR 0 [io  0x1140-0x115f]: assigned
server # [    0.436226] pci 0000:00:05.0: BAR 0 [io  0x1160-0x117f]: assigned
server # [    0.436249] pci 0000:00:07.0: BAR 0 [io  0x1180-0x119f]: assigned
server # [    0.436287] pci 0000:00:0c.0: BAR 0 [io  0x11a0-0x11bf]: assigned
server # [    0.436315] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]
builder # [    0.485576] pci 0000:00:05.0: BAR 0 [io  0x1160-0x117f]: assigned
server # [    0.436325] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]
builder # [    0.485609] pci 0000:00:07.0: BAR 0 [io  0x1180-0x119f]: assigned
server # [    0.436330] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]
builder # [    0.485633] pci 0000:00:0c.0: BAR 0 [io  0x11a0-0x11bf]: assigned
server # [    0.437514] pci 0000:00:0a.0: enabling device (0000 -> 0002)
builder # [    0.485667] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]
builder # [    0.485678] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]
builder # [    0.485682] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]
builder # [    0.486889] pci 0000:00:0a.0: enabling device (0000 -> 0002)
builder # [    0.512326] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)
server # [    0.506552] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)
builder # [    0.518655] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)
builder # [    0.520641] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)
server # [    0.516473] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)
builder # [    0.530925] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)
server # [    0.520045] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)
builder # [    0.532820] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)
server # [    0.522252] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)
server # [    0.524203] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)
server # [    0.534279] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)
builder # [    0.544157] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)
builder # [    0.547093] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)
builder # [    0.549354] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)
server # [    0.540423] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)
server # [    0.542829] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)
builder # [    0.559216] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)
builder # [    0.561007] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)
server # [    0.552479] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)
server # [    0.554353] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)
builder # [    0.571779] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)
server # [    0.564467] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)
builder # [    0.582771] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
server # [    0.574721] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
builder # [    0.585572] msm_serial: driver initialized
builder # [    0.585722] SuperH (H)SCI(F) driver initialized
builder # [    0.585783] STM32 USART driver initialized
server # [    0.581426] msm_serial: driver initialized
server # [    0.581569] SuperH (H)SCI(F) driver initialized
server # [    0.581623] STM32 USART driver initialized
builder # [    0.620633] loop: module loaded
builder # [    0.620824] virtio_blk virtio5: 1/0/0 default/read/poll queues
server # [    0.613905] loop: module loaded
builder # [    0.622839] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
server # [    0.614088] virtio_blk virtio5: 1/0/0 default/read/poll queues
server # [    0.614833] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
builder # [    0.626123] megasas: 07.734.00.00-rc1
builder # [    0.626790] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]
server # [    0.620847] megasas: 07.734.00.00-rc1
server # [    0.621473] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]
server # [    0.623405] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000
server # [    0.623429] Intel/Sharp Extended Query Table at 0x0031
builder # [    0.638587] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000
builder # [    0.638621] Intel/Sharp Extended Query Table at 0x0031
server # [    0.633038] Using buffer write method
builder # [    0.640237] Using buffer write method
server # [    0.633114] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]
builder # [    0.640307] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]
server # [    0.634600] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000
server # [    0.634657] Intel/Sharp Extended Query Table at 0x0031
server # [    0.636236] Using buffer write method
server # [    0.648300] Concatenating MTD devices:
server # [    0.648306] (0): "0.flash"
server # [    0.648311] (1): "0.flash"
server # [    0.648314] into device "0.flash"
builder # [    0.656244] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000
builder # [    0.656277] Intel/Sharp Extended Query Table at 0x0031
builder # [    0.659863] Using buffer write method
builder # [    0.659893] Concatenating MTD devices:
builder # [    0.659896] (0): "0.flash"
builder # [    0.659900] (1): "0.flash"
builder # [    0.659904] into device "0.flash"
server # [    0.883880] Freeing initrd memory: 25516K
server # [    0.889990] tun: Universal TUN/TAP device driver, 1.6
builder # [    0.900801] Freeing initrd memory: 25516K
server # [    0.893819] thunder_xcv, ver 1.0
server # [    0.893859] thunder_bgx, ver 1.0
server # [    0.893884] nicpf, ver 1.0
server # [    0.894426] e1000: Intel(R) PRO/1000 Network Driver
builder # [    0.907038] tun: Universal TUN/TAP device driver, 1.6
server # [    0.894433] e1000: Copyright (c) 1999-2006 Intel Corporation.
server # [    0.894460] e1000e: Intel(R) PRO/1000 Network Driver
server # [    0.894467] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
server # [    0.894499] igb: Intel(R) Gigabit Ethernet Network Driver
server # [    0.894505] igb: Copyright (c) 2007-2014 Intel Corporation.
server # [    0.894527] igbvf: Intel(R) Gigabit Virtual Function Network Driver
server # [    0.894534] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
server # [    0.894665] sky2: driver version 1.30
server # [    0.896251] usbcore: registered new interface driver usb-storage
server # [    0.896996] ehci-pci 0000:00:0a.0: EHCI Host Controller
builder # [    0.911062] thunder_xcv, ver 1.0
builder # [    0.911113] thunder_bgx, ver 1.0
builder # [    0.911138] nicpf, ver 1.0
server # [    0.897024] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1
builder # [    0.911691] e1000: Intel(R) PRO/1000 Network Driver
server # [    0.897184] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000
builder # [    0.911698] e1000: Copyright (c) 1999-2006 Intel Corporation.
builder # [    0.911727] e1000e: Intel(R) PRO/1000 Network Driver
server # [    0.907667] usbcore: registered new interface driver usbserial_generic
builder # [    0.911734] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
server # [    0.907683] usbserial: USB Serial support registered for generic
builder # [    0.911765] igb: Intel(R) Gigabit Ethernet Network Driver
server # [    0.909874] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00
builder # [    0.911771] igb: Copyright (c) 2007-2014 Intel Corporation.
server # [    0.910924] hub 1-0:1.0: USB hub found
builder # [    0.911794] igbvf: Intel(R) Gigabit Virtual Function Network Driver
server # [    0.911426] hub 1-0:1.0: 6 ports detected
builder # [    0.911800] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
builder # [    0.911936] sky2: driver version 1.30
server # [    0.912392] hv_vmbus: registering driver hyperv_keyboard
builder # [    0.921594] ehci-pci 0000:00:0a.0: EHCI Host Controller
builder # [    0.921623] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1
server # [    0.913880] rtc-pl031 9010000.pl031: registered as rtc0
builder # [    0.921848] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000
server # [    0.913908] rtc-pl031 9010000.pl031: setting system clock to 2026-07-07T08:15:42 UTC (1783412142)
server # [    0.914226] i2c_dev: i2c /dev entries driver
builder # [    0.924404] usbcore: registered new interface driver usb-storage
builder # [    0.924534] usbcore: registered new interface driver usbserial_generic
builder # [    0.924548] usbserial: USB Serial support registered for generic
builder # [    0.925141] hv_vmbus: registering driver hyperv_keyboard
server # [    0.919324] sdhci: Secure Digital Host Controller Interface driver
server # [    0.919336] sdhci: Copyright(c) Pierre Ossman
builder # [    0.929038] rtc-pl031 9010000.pl031: registered as rtc0
server # [    0.919597] Synopsys Designware Multimedia Card Interface Driver
server # [    0.919967] sdhci-pltfm: SDHCI platform and OF driver helper
builder # [    0.929067] rtc-pl031 9010000.pl031: setting system clock to 2026-07-07T08:15:42 UTC (1783412142)
builder # [    0.929382] i2c_dev: i2c /dev entries driver
server # [    0.924349] hid: raw HID events driver (C) Jiri Kosina
server # [    0.924594] usbcore: registered new interface driver usbhid
server # [    0.924600] usbhid: USB HID core driver
server # [    0.927509] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available
builder # [    0.936111] sdhci: Secure Digital Host Controller Interface driver
builder # [    0.936124] sdhci: Copyright(c) Pierre Ossman
server # [    0.930160] drop_monitor: Initializing network drop monitor service
builder # [    0.936402] Synopsys Designware Multimedia Card Interface Driver
server # [    0.930328] NET: Registered PF_INET6 protocol family
builder # [    0.936789] sdhci-pltfm: SDHCI platform and OF driver helper
builder # [    0.940332] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00
server # [    0.932532] Segment Routing with IPv6
server # [    0.932564] In-situ OAM (IOAM) with IPv6
builder # [    0.941423] hub 1-0:1.0: USB hub found
server # [    0.932597] NET: Registered PF_PACKET protocol family
builder # [    0.942150] hub 1-0:1.0: 6 ports detected
server # [    0.934297] 9pnet: Installing 9P2000 support
builder # [    0.944797] hid: raw HID events driver (C) Jiri Kosina
server # [    0.937011] Key type dns_resolver registered
builder # [    0.946678] usbcore: registered new interface driver usbhid
builder # [    0.946703] usbhid: USB HID core driver
server # [    0.943413] registered taskstats version 1
server # [    0.943573] Loading compiled-in X.509 certificates
builder # [    0.951111] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available
builder # [    0.956679] drop_monitor: Initializing network drop monitor service
builder # [    0.956939] NET: Registered PF_INET6 protocol family
builder # [    0.959586] Segment Routing with IPv6
builder # [    0.959608] In-situ OAM (IOAM) with IPv6
server # [    0.951950] Demotion targets for Node 0: null
builder # [    0.959638] NET: Registered PF_PACKET protocol family
server # [    0.952053] Key type .fscrypt registered
builder # [    0.961342] 9pnet: Installing 9P2000 support
server # [    0.952060] Key type fscrypt-provisioning registered
server # [    0.952153] ima: No TPM chip found, activating TPM-bypass!
server # [    0.952172] ima: Allocated hash algorithm: sha1
server # [    0.952195] ima: No architecture policies found
server # [    0.956528] input: gpio-keys as /devices/platform/gpio-keys/input/input0
builder # [    0.964282] Key type dns_resolver registered
builder # [    0.976840] registered taskstats version 1
builder # [    0.977149] Loading compiled-in X.509 certificates
server # [    0.974126] clk: Disabling unused clocks
server # [    0.974153] PM: genpd: Disabling unused power domains
server # [    0.978557] Freeing unused kernel memory: 4736K
server # [    0.978773] Run /init as init process
builder # [    0.991001] Demotion targets for Node 0: null
builder # [    0.991150] Key type .fscrypt registered
builder # [    0.991158] Key type fscrypt-provisioning registered
builder # [    0.991276] ima: No TPM chip found, activating TPM-bypass!
builder # [    0.991296] ima: Allocated hash algorithm: sha1
builder # [    0.991322] ima: No architecture policies found
builder # [    0.995764] input: gpio-keys as /devices/platform/gpio-keys/input/input0
server # [    0.994602] systemd[1]: Successfully made /usr/ read-only.
builder # [    1.014557] clk: Disabling unused clocks
builder # [    1.014594] PM: genpd: Disabling unused power domains
builder # [    1.019169] Freeing unused kernel memory: 4736K
builder # [    1.019385] Run /init as init process
builder # [    1.036065] systemd[1]: Successfully made /usr/ read-only.
server # [    1.156384] usb 1-1: new high-speed USB device number 2 using ehci-pci
builder # [    1.189560] usb 1-1: new high-speed USB device number 2 using ehci-pci
server # [    1.306176] 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/input1
server # [    1.329926] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)
server # [    1.343144] systemd[1]: Detected virtualization qemu.
builder # [    1.347983] 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/input1
server # [    1.345487] systemd[1]: Detected architecture arm64.
server # [    1.347578] systemd[1]: Running in initrd.
server # [    1.351055] systemd[1]: Initializing machine ID from random generator.
server # [    1.354406] systemd[1]: Hostname set to <server>.
builder # [    1.371143] systemd[1]: systemd 260.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)
builder # [    1.383620] systemd[1]: Detected virtualization qemu.
builder # [    1.385723] systemd[1]: Detected architecture arm64.
builder # [    1.387675] systemd[1]: Running in initrd.
builder # [    1.390530] systemd[1]: Initializing machine ID from random generator.
builder # [    1.393441] systemd[1]: Hostname set to <builder>.
server # [    1.392686] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0
builder # [    1.434120] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0
server # [    1.464607] systemd[1]: Queued start job for default target Initrd Default Target.
server # [    1.474289] systemd[1]: Created slice Slice /system/modprobe.
server # [    1.475439] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
server # [    1.476726] systemd[1]: Expecting device /dev/disk/by-label/nixos...
server # [    1.477725] systemd[1]: Reached target Path Units.
server # [    1.478489] systemd[1]: Reached target Slice Units.
server # [    1.479353] systemd[1]: Reached target Swaps.
server # [    1.480055] systemd[1]: Reached target Timer Units.
server # [    1.481033] systemd[1]: Listening on D-Bus System Message Bus Socket.
server # [    1.482221] systemd[1]: Listening on Journal Socket (/dev/log).
server # [    1.483299] systemd[1]: Listening on Journal Sockets.
server # [    1.484209] systemd[1]: Listening on udev Control Socket.
server # [    1.485213] systemd[1]: Listening on udev Kernel Socket.
server # [    1.485237] systemd[1]: Reached target Socket Units.
server # [    1.488193] systemd[1]: Starting Create List of Static Device Nodes...
server # [    1.496470] systemd[1]: Starting Load Kernel Module 9pnet_virtio...
server # [    1.496564] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs
server # [    1.504514] systemd[1]: Mounting Kernel Configuration File System...
builder # [    1.519279] systemd[1]: Queued start job for default target Initrd Default Target.
server # [    1.518550] usb 1-2: new high-speed USB device number 3 using ehci-pci
builder # [    1.527613] systemd[1]: Created slice Slice /system/modprobe.
builder # [    1.528850] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
builder # [    1.530293] systemd[1]: Expecting device /dev/disk/by-label/nixos...
builder # [    1.531372] systemd[1]: Reached target Path Units.
builder # [    1.532198] systemd[1]: Reached target Slice Units.
builder # [    1.533098] systemd[1]: Reached target Swaps.
builder # [    1.533951] systemd[1]: Reached target Timer Units.
builder # [    1.534133] systemd[1]: Listening on D-Bus System Message Bus Socket.
builder # [    1.534315] systemd[1]: Listening on Journal Socket (/dev/log).
builder # [    1.534488] systemd[1]: Listening on Journal Sockets.
builder # [    1.534621] systemd[1]: Listening on udev Control Socket.
server # [    1.532430] systemd[1]: Starting Journal Service...
builder # [    1.534731] systemd[1]: Listening on udev Kernel Socket.
builder # [    1.534754] systemd[1]: Reached target Socket Units.
builder # [    1.542742] systemd[1]: Starting Create List of Static Device Nodes...
server # [    1.538366] systemd[1]: Starting Load Kernel Modules...
server # [    1.539278] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki
server # [    1.542153] systemd[1]: Starting Coldplug All udev Devices...
builder # [    1.553190] systemd[1]: Starting Load Kernel Module 9pnet_virtio...
builder # [    1.554514] usb 1-2: new high-speed USB device number 3 using ehci-pci
builder # [    1.555973] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs
server # [    1.552136] systemd[1]: Finished Create List of Static Device Nodes.
builder # [    1.559493] systemd[1]: Mounting Kernel Configuration File System...
server # [    1.554758] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.
server # [    1.564446] systemd[1]: Finished Load Kernel Module 9pnet_virtio.
server # [    1.564994] systemd[1]: Mounted Kernel Configuration File System.
builder # [    1.577958] systemd[1]: Starting Journal Service...
server # [    1.584611] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
builder # [    1.597424] systemd[1]: Starting Load Kernel Modules...
builder # [    1.598419] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki
server # [    1.591860] systemd-journald[73]: Collecting audit messages is disabled.
builder # [    1.601120] systemd[1]: Starting Coldplug All udev Devices...
builder # [    1.608318] systemd[1]: Finished Create List of Static Device Nodes.
builder # [    1.609240] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.
server # [    1.601057] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
builder # [    1.625780] systemd[1]: Finished Load Kernel Module 9pnet_virtio.
builder # [    1.626359] systemd[1]: Mounted Kernel Configuration File System.
server # [    1.620327] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev
server # [    1.624650] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0
server # [    1.624880] [drm] features: -virgl +edid -resource_blob -host_visible
server # [    1.624890] [drm] features: -context_init
server # [    1.625573] [drm] number of scanouts: 1
server # [    1.625591] [drm] number of cap sets: 0
server # [    1.631175] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
server # [    1.633645] systemd[1]: Starting Create Static Device Nodes in /dev...
builder # [    1.645739] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
builder # [    1.654192] systemd-journald[73]: Collecting audit messages is disabled.
server # [    1.648574] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic
server # [    1.648590] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0
builder # [    1.664486] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
builder # [    1.673656] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev
builder # [    1.678169] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0
builder # [    1.678405] [drm] features: -virgl +edid -resource_blob -host_visible
builder # [    1.678414] [drm] features: -context_init
builder # [    1.679166] [drm] number of scanouts: 1
builder # [    1.679184] [drm] number of cap sets: 0
builder # [    1.690082] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
server # [    1.676624] Console: switching to colour frame buffer device 160x50
builder # [    1.693826] systemd[1]: Starting Create Static Device Nodes in /dev...
server # [    1.694894] systemd[1]: Finished Create Static Device Nodes in /dev.
server # [    1.695077] systemd[1]: Reached target Preparation for Local File Systems.
server # [    1.695102] systemd[1]: Reached target Local File Systems.
server # [    1.700614] systemd[1]: Starting Rule-based Manager for Device Events and Files...
builder # [    1.705834] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic
builder # [    1.705853] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0
server # [    1.702440] 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/input2
server # [    1.702597] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0
builder # [    1.715396] 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/input2
builder # [    1.717314] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0
server # [    1.720953] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device
server # [    1.739991] systemd[1]: Finished Load Kernel Modules.
builder # [    1.754087] systemd[1]: Finished Create Static Device Nodes in /dev.
builder # [    1.754314] systemd[1]: Reached target Preparation for Local File Systems.
builder # [    1.754339] systemd[1]: Reached target Local File Systems.
server # [    1.744825] systemd[1]: Starting Apply Kernel Variables...
builder # [    1.758246] systemd[1]: Starting Rule-based Manager for Device Events and Files...
builder # [    1.769549] Console: switching to colour frame buffer device 160x50
builder # [    1.770212] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device
builder # [    1.788603] systemd[1]: Finished Load Kernel Modules.
builder # [    1.791637] systemd[1]: Starting Apply Kernel Variables...
server # [    1.806832] systemd[1]: Started Journal Service.
server # [    1.804290] systemd-modules-load[74]: Inserted module 'dm_mod'
server # [    1.808348] systemd-modules-load[74]: Module 'virtio_balloon' is built in
server # [    1.809568] systemd-modules-load[74]: Module 'virtio_console' is built in
server # [    1.810699] systemd-modules-load[74]: Inserted module 'virtio_gpu'
server # [    1.811727] systemd-modules-load[74]: Module 'virtio_rng' is built in
builder # [    1.840110] systemd[1]: Finished Apply Kernel Variables.
server # [    1.828479] systemd[1]: Starting Create System Files and Directories...
server # [    1.829888] systemd-udevd[80]: Using default interface naming scheme 'v260'.
server # [    1.831068] systemd[1]: Finished Apply Kernel Variables.
builder # [    1.854610] systemd[1]: Started Journal Service.
builder # [    1.848291] systemd-modules-load[74]: Inserted module 'dm_mod'
builder # [    1.851435] systemd-modules-load[74]: Module 'virtio_balloon' is built in
server # [    1.845038] systemd[1]: Finished Create System Files and Directories.
builder # [    1.852697] systemd-modules-load[74]: Module 'virtio_console' is built in
builder # [    1.855212] systemd-modules-load[74]: Inserted module 'virtio_gpu'
builder # [    1.860388] systemd-modules-load[74]: Module 'virtio_rng' is built in
builder # [    1.863365] systemd-udevd[81]: Using default interface naming scheme 'v260'.
builder # [    1.872471] systemd[1]: Starting Create System Files and Directories...
server # [    1.865624] systemd[1]: Started Rule-based Manager for Device Events and Files.
builder # [    1.885991] systemd[1]: Finished Create System Files and Directories.
builder # [    1.901112] systemd[1]: Started Rule-based Manager for Device Events and Files.
server # [    1.935554] systemd[1]: Starting Virtual Console Setup...
builder # [    1.976116] systemd[1]: Starting Virtual Console Setup...
server # [    2.012476] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.
server # [    2.020160] systemd[1]: Finished Virtual Console Setup.
builder # [    2.038971] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.
builder # [    2.048244] systemd[1]: Finished Virtual Console Setup.
server # [    2.681559] systemd[1]: Finished Coldplug All udev Devices.
server # [    2.682524] systemd[1]: Reached target System Initialization.
server # [    2.683364] systemd[1]: Reached target Basic System.
builder # [    2.733104] systemd[1]: Finished Coldplug All udev Devices.
builder # [    2.734752] systemd[1]: Reached target System Initialization.
builder # [    2.735578] systemd[1]: Reached target Basic System.
server # [    2.861992] (udev-worker)[97]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
server # [    2.873381] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.
server # [    2.879169] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.
builder # [    2.903176] (udev-worker)[103]: Network interface NamePolicy= disabled on kernel command line.
builder # [    2.925021] (udev-worker)[102]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
builder # [    2.929134] (udev-worker)[102]: Network interface NamePolicy= disabled on kernel command line.
server # [    2.931821] systemd[1]: Found device /dev/disk/by-label/nixos.
server # [    2.937117] systemd[1]: Reached target Initrd Root Device.
server # [    2.940162] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...
builder # [    2.971322] systemd[1]: Found device /dev/disk/by-label/nixos.
builder # [    2.974169] systemd[1]: Reached target Initrd Root Device.
builder # [    2.976144] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...
server # [    2.994164] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks
server # [    3.000287] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.
server # [    3.009524] systemd[1]: Mounting /sysroot...
builder # [    3.023927] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks
builder # [    3.031135] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.
builder # [    3.036460] systemd[1]: Mounting /sysroot...
server # [    3.060060] EXT4-fs (vda): mounted filesystem d7090602-9405-4fa5-a30c-77af36abbfda r/w with ordered data mode. Quota mode: none.
server # [    3.051979] systemd[1]: Mounted /sysroot.
server # [    3.057436] systemd[1]: Reached target Initrd Root File System.
server # [    3.060205] systemd[1]: Starting Mountpoints Configured in the Real Root...
builder # [    3.108311] EXT4-fs (vda): mounted filesystem ce261515-8a0c-47dc-bdd7-cfb92bd25d4a r/w with ordered data mode. Quota mode: none.
builder # [    3.096830] systemd[1]: Mounted /sysroot.
server # [    3.089590] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.
builder # [    3.099626] systemd[1]: Reached target Initrd Root File System.
builder # [    3.104675] systemd[1]: Starting Mountpoints Configured in the Real Root...
server # [    3.098171] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...
server # [    3.100791] systemd[1]: Reloading...
builder # [    3.138427] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.
builder # [    3.146939] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...
builder # [    3.150933] systemd[1]: Reloading...
server # [    3.334599] systemd[1]: Reloading finished in 237 ms.
server # [    3.347450] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...
server # [    3.352340] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...
server # [    3.356167] systemd[1]: Starting Load Kernel Module 9pnet_virtio...
server # [    3.366087] systemd[1]: initrd-parse-etc.service: Deactivated successfully.
server # [    3.373626] systemd[1]: Finished Mountpoints Configured in the Real Root.
server # [    3.376719] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.
server # [    3.389888] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.
server # [    3.391350] systemd[1]: Finished Load Kernel Module 9pnet_virtio.
builder # [    3.411099] systemd[1]: Reloading finished in 264 ms.
builder # [    3.434511] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...
builder # [    3.437976] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...
builder # [    3.444553] systemd[1]: Starting Load Kernel Module 9pnet_virtio...
builder # [    3.453393] systemd[1]: initrd-parse-etc.service: Deactivated successfully.
builder # [    3.456817] systemd[1]: Finished Mountpoints Configured in the Real Root.
builder # [    3.457851] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.
builder # [    3.478176] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.
builder # [    3.479631] systemd[1]: Finished Load Kernel Module 9pnet_virtio.
server # [    3.531439] systemd[1]: Mounting /sysroot/nix/.ro-store...
server # [    3.548138] systemd[1]: Mounting /sysroot/nix/.rw-store...
server # [    3.557623] systemd[1]: Mounting /sysroot/run...
builder # [    3.587915] systemd[1]: Mounting /sysroot/nix/.ro-store...
server # [    3.590182] systemd[1]: Mounting /sysroot/tmp/shared...
builder # [    3.607836] systemd[1]: Mounting /sysroot/nix/.rw-store...
server # [    3.604871] systemd[1]: Mounting /sysroot/tmp/xchg...
builder # [    3.611951] systemd[1]: Mounting /sysroot/run...
server # [    3.608683] systemd[1]: Mounted /sysroot/nix/.rw-store.
builder # [    3.623893] systemd[1]: Mounting /sysroot/tmp/shared...
server # [    3.627632] systemd[1]: Mounted /sysroot/nix/.ro-store.
builder # [    3.646198] systemd[1]: Mounting /sysroot/tmp/xchg...
server # [    3.640513] systemd[1]: Mounted /sysroot/run.
server # [    3.662243] systemd[1]: Starting rw-sysroot-nix-store.service...
server # [    3.667261] systemd[1]: Mounted /sysroot/tmp/shared.
server # [    3.675037] systemd[1]: Mounted /sysroot/tmp/xchg.
builder # [    3.695376] systemd[1]: Mounted /sysroot/nix/.ro-store.
builder # [    3.703931] systemd[1]: Mounted /sysroot/nix/.rw-store.
builder # [    3.708243] systemd[1]: Mounted /sysroot/run.
server # [    3.700627] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.
server # [    3.704654] systemd[1]: Finished rw-sysroot-nix-store.service.
server # [    3.709746] systemd[1]: Mounting /sysroot/nix/store...
builder # [    3.726852] systemd[1]: Starting rw-sysroot-nix-store.service...
builder # [    3.730297] systemd[1]: Mounted /sysroot/tmp/shared.
builder # [    3.735614] systemd[1]: Mounted /sysroot/tmp/xchg.
server # [    3.751362] systemd[1]: Mounted /sysroot/nix/store.
server # [    3.753685] systemd[1]: Reached target Initrd File Systems.
server # [    3.756740] systemd[1]: Starting Find NixOS closure...
builder # [    3.764402] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.
builder # [    3.768354] systemd[1]: Finished rw-sysroot-nix-store.service.
builder # [    3.773104] systemd[1]: Mounting /sysroot/nix/store...
server # [    3.765833] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...
builder # [    3.814008] systemd[1]: Mounted /sysroot/nix/store.
server # [    3.808621] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.
builder # [    3.815968] systemd[1]: Reached target Initrd File Systems.
builder # [    3.818054] systemd[1]: Starting Find NixOS closure...
server # [    3.821542] systemd[1]: Finished Find NixOS closure.
server # [    3.824785] systemd[1]: Reached target Initrd Default Target.
builder # [    3.834763] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...
server # [    3.827993] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...
server # [    3.857674] systemd[1]: Stopped target Initrd Default Target.
builder # [    3.864469] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.
server # [    3.860612] systemd[1]: Stopped target Basic System.
server # [    3.861696] systemd[1]: Stopped target Initrd Root Device.
server # [    3.864249] systemd[1]: Stopped target Path Units.
server # [    3.868152] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
server # [    3.871198] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.
server # [    3.874931] systemd[1]: Stopped target Slice Units.
builder # [    3.883239] systemd[1]: Finished Find NixOS closure.
builder # [    3.884197] systemd[1]: Reached target Initrd Default Target.
server # [    3.880151] systemd[1]: Stopped target Socket Units.
server # [    3.882240] systemd[1]: Stopped target System Initialization.
builder # [    3.889575] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...
server # [    3.884120] systemd[1]: Stopped target Swaps.
server # [    3.885970] systemd[1]: Stopped target Timer Units.
server # [    3.886755] systemd[1]: dbus.socket: Deactivated successfully.
server # [    3.892722] systemd[1]: Closed D-Bus System Message Bus Socket.
server # [    3.894561] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.
server # [    3.896655] systemd[1]: Stopped Find NixOS closure.
server # [    3.898126] systemd[1]: Starting Load Kernel Module 9pnet_virtio...
server # [    3.905857] systemd[1]: Starting rw-sysroot-nix-store.service...
server # [    3.907872] systemd[1]: systemd-sysctl.service: Deactivated successfully.
server # [    3.910157] systemd[1]: Stopped Apply Kernel Variables.
server # [    3.910954] systemd[1]: systemd-modules-load.service: Deactivated successfully.
server # [    3.911994] systemd[1]: Stopped Load Kernel Modules.
builder # [    3.922760] systemd[1]: Stopped target Initrd Default Target.
server # [    3.918861] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.
builder # [    3.928387] systemd[1]: Stopped target Basic System.
server # [    3.922508] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.
builder # [    3.930003] systemd[1]: Stopped target Initrd Root Device.
builder # [    3.931945] systemd[1]: Stopped target Path Units.
builder # [    3.932816] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
builder # [    3.935783] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.
server # [    3.932483] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
builder # [    3.940493] systemd[1]: Stopped target Slice Units.
server # [    3.936255] systemd[1]: Stopped Create System Files and Directories.
builder # [    3.945324] systemd[1]: Stopped target Socket Units.
builder # [    3.947284] systemd[1]: Stopped target System Initialization.
server # [    3.940386] systemd[1]: Stopped target Local File Systems.
builder # [    3.948258] systemd[1]: Stopped target Swaps.
builder # [    3.950533] systemd[1]: Stopped target Timer Units.
server # [    3.944411] systemd[1]: Stopped target Preparation for Local File Systems.
server # [    3.947293] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
builder # [    3.956183] systemd[1]: dbus.socket: Deactivated successfully.
builder # [    3.957841] systemd[1]: Closed D-Bus System Message Bus Socket.
server # [    3.952585] systemd[1]: Stopped Coldplug All udev Devices.
builder # [    3.959856] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.
server # [    3.953456] systemd[1]: Stopping Rule-based Manager for Device Events and Files...
builder # [    3.961929] systemd[1]: Stopped Find NixOS closure.
server # [    3.954850] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
server # [    3.958907] systemd[1]: Stopped Virtual Console Setup.
builder # [    3.965792] systemd[1]: Starting Load Kernel Module 9pnet_virtio...
server # [    3.959811] systemd[1]: initrd-cleanup.service: Deactivated successfully.
server # [    3.961031] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.
builder # [    3.969374] systemd[1]: Starting rw-sysroot-nix-store.service...
server # [    3.964213] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.
builder # [    3.971481] systemd[1]: systemd-sysctl.service: Deactivated successfully.
server # [    3.965287] systemd[1]: Finished rw-sysroot-nix-store.service.
server # [    3.966804] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.
server # [    3.967868] systemd[1]: Finished Load Kernel Module 9pnet_virtio.
builder # [    3.977242] systemd[1]: Stopped Apply Kernel Variables.
builder # [    3.978060] systemd[1]: systemd-modules-load.service: Deactivated successfully.
builder # [    3.979087] systemd[1]: Stopped Load Kernel Modules.
builder # [    3.979793] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.
server # [    3.976277] systemd[1]: systemd-udevd.service: Deactivated successfully.
server # [    3.978627] systemd[1]: Stopped Rule-based Manager for Device Events and Files.
builder # [    3.986949] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.
builder # [    3.990813] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
server # [    3.984682] systemd[1]: systemd-udevd.service: Consumed 1.013s CPU time over 2.290s wall clock time, 15.8M memory peak.
server # [    3.986310] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
server # [    3.987335] systemd[1]: Closed udev Control Socket.
server # [    3.988163] systemd[1]: Starting Cleanup udev Database...
server # [    3.990005] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
server # [    3.991794] systemd[1]: Stopped Create Static Device Nodes in /dev.
server # [    3.993173] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.
builder # [    4.000332] systemd[1]: Stopped Create System Files and Directories.
server # [    3.994379] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.
server # [    3.995367] systemd[1]: kmod-static-nodes.service: Deactivated successfully.
server # [    3.996381] systemd[1]: Stopped Create List of Static Device Nodes.
builder # [    4.005503] systemd[1]: Stopped target Local File Systems.
builder # [    4.007182] systemd[1]: Stopped target Preparation for Local File Systems.
builder # [    4.016434] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
builder # [    4.019146] systemd[1]: Stopped Coldplug All udev Devices.
builder # [    4.020364] systemd[1]: Stopping Rule-based Manager for Device Events and Files...
builder # [    4.021664] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
builder # [    4.024249] systemd[1]: Stopped Virtual Console Setup.
builder # [    4.027493] systemd[1]: initrd-cleanup.service: Deactivated successfully.
builder # [    4.028795] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.
builder # [    4.029719] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.
builder # [    4.030737] systemd[1]: Finished rw-sysroot-nix-store.service.
builder # [    4.031574] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.
server # [    4.028564] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
server # [    4.032713] systemd[1]: Finished Cleanup udev Database.
builder # [    4.040391] systemd[1]: Finished Load Kernel Module 9pnet_virtio.
server # [    4.033741] systemd[1]: Reached target Switch Root.
builder # [    4.041443] systemd[1]: systemd-udevd.service: Deactivated successfully.
server # [    4.035902] systemd[1]: Starting NixOS Activation...
builder # [    4.043979] systemd[1]: Stopped Rule-based Manager for Device Events and Files.
builder # [    4.045496] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
builder # [    4.047068] systemd[1]: Closed udev Control Socket.
builder # [    4.047895] systemd[1]: Starting Cleanup udev Database...
builder # [    4.048958] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
builder # [    4.050026] systemd[1]: Stopped Create Static Device Nodes in /dev.
builder # [    4.050904] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.
builder # [    4.054562] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.
builder # [    4.060353] systemd[1]: kmod-static-nodes.service: Deactivated successfully.
builder # [    4.061551] systemd[1]: Stopped Create List of Static Device Nodes.
builder # [    4.080946] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
builder # [    4.083880] systemd[1]: Finished Cleanup udev Database.
builder # [    4.085251] systemd[1]: Reached target Switch Root.
builder # [    4.086414] systemd[1]: Starting NixOS Activation...
server # [    4.209623] initrd-nixos-activation-start[172]: booting system configuration /nix/store/jbygigwfnd3zdilmi52zhz7z5slg24lv-nixos-system-server-test
builder # [    4.257368] initrd-nixos-activation-start[173]: booting system configuration /nix/store/dqadlb8kl4mj3k5f84kx2zisp0jziy88-nixos-system-builder-test
server # [    4.271139] initrd-nixos-activation-start[172]: running activation script...
builder # [    4.318814] initrd-nixos-activation-start[173]: running activation script...
server # [    4.653885] initrd-nixos-activation-start[195]: setting up /etc...
builder # [    4.750988] initrd-nixos-activation-start[196]: setting up /etc...
server # [    4.907597] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.
server # [    4.910659] systemd[1]: Finished NixOS Activation.
server # [    4.912122] systemd[1]: Starting Switch Root...
server # [    4.940771] systemd[1]: Switching root.
builder # [    4.973323] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.
builder # [    4.976380] systemd[1]: Finished NixOS Activation.
builder # [    4.980265] systemd[1]: Starting Switch Root...
server # [    4.999068] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).
builder # [    4.997024] systemd[1]: Switching root.
builder # [    5.068371] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).
server # [    5.590160] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)
server # [    5.595791] systemd[1]: Detected virtualization qemu.
server # [    5.597950] systemd[1]: Detected architecture arm64.
server # [    5.600587] systemd[1]: Detected first boot.
server # [    5.607479] systemd[1]: Initializing machine ID from random generator.
builder # [    5.632597] systemd[1]: systemd 260.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)
builder # [    5.632665] systemd[1]: Detected virtualization qemu.
builder # [    5.632729] systemd[1]: Detected architecture arm64.
builder # [    5.632863] systemd[1]: Detected first boot.
builder # [    5.650686] systemd[1]: Initializing machine ID from random generator.
builder # [    5.942175] systemd[1]: bpf-restrict-fs: LSM BPF program attached
server # [    5.940114] systemd[1]: bpf-restrict-fs: LSM BPF program attached
builder # [    6.193027] systemd[1]: Applying preset policy.
server # [    6.433583] systemd[1]: Applying preset policy.
builder # [    7.277092] systemd[1]: Populated /etc with preset unit settings.
server # [    7.367574] systemd[1]: Populated /etc with preset unit settings.
builder # [    7.799608] systemd[1]: initrd-switch-root.service: Deactivated successfully.
builder # [    7.801027] systemd[1]: Stopped initrd-switch-root.service.
builder # [    7.805006] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
builder # [    7.809948] systemd[1]: Created slice Slice /system/getty.
builder # [    7.814113] systemd[1]: Created slice User and Session Slice.
builder # [    7.815941] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
builder # [    7.818091] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
builder # [    7.819815] systemd[1]: Expecting device /dev/hvc0...
builder # [    7.821203] systemd[1]: Expecting device /dev/ttyAMA0...
builder # [    7.822786] systemd[1]: Reached target Local Encrypted Volumes.
builder # [    7.824263] systemd[1]: Stopped target initrd-fs.target.
builder # [    7.825774] systemd[1]: Stopped target initrd-root-fs.target.
builder # [    7.827198] systemd[1]: Stopped target initrd-switch-root.target.
builder # [    7.828702] systemd[1]: Reached target Virtual Machines and Containers.
builder # [    7.830532] systemd[1]: Reached target Path Units.
builder # [    7.831960] systemd[1]: Reached target Remote File Systems.
builder # [    7.833605] systemd[1]: Reached target Slice Units.
builder # [    7.835127] systemd[1]: Reached target Swaps.
builder # [    7.839536] systemd[1]: Listening on Query the User Interactively for a Password.
builder # [    7.844835] systemd[1]: Listening on Process Core Dump Socket.
builder # [    7.849624] systemd[1]: Listening on Credential Encryption/Decryption.
builder # [    7.854574] systemd[1]: Listening on Factory Reset Management.
builder # [    7.864267] systemd[1]: Starting Journal Log Access Socket...
builder # [    7.867125] systemd[1]: Listening on Journal Audit Socket.
builder # [    7.874917] systemd[1]: Listening on Console Output Muting Service Socket.
server # [    7.868133] systemd[1]: initrd-switch-root.service: Deactivated successfully.
server # [    7.870094] systemd[1]: Stopped initrd-switch-root.service.
builder # [    7.877641] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
builder # [    7.879997] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki
server # [    7.873586] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
builder # [    7.882763] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki
server # [    7.877181] systemd[1]: Created slice Slice /system/getty.
server # [    7.879283] systemd[1]: Created slice User and Session Slice.
server # [    7.881770] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
server # [    7.884229] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
server # [    7.886885] systemd[1]: Expecting device /dev/hvc0...
builder # [    7.896494] systemd[1]: Listening on Disk Repartitioning Service Socket.
server # [    7.889060] systemd[1]: Expecting device /dev/ttyAMA0...
server # [    7.891243] systemd[1]: Reached target Local Encrypted Volumes.
builder # [    7.899658] systemd[1]: Listening on udev Control Socket.
builder # [    7.901966] systemd[1]: Listening on udev Varlink Socket.
server # [    7.893423] systemd[1]: Stopped target initrd-fs.target.
server # [    7.895571] systemd[1]: Stopped target initrd-root-fs.target.
server # [    7.898174] systemd[1]: Stopped target initrd-switch-root.target.
server # [    7.899714] systemd[1]: Reached target Virtual Machines and Containers.
builder # [    7.909159] systemd[1]: Mounting Huge Pages File System...
server # [    7.902306] systemd[1]: Reached target Path Units.
server # [    7.903583] systemd[1]: Reached target Remote File Systems.
server # [    7.905254] systemd[1]: Reached target Slice Units.
server # [    7.907329] systemd[1]: Reached target Swaps.
server # [    7.912192] systemd[1]: Listening on Query the User Interactively for a Password.
builder # [    7.921946] systemd[1]: Mounting POSIX Message Queue File System...
server # [    7.918046] systemd[1]: Listening on Process Core Dump Socket.
server # [    7.922744] systemd[1]: Listening on Credential Encryption/Decryption.
builder # [    7.934461] systemd[1]: Mounting Kernel Debug File System...
server # [    7.927401] systemd[1]: Listening on Factory Reset Management.
server # [    7.935211] systemd[1]: Starting Journal Log Access Socket...
server # [    7.938414] systemd[1]: Listening on Journal Audit Socket.
builder # [    7.951236] systemd[1]: Mounting Kernel Trace File System...
server # [    7.944952] systemd[1]: Listening on Console Output Muting Service Socket.
server # [    7.946836] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
server # [    7.948766] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki
server # [    7.951927] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki
builder # [    7.968147] systemd[1]: Starting Create List of Static Device Nodes...
server # [    7.961734] systemd[1]: Listening on Disk Repartitioning Service Socket.
server # [    7.963348] systemd[1]: Listening on udev Control Socket.
server # [    7.964908] systemd[1]: Listening on udev Varlink Socket.
server # [    7.969933] systemd[1]: Mounting Huge Pages File System...
server # [    7.975095] systemd[1]: Mounting POSIX Message Queue File System...
builder # [    7.984876] systemd[1]: Starting Load Kernel Module 9pnet_virtio...
builder # [    7.987671] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs
server # [    7.980838] systemd[1]: Mounting Kernel Debug File System...
builder # [    8.002185] systemd[1]: Mounting Kernel Configuration File System...
server # [    7.996051] systemd[1]: Mounting Kernel Trace File System...
builder # [    8.004138] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm
builder # [    8.011179] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore
server # [    8.014301] systemd[1]: Starting Create List of Static Device Nodes...
server # [    8.021888] systemd[1]: Starting Load Kernel Module 9pnet_virtio...
server # [    8.023928] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs
builder # [    8.048541] systemd[1]: Starting Load Kernel Module fuse...
server # [    8.040167] systemd[1]: Mounting Kernel Configuration File System...
server # [    8.042820] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm
builder # [    8.051763] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67
server # [    8.046808] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore
server # [    8.060553] systemd[1]: Starting Load Kernel Module fuse...
server # [    8.062213] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67
builder # [    8.120195] systemd[1]: Starting Journal Service...
server # [    8.122522] systemd[1]: Starting Journal Service...
builder # [    8.153304] systemd[1]: Starting Load Kernel Modules...
server # [    8.166396] systemd[1]: Starting Load Kernel Modules...
builder # [    8.185431] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
server # [    8.179126] fuse: init (API version 7.45)
server # [    8.193119] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
builder # [    8.217871] systemd[1]: Starting Remount Root and Kernel File Systems...
builder # [    8.220633] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki
server # [    8.214438] systemd[1]: Starting Remount Root and Kernel File Systems...
builder # [    8.225249] fuse: init (API version 7.45)
server # [    8.223365] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki
builder # [    8.241222] systemd[1]: Starting Coldplug All udev Devices...
builder # [    8.244243] systemd[1]: Listening on Journal Log Access Socket.
builder # [    8.244683] systemd[1]: Mounted Huge Pages File System.
builder # [    8.245089] systemd[1]: Mounted POSIX Message Queue File System.
builder # [    8.245472] systemd[1]: Mounted Kernel Debug File System.
server # [    8.252030] systemd[1]: Starting Coldplug All udev Devices...
server # [    8.277799] systemd[1]: Listening on Journal Log Access Socket.
builder # [    8.287721] systemd-journald[267]: Collecting audit messages is enabled.
builder # [    8.293884] systemd[1]: Mounted Kernel Trace File System.
server # [    8.294225] systemd[1]: Mounted Huge Pages File System.
server # [    8.299924] systemd[1]: Mounted POSIX Message Queue File System.
server # [    8.303157] systemd-journald[266]: Collecting audit messages is enabled.
builder # [    8.310220] systemd[1]: Finished Create List of Static Device Nodes.
builder # [    8.311140] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.
builder # [    8.311714] systemd[1]: Finished Load Kernel Module 9pnet_virtio.
builder # [    8.312217] systemd[1]: Mounted Kernel Configuration File System.
builder # [    8.312772] systemd[1]: modprobe@fuse.service: Deactivated successfully.
builder # [    8.313312] systemd[1]: Finished Load Kernel Module fuse.
builder # [    8.303415] systemd[1]: Queued start job for default target Multi-User System.
builder # [    8.305400] systemd[1]: systemd-journald.service: Deactivated successfully.
builder # [    8.306837] systemd-modules-load[268]: Module 'atkbd' is built in
server # [    8.314466] systemd[1]: Mounted Kernel Debug File System.
builder # [    8.307892] systemd-modules-load[268]: Module 'loop' is built in
server # [    8.315186] systemd[1]: Mounted Kernel Trace File System.
server # [    8.305942] systemd[1]: Queued start job for default target Multi-User System.
server # [    8.323993] systemd[1]: Finished Create List of Static Device Nodes.
builder # [    8.334017] systemd[1]: Started Journal Service.
server # [    8.329472] systemd[1]: Started Journal Service.
builder # [    8.323897] systemd[1]: Finished Load Kernel Modules.
server # [    8.321491] systemd[1]: systemd-journald.service: Deactivated successfully.
server # [    8.333782] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.
server # [    8.345104] systemd-modules-load[267]: Module 'atkbd' is built in
server # [    8.346245] systemd-modules-load[267]: Module 'loop' is built in
server # [    8.347245] systemd[1]: Finished Load Kernel Module 9pnet_virtio.
server # [    8.366494] EXT4-fs (vda): re-mounted d7090602-9405-4fa5-a30c-77af36abbfda.
builder # [    8.362991] systemd-oomd[269]: No swap; memory pressure usage will be degraded
builder # [    8.370603] systemd[1]: Mounting FUSE Control File System...
server # [    8.364314] systemd[1]: Mounted Kernel Configuration File System.
server # [    8.368857] systemd[1]: modprobe@fuse.service: Deactivated successfully.
builder # [    8.376596] systemd[1]: Starting Firewall...
server # [    8.370787] systemd[1]: Finished Load Kernel Module fuse.
builder # [    8.381154] systemd[1]: Starting Apply Kernel Variables...
server # [    8.376308] systemd-modules-load[267]: Inserted module 'tls'
builder # [    8.401773] EXT4-fs (vda): re-mounted ce261515-8a0c-47dc-bdd7-cfb92bd25d4a.
server # [    8.381843] systemd[1]: Mounting FUSE Control File System...
builder # [    8.394910] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
server # [    8.388707] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
server # [    8.392312] systemd[1]: Finished Load Kernel Modules.
builder # [    8.413005] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
builder # [    8.416486] systemd[1]: Finished Remount Root and Kernel File Systems.
server # [    8.415424] systemd[1]: Finished Remount Root and Kernel File Systems.
server # [    8.437346] systemd-oomd[268]: No swap; memory pressure usage will be degraded
builder # [    8.447620] systemd[1]: Listening on Disk Image Download Service Socket.
server # [    8.453723] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
builder # [    8.463949] systemd[1]: Starting Flush Journal to Persistent Storage...
builder # [    8.472474] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore
server # [    8.474062] systemd[1]: Listening on Disk Image Download Service Socket.
server # [    8.481915] systemd[1]: Starting Firewall...
server # [    8.500165] systemd[1]: Starting Flush Journal to Persistent Storage...
server # [    8.502918] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore
builder # [    8.510621] systemd[1]: Starting Load/Save OS Random Seed...
builder # [    8.516424] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki
server # [    8.517363] systemd[1]: Starting Load/Save OS Random Seed...
server # [    8.557498] systemd[1]: Starting Apply Kernel Variables...
server # [    8.559181] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki
builder # [    8.583333] systemd[1]: Finished Apply Kernel Variables.
server # [    8.583634] systemd[1]: Mounted FUSE Control File System.
builder # [    8.618696] systemd-journald[267]: Received client request to flush runtime journal.
server # [    8.606010] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
server # [    8.632690] systemd[1]: Starting Create Static Device Nodes in /dev...
server # [    8.651633] systemd-journald[266]: Received client request to flush runtime journal.
builder # [    8.685732] systemd[1]: Mounted FUSE Control File System.
builder # [    8.695786] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
builder # [    8.707273] systemd[1]: Starting Create Static Device Nodes in /dev...
builder # [    8.712580] systemd[1]: Finished Flush Journal to Persistent Storage.
builder # [    8.715196] systemd[1]: Finished Load/Save OS Random Seed.
builder # [    8.717188] systemd[1]: Reached target First Boot Complete.
server # [    8.721221] systemd[1]: Finished Load/Save OS Random Seed.
server # [    8.728774] systemd[1]: Reached target First Boot Complete.
server # [    8.733911] systemd[1]: Finished Flush Journal to Persistent Storage.
server # [    8.776687] systemd[1]: Finished Apply Kernel Variables.
server # [    8.803487] systemd[1]: Finished Create Static Device Nodes in /dev.
server # [    8.808251] systemd[1]: Reached target Preparation for Local File Systems.
server # [    8.811842] systemd[1]: Starting Rule-based Manager for Device Events and Files...
builder # [    8.835748] systemd[1]: Finished Create Static Device Nodes in /dev.
builder # [    8.840637] systemd[1]: Reached target Preparation for Local File Systems.
builder # [    8.847997] systemd[1]: Mounting /run/wrappers...
builder # [    8.852897] systemd[1]: Starting Rule-based Manager for Device Events and Files...
server # [    8.859957] systemd[1]: Mounting /run/wrappers...
builder # [    8.936475] systemd[1]: Mounted /run/wrappers.
server # [    8.930310] systemd[1]: Mounted /run/wrappers.
builder # [    8.938609] systemd[1]: Reached target Local File Systems.
server # [    8.932308] systemd[1]: Reached target Local File Systems.
server # [    8.937358] systemd[1]: Listening on Boot Loader Control Service Socket.
builder # [    8.944515] systemd[1]: Listening on Boot Loader Control Service Socket.
server # [    8.948247] systemd[1]: Starting register-nix-paths.service...
builder # [    8.956151] systemd[1]: Starting register-nix-paths.service...
builder # [    8.967135] systemd[1]: Starting Create SUID/SGID Wrappers...
builder # [    8.972489] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.
builder # [    8.977868] systemd[1]: Starting Save Transient machine-id to Disk...
server # [    8.963243] systemd[1]: Starting Create SUID/SGID Wrappers...
server # [    8.972664] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.
server # [    8.988179] systemd[1]: Starting Save Transient machine-id to Disk...
builder # [    9.020874] systemd[1]: Starting Create System Files and Directories...
server # [    9.027947] systemd[1]: Starting Create System Files and Directories...
builder # [    9.146162] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
builder # [    9.166098] systemd[1]: Finished Save Transient machine-id to Disk.
server # [    9.180223] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
server # [    9.205820] systemd[1]: Finished Save Transient machine-id to Disk.
server # [    9.303108] systemd[1]: Finished Create System Files and Directories.
server # [    9.322788] systemd[1]: Starting Rebuild Journal Catalog...
server # [    9.335986] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
builder # [    9.345855] systemd[1]: Finished Create System Files and Directories.
builder # [    9.372313] systemd[1]: Starting Rebuild Journal Catalog...
builder # [    9.378518] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
server # [    9.484761] systemd-udevd[304]: Using default interface naming scheme 'v260'.
server # [    9.532698] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
builder # [    9.563259] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
builder # [    9.572958] systemd-udevd[315]: Using default interface naming scheme 'v260'.
server # [    9.595682] systemd[1]: Finished Rebuild Journal Catalog.
server # [    9.611259] systemd[1]: Starting Update is Completed...
builder # [    9.641496] systemd[1]: Finished Rebuild Journal Catalog.
builder # [    9.655894] systemd[1]: Starting Update is Completed...
server # [    9.740588] systemd[1]: Finished Update is Completed.
builder # [    9.773319] systemd[1]: Finished Update is Completed.
server # [    9.824894] systemd[1]: Started Rule-based Manager for Device Events and Files.
builder # [    9.901294] systemd[1]: Started Rule-based Manager for Device Events and Files.
server # [   10.303400] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.
server # [   10.307917] systemd[1]: Finished Create SUID/SGID Wrappers.
builder # [   10.314829] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.
builder # [   10.324207] systemd[1]: Finished Create SUID/SGID Wrappers.
builder # [   10.600919] systemd[1]: Finished register-nix-paths.service.
server # [   10.606891] systemd[1]: Finished register-nix-paths.service.
builder # [   10.987134] systemd[1]: Finished Coldplug All udev Devices.
builder # [   10.988734] systemd[1]: Reached target System Initialization.
builder # [   10.991042] systemd[1]: Started Discard unused filesystem blocks once a week.
builder # [   10.994527] systemd[1]: Started Daily Cleanup of Temporary Directories.
builder # [   11.000334] systemd[1]: Reached target Timer Units.
builder # [   11.005653] systemd[1]: Listening on D-Bus System Message Bus Socket.
builder # [   11.008139] systemd[1]: Starting niks3 auto-upload socket...
builder # [   11.009990] systemd[1]: Listening on Nix Daemon Socket.
builder # [   11.016292] systemd[1]: Listening on Hostname Service Socket.
builder # [   11.018711] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
builder # [   11.023533] systemd[1]: Starting D-Bus System Message Bus...
builder # [   11.024721] systemd[1]: Finished Firewall.
builder # [   11.028583] systemd[1]: Listening on niks3 auto-upload socket.
builder # [   11.030037] systemd[1]: Reached target Socket Units.
server # [   11.073769] systemd[1]: Finished Coldplug All udev Devices.
server # [   11.075287] systemd[1]: Reached target System Initialization.
server # [   11.080364] systemd[1]: Started Discard unused filesystem blocks once a week.
server # [   11.081558] systemd[1]: Started niks3 garbage collection timer.
server # [   11.082488] systemd[1]: Started Daily Cleanup of Temporary Directories.
server # [   11.083397] systemd[1]: Reached target Timer Units.
server # [   11.093065] systemd[1]: Listening on D-Bus System Message Bus Socket.
server # [   11.103952] systemd[1]: Listening on niks3 server socket.
server # [   11.105710] systemd[1]: Listening on Nix Daemon Socket.
server # [   11.108259] systemd[1]: Listening on Hostname Service Socket.
server # [   11.112349] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
server # [   11.118186] systemd[1]: Reached target Socket Units.
server # [   11.120542] systemd[1]: Reached target Basic System.
server # [   11.127645] systemd[1]: Starting Import lastlog data into lastlog2 database...
server # [   11.130544] systemd[1]: Starting Generate test mTLS certs...
server # [   11.140389] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
builder # [   11.154166] dbus-broker-launch[485]: Looking up NSS user entry for 'systemd-timesync'...
builder # [   11.171593] dbus-broker-launch[485]: NSS returned no entry for 'systemd-timesync'
builder # [   11.174543] dbus-broker-launch[485]: Invalid user-name in /nix/store/hzrr2d23x7js016nr106dx00q8k49y6a-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
server # [   11.172867] systemd[1]: Starting Post-Boot Actions...
builder # [   11.212376] systemd[1]: Started D-Bus System Message Bus.
server # [   11.204562] systemd[1]: Started Reset console on configuration changes.
builder # [   11.252993] dbus-broker-launch[485]: Ready
builder # [   11.258682] systemd[1]: Reached target Basic System.
server # [   11.256091] systemd[1]: Starting resolvconf update...
builder # [   11.264129] systemd[1]: Starting Import lastlog data into lastlog2 database...
builder # [   11.272172] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
builder # [   11.282753] systemd[1]: Starting Post-Boot Actions...
builder # [   11.319923] systemd[1]: Started Reset console on configuration changes.
builder # [   11.355438] systemd[1]: Starting resolvconf update...
server # [   11.381903] nsncd[482]: Jul 07 08:15:52.983 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
server # [   11.402227] systemd[1]: Starting D-Bus System Message Bus...
server # [   11.409651] systemd[1]: Started Name Service Cache Daemon (nsncd).
builder # [   11.424799] systemd[1]: Finished Post-Boot Actions.
server # [   11.417143] systemd[1]: Finished Post-Boot Actions.
builder # [   11.442558] nsncd[488]: Jul 07 08:15:53.030 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
builder # [   11.452898] systemd[1]: Started Name Service Cache Daemon (nsncd).
builder # [   11.456967] systemd[1]: Reached target Host and Network Name Lookups.
builder # [   11.463170] systemd[1]: Reached target User and Group Name Lookups.
server # [   11.453475] systemd[1]: Reached target Host and Network Name Lookups.
server # [   11.471643] systemd[1]: Reached target User and Group Name Lookups.
builder # [   11.480155] systemd[1]: Starting User Login Management...
server # [   11.480152] niks3-test-certs-start[500]: -----
server # [   11.498621] systemd[1]: Starting User Login Management...
server # [   11.504478] systemd[1]: Finished Import lastlog data into lastlog2 database.
server # [   11.525802] niks3-test-certs-start[520]: -----
server # [   11.532453] systemd[1]: Finished Firewall.
builder # [   11.542621] systemd[1]: Finished Import lastlog data into lastlog2 database.
builder # [   11.666972] systemd-logind[506]: New seat seat0.
builder # [   11.673973] systemd[1]: Started User Login Management.
builder # [   11.687595] systemd[1]: Starting linger-users.service...
server # [   11.719141] niks3-test-certs-start[524]: Certificate request self-signature ok
server # [   11.724737] niks3-test-certs-start[524]: subject=CN=server
server # [   11.758724] dbus-broker-launch[512]: Looking up NSS user entry for 'systemd-timesync'...
server # [   11.776890] dbus-broker-launch[512]: NSS returned no entry for 'systemd-timesync'
server # [   11.783674] dbus-broker-launch[512]: Invalid user-name in /nix/store/m4m250cv2f00l4cp6y1h9rwvcnd89y96-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
server # [   11.800946] systemd-logind[518]: New seat seat0.
builder # [   11.812762] systemd[1]: linger-users.service: Deactivated successfully.
server # [   11.807705] systemd[1]: Started User Login Management.
builder # [   11.818006] systemd[1]: Finished linger-users.service.
server # [   11.815979] systemd[1]: Starting linger-users.service...
server # [   11.821431] niks3-test-certs-start[556]: -----
builder # [   11.835236] systemd[1]: Stopped target Host and Network Name Lookups.
builder # [   11.839910] systemd[1]: Stopping Host and Network Name Lookups...
builder # [   11.844120] systemd[1]: Stopped target User and Group Name Lookups.
builder # [   11.848393] systemd[1]: Stopping User and Group Name Lookups...
server # [   11.840978] systemd[1]: Started D-Bus System Message Bus.
builder # [   11.851768] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
builder # [   11.856908] systemd[1]: nscd.service: Deactivated successfully.
builder # [   11.862772] systemd[1]: Stopped Name Service Cache Daemon (nsncd).
builder # [   11.883551] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
server # [   11.900181] dbus-broker-launch[512]: Ready
server # [   11.935042] systemd[1]: linger-users.service: Deactivated successfully.
server # [   11.940827] systemd[1]: Finished linger-users.service.
server # [   11.947992] systemd[1]: Stopped target Host and Network Name Lookups.
server # [   11.954752] systemd[1]: Stopping Host and Network Name Lookups...
builder # [   11.964731] systemd[1]: Started Name Service Cache Daemon (nsncd).
server # [   11.961067] niks3-test-certs-start[560]: Certificate request self-signature ok
builder # [   11.970501] nsncd[553]: Jul 07 08:15:53.552 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
server # [   11.969221] niks3-test-certs-start[560]: subject=CN=niks3 test client
builder # [   11.976373] systemd[1]: Reached target Host and Network Name Lookups.
builder # [   11.979755] systemd[1]: Reached target User and Group Name Lookups.
server # [   11.973436] systemd[1]: Stopped target User and Group Name Lookups.
server # [   11.981364] systemd[1]: Stopping User and Group Name Lookups...
server # [   11.985308] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
builder # [   11.996544] systemd[1]: Finished resolvconf update.
server # [   11.989592] systemd[1]: nscd.service: Deactivated successfully.
builder # [   12.001399] systemd[1]: Reached target Preparation for Network.
server # [   11.995528] systemd[1]: Stopped Name Service Cache Daemon (nsncd).
builder # [   12.008386] systemd[1]: Starting DHCP Client...
server # [   12.001617] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
builder # [   12.018934] systemd[1]: Starting Extra networking commands....
server # [   12.024673] systemd[1]: Finished Generate test mTLS certs.
server # [   12.081787] systemd[1]: Started Name Service Cache Daemon (nsncd).
server # [   12.087352] systemd[1]: Reached target Host and Network Name Lookups.
server # [   12.090416] nsncd[572]: Jul 07 08:15:53.685 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
server # [   12.097560] systemd[1]: Reached target User and Group Name Lookups.
server # [   12.104434] systemd[1]: Finished resolvconf update.
server # [   12.107554] systemd[1]: Reached target Preparation for Network.
server # [   12.118943] systemd[1]: Starting DHCP Client...
server # [   12.124338] systemd[1]: Starting Extra networking commands....
builder # [   12.266576] dhcpcd[582]: dhcpcd-10.3.2 starting
builder # [   12.286738] dhcpcd[614]: dev: loaded udev
builder # [   12.293612] dhcpcd[614]: no valid interfaces found
builder # [   12.296264] dhcpcd[614]: libudev: received NULL device
builder # [   12.299441] dhcpcd[614]: no valid interfaces found
builder # [   12.301836] dhcpcd[614]: libudev: received NULL device
builder # [   12.328357] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3
builder # [   12.341058] systemd[1]: Finished Extra networking commands..
builder # [   12.346212] systemd[1]: Reached target Network.
builder # [   12.350468] systemd[1]: Starting Permit User Sessions...
server # [   12.374464] dhcpcd[601]: dhcpcd-10.3.2 starting
server # [   12.388341] dhcpcd[635]: dev: loaded udev
server # [   12.395721] dhcpcd[635]: no valid interfaces found
server # [   12.397463] dhcpcd[635]: no valid interfaces found
builder # [   12.423738] systemd[1]: Finished Permit User Sessions.
builder # [   12.434040] systemd[1]: Started Getty on tty1.
builder # [   12.435059] systemd[1]: Reached target Login Prompts.
server # [   12.436391] systemd[1]: Finished Extra networking commands..
server # [   12.442029] systemd[1]: Reached target Network.
server # [   12.446443] systemd[1]: Started Mock OIDC server for testing.
server # [   12.457842] systemd[1]: Starting Nginx Web Server...
server # [   12.473519] systemd[1]: Starting PostgreSQL Server...
server # [   12.497299] systemd[1]: Started RustFS S3-compatible object storage.
server # [   12.523330] systemd[1]: Starting Setup RustFS bucket...
server # [   12.534250] systemd[1]: Starting Permit User Sessions...
server # [   12.690114] systemd[1]: Finished Permit User Sessions.
server # [   12.727667] systemd[1]: Started Getty on tty1.
server # [   12.733489] systemd[1]: Reached target Login Prompts.
builder # [   12.804624] systemd[1]: Starting Virtual Console Setup...
server # [   12.822786] mock-oidc-server[644]: Mock OIDC Server running
builder # [   12.829591] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs
server # [   12.829504] mock-oidc-server[644]:   OIDC Address: 127.0.0.1:8080
builder # [   12.841162] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
server # [   12.834852] mock-oidc-server[644]:   Issue Address: 127.0.0.1:8081
builder # [   12.847664] systemd[1]: Stopped Virtual Console Setup.
server # [   12.839543] mock-oidc-server[644]:   Issuer: http://127.0.0.1:8080/oidc
server # [   12.844236] mock-oidc-server[644]:   JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json
builder # [   12.859633] systemd[1]: Starting Virtual Console Setup...
server # [   12.853865] mock-oidc-server[644]:   Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration
server # [   12.855200] mock-oidc-server[644]:   Issue tokens: http://127.0.0.1:8081/issue?sub=...
builder # [   13.024614] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.
server # [   13.066190] postgresql-pre-start[677]: The files belonging to this database system will be owned by user "postgres".
builder # [   13.075394] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.
server # [   13.070968] postgresql-pre-start[677]: This user must also own the server process.
builder # [   13.083297] systemd[1]: Started backdoor.service.
server # [   13.082432] postgresql-pre-start[677]: The database cluster will be initialized with locale "en_US.UTF-8".
server # [   13.101911] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3
server # [   13.090064] postgresql-pre-start[677]: The default database encoding has accordingly been set to "UTF8".
server # [   13.091973] postgresql-pre-start[677]: The default text search configuration will be set to "english".
server # [   13.098692] postgresql-pre-start[677]: Data page checksums are disabled.
server # [   13.107687] postgresql-pre-start[677]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok
server # [   13.114454] postgresql-pre-start[677]: creating subdirectories ... ok
server # [   13.119894] postgresql-pre-start[677]: selecting dynamic shared memory implementation ... posix
server # [   13.133128] nginx-pre-start[668]: nginx: the configuration file /nix/store/q2qwgbfznnzrr87lhic3cd6hyk8rk12a-nginx.conf syntax is ok
server # [   13.137632] nginx-pre-start[668]: nginx: configuration file /nix/store/q2qwgbfznnzrr87lhic3cd6hyk8rk12a-nginx.conf test is successful
builder # [   13.164467] 8021q: 802.1Q VLAN Support v1.8
server # [   13.151957] systemd[1]: Started Nginx Web Server.
builder # connecting to host...
builder # [   13.278401] (udev-worker)[635]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
builder # [   13.286133] (udev-worker)[635]: Network interface NamePolicy= disabled on kernel command line.
builder # [   13.314035] cfg80211: Loading compiled-in X.509 certificates for regulatory database
builder # [   13.311071] (udev-worker)[639]: Network interface NamePolicy= disabled on kernel command line.
builder # [   13.349350] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
builder # [   13.351847] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
server # [   13.332784] postgresql-pre-start[677]: selecting default "max_connections" ... 100
builder # [   13.356223] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2
builder # [   13.356563] cfg80211: failed to load regulatory.db
builder # [   13.442922] systemd-vconsole-setup[644]: Configuration of first virtual console was skipped, ignoring remaining ones.
builder # [   13.449272] systemd[1]: Finished Virtual Console Setup.
builder # [   13.504302] systemd[1]: Condition check resulted in Virtio network device being skipped.
builder # [   13.513145] systemd[1]: Starting Address configuration of eth1...
server # [   13.509674] postgresql-pre-start[677]: selecting default "shared_buffers" ... 128MB
builder # [   13.612600] systemd-logind[506]: Watching system buttons on /dev/input/event0 (gpio-keys)
builder # [   13.636366] 8021q: adding VLAN 0 to HW filter on device eth1
builder # [   13.649465] 8021q: adding VLAN 0 to HW filter on device eth0
builder # [   13.635671] dhcpcd[614]: eth0: waiting for carrier
builder # [   13.637435] dhcpcd[614]: eth0: carrier acquired
builder # [   13.646972] network-addresses-eth1-start[663]: adding address 192.168.1.1/24... done
builder # [   13.656197] dhcpcd[614]: DUID 00:01:00:01:31:df:72:3b:52:54:00:12:34:56
builder # [   13.657244] dhcpcd[614]: eth0: IAID 00:12:34:56
builder # [   13.657891] dhcpcd[614]: eth0: adding address fe80::5054:ff:fe12:3456
builder # [   13.668091] network-addresses-eth1-start[663]: adding address 2001:db8:1::1/64... done
builder # [   13.688522] systemd[1]: Finished Address configuration of eth1.
builder # [   13.788011] mousedev: PS/2 mouse device common for all mice
server # [   13.769610] rustfs[647]: {"timestamp":"2026-07-07T08:15:55.36845488Z","level":"ERROR","fields":{"message":"list_path_raw: revjob err VolumeNotFound"},"target":"rustfs_ecstore::cache_value::metacache_set","filename":"crates/ecstore/src/cache_value/metacache_set.rs","line_number":486,"threadName":"rustfs-worker","threadId":"ThreadId(3)"}
builder # [   13.849444] systemd-logind[506]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)
builder # [   13.852625] dhcpcd[614]: eth0: soliciting a DHCP lease
builder # [   13.860620] dhcpcd[614]: eth0: offered 10.0.2.15 from 10.0.2.2
builder # [   13.868339] dhcpcd[614]: eth0: probing address 10.0.2.15/24
server # [   14.043741] systemd[1]: Starting Virtual Console Setup...
server # [   14.053607] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs
server # [   14.080273] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
server # [   14.084382] systemd[1]: Stopped Virtual Console Setup.
server # [   14.088864] systemd[1]: Starting Virtual Console Setup...
server # [   14.349714] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.
server # [   14.407247] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.
server # [   14.417318] systemd[1]: Started backdoor.service.
server # [   14.532987] 8021q: 802.1Q VLAN Support v1.8
server # connecting to host...
server # [   14.566897] (udev-worker)[676]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
server # [   14.577864] (udev-worker)[676]: Network interface NamePolicy= disabled on kernel command line.
server: Guest shell says: b'Spawning backdoor root shell...\n'
server: connected to guest root shell
server # [   14.595138] (udev-worker)[679]: Network interface NamePolicy= disabled on kernel command line.
server: (connecting took 14.95 seconds)
server: (finished: waiting for the VM to finish booting, in 14.95 seconds)
server # [   14.678302] systemd-vconsole-setup[716]: Configuration of first virtual console was skipped, ignoring remaining ones.
server # [   14.691382] systemd[1]: Finished Virtual Console Setup.
server # [   14.717674] cfg80211: Loading compiled-in X.509 certificates for regulatory database
server # [   14.757567] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
server # [   14.758153] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
server # [   14.763107] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2
server # [   14.763462] cfg80211: failed to load regulatory.db
builder # [   14.878424] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
builder # [   14.899586] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
builder # [   14.904255] systemd[1]: Stopped Virtual Console Setup.
builder # [   14.905068] systemd[1]: Stopping Virtual Console Setup...
builder # [   14.911609] systemd[1]: Starting Virtual Console Setup...
server # [   14.912806] systemd[1]: Condition check resulted in Virtio network device being skipped.
server # [   14.921337] systemd[1]: Starting Address configuration of eth1...
builder # [   14.938212] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
builder # [   14.943973] systemd[1]: Stopped Virtual Console Setup.
builder # [   14.948990] systemd[1]: Starting Virtual Console Setup...
builder # [   14.970903] systemd-logind[506]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)
server # [   15.048615] 8021q: adding VLAN 0 to HW filter on device eth0
server # [   15.037431] dhcpcd[635]: eth0: waiting for carrier
server # [   15.040679] dhcpcd[635]: eth0: carrier acquired
server # [   15.064805] 8021q: adding VLAN 0 to HW filter on device eth1
server # [   15.058064] dhcpcd[635]: DUID 00:01:00:01:31:df:72:3c:52:54:00:12:34:56
server # [   15.060849] dhcpcd[635]: eth0: IAID 00:12:34:56
server # [   15.063221] dhcpcd[635]: eth0: adding address fe80::5054:ff:fe12:3456
server # [   15.080431] network-addresses-eth1-start[739]: adding address 192.168.1.2/24... done
server # [   15.095875] network-addresses-eth1-start[739]: adding address 2001:db8:1::2/64... done
server # [   15.122415] systemd[1]: Finished Address configuration of eth1.
server # [   15.194926] systemd-logind[518]: Watching system buttons on /dev/input/event0 (gpio-keys)
builder # [   15.296586] systemd-vconsole-setup[687]: Configuration of first virtual console was skipped, ignoring remaining ones.
builder # [   15.301199] systemd[1]: Finished Virtual Console Setup.
server # [   15.348001] mousedev: PS/2 mouse device common for all mice
server # [   15.393016] dhcpcd[635]: eth0: soliciting a DHCP lease
server # [   15.400584] dhcpcd[635]: eth0: offered 10.0.2.15 from 10.0.2.2
server # [   15.408298] dhcpcd[635]: eth0: probing address 10.0.2.15/24
server # [   15.486710] systemd-logind[518]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)
builder # [   15.853583] dhcpcd[614]: eth0: soliciting an IPv6 router
builder # [   15.856929] dhcpcd[614]: eth0: Router Advertisement from fe80::2
builder # [   15.859799] dhcpcd[614]: eth0: adding address fec0::5054:ff:fe12:3456/64
builder # [   15.862922] dhcpcd[614]: eth0: adding route to fec0::/64
builder # [   15.865411] dhcpcd[614]: eth0: adding default route via fe80::2
server # [   16.467712] postgresql-pre-start[677]: selecting default time zone ... UTC
server # [   16.473760] postgresql-pre-start[677]: creating configuration files ... ok
server # [   16.779521] dhcpcd[635]: eth0: soliciting an IPv6 router
server # [   16.782506] dhcpcd[635]: eth0: Router Advertisement from fe80::2
server # [   16.783395] dhcpcd[635]: eth0: adding address fec0::5054:ff:fe12:3456/64
server # [   16.787739] dhcpcd[635]: eth0: adding route to fec0::/64
server # [   16.790318] dhcpcd[635]: eth0: adding default route via fe80::2
server # [   17.021539] postgresql-pre-start[677]: running bootstrap script ... ok
server # [   17.902728] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
server # [   17.936395] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
server # [   17.937590] systemd[1]: Stopped Virtual Console Setup.
server # [   17.938354] systemd[1]: Stopping Virtual Console Setup...
server # [   17.951256] systemd[1]: Starting Virtual Console Setup...
server # [   18.000341] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
server # [   18.001539] systemd[1]: Stopped Virtual Console Setup.
server # [   18.007342] systemd[1]: Starting Virtual Console Setup...
server # [   18.013517] systemd-logind[518]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)
server # [   18.235448] postgresql-pre-start[677]: performing post-bootstrap initialization ... ok
server # [   18.438385] postgresql-pre-start[677]: syncing data to disk ... ok
server # [   18.440877] postgresql-pre-start[677]: initdb: warning: enabling "trust" authentication for local connections
server # [   18.442728] postgresql-pre-start[677]: initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.
server # [   18.445287] postgresql-pre-start[677]: Success. You can now start the database server using:
server # [   18.446702] postgresql-pre-start[677]:     pg_ctl -D /var/lib/postgresql/17 -l logfile start
server # [   18.556272] systemd-vconsole-setup[785]: Configuration of first virtual console was skipped, ignoring remaining ones.
server # [   18.565841] systemd[1]: Finished Virtual Console Setup.
server # [   18.628171] postgres[794]: [794] LOG:  starting PostgreSQL 17.10 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit
server # [   18.636164] postgres[794]: [794] LOG:  listening on IPv6 address "::1", port 5432
server # [   18.637618] postgres[794]: [794] LOG:  listening on IPv4 address "127.0.0.1", port 5432
server # [   18.644243] postgres[794]: [794] LOG:  listening on Unix socket "/run/postgresql/.s.PGSQL.5432"
server # [   18.659648] postgres[806]: [806] LOG:  database system was shut down at 2026-07-07 08:15:59 GMT
server # [   18.668906] postgres[794]: [794] LOG:  database system is ready to accept connections
server # [   18.683932] systemd[1]: Started PostgreSQL Server.
server # [   18.692204] systemd[1]: Starting PostgreSQL Setup Scripts...
builder # [   18.961449] dhcpcd[614]: eth0: leased 10.0.2.15 for 86400 seconds
builder # [   18.962998] dhcpcd[614]: eth0: adding route to 10.0.2.0/24
builder # [   18.963873] dhcpcd[614]: eth0: adding default route via 10.0.2.2
server # [   18.964653] postgresql-setup-start[817]: CREATE DATABASE
server # [   19.018683] postgresql-setup-start[822]: CREATE ROLE
server # [   19.045223] postgresql-setup-start[824]: ALTER DATABASE
server # [   19.054022] systemd[1]: Finished PostgreSQL Setup Scripts.
server # [   19.055992] systemd[1]: Reached target PostgreSQL.
builder # [   19.089763] systemd[1]: Started DHCP Client.
builder # [   19.091540] systemd[1]: Reached target Multi-User System.
builder # [   19.093115] systemd[1]: Startup finished in 1.007s (kernel) + 4.149s (initrd) + 13.936s (userspace) = 19.092s.
server: (finished: waiting for unit postgresql.service, in 19.52 seconds)
server: waiting for unit rustfs.service
server: (finished: waiting for unit rustfs.service, in 0.09 seconds)
server: waiting for unit rustfs-setup.service
server # [   20.333993] dhcpcd[635]: eth0: leased 10.0.2.15 for 86400 seconds
server # [   20.335820] dhcpcd[635]: eth0: adding route to 10.0.2.0/24
server # [   20.337068] dhcpcd[635]: eth0: adding default route via 10.0.2.2
server # [   20.486835] systemd[1]: Started DHCP Client.
server # [   29.493969] rustfs-setup-start[924]: mb s3://niks3-test
server # [   29.501613] systemd[1]: Finished Setup RustFS bucket.
server # [   29.511516] systemd[1]: Starting niks3 server...
server # [   29.821470] postgres[938]: [938] ERROR:  relation "goose_db_version" does not exist at character 36
server # [   29.822834] postgres[938]: [938] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
server # [   29.856894] niks3-server[932]: 2026/07/07 08:16:11 OK   20241026095416_initial_model.sql (19.18ms)
server # [   29.879154] niks3-server[932]: 2026/07/07 08:16:11 OK   20251210153512_drop_unused_gin_index.sql (6.48ms)
server # [   29.883099] niks3-server[932]: 2026/07/07 08:16:11 OK   20251218171726_add_pins.sql (4.95ms)
server # [   29.884816] niks3-server[932]: 2026/07/07 08:16:11 OK   20260628120000_add_object_size_and_stats.sql (7.32ms)
server # [   29.886400] niks3-server[932]: 2026/07/07 08:16:11 goose: successfully migrated database to version: 20260628120000
server # [   29.888784] niks3-server[932]: 2026/07/07 08:16:11 OK   1_commit_pending_closure.sql (6.27ms)
server # [   29.891136] niks3-server[932]: 2026/07/07 08:16:11 OK   2_object_stats_trigger.sql (3.64ms)
server # [   29.893457] niks3-server[932]: 2026/07/07 08:16:11 goose: up to current file version: 2
server # [   29.919027] niks3-server[932]: 2026/07/07 08:16:11 INFO OIDC provider initialized name=test
server # [   29.920702] niks3-server[932]: 2026/07/07 08:16:11 INFO OIDC authentication enabled config=/nix/store/2za6kyy80mq2xh1w4ydfs7gg8psbv5g3-niks3-oidc.json
server # [   29.926998] niks3-server[932]: 2026/07/07 08:16:11 INFO Loaded signing key name=niks3-test-1 path=/nix/store/1cz670ydszbfkf0naknmhg579xvlcyr7-niks3-signing-key
server # [   29.988412] rustfs[647]: {"timestamp":"2026-07-07T08:16:11.58947706Z","level":"ERROR","fields":{"message":"system path read failed","path_kind":"data_usage","operation":"read_primary","reason":"config_not_found","object":"buckets/.usage.json","error":"Config not found"},"target":"rustfs_ecstore::data_usage","filename":"crates/ecstore/src/data_usage.rs","line_number":126,"threadName":"rustfs-worker","threadId":"ThreadId(3)"}
server # [   30.000864] niks3-server[932]: 2026/07/07 08:16:11 INFO Created nix-cache-info in bucket bucket=niks3-test
server # [   30.007245] niks3-server[932]: 2026/07/07 08:16:11 INFO Using socket-activated listener address=0.0.0.0:5751
server # [   30.009163] niks3-server[932]: 2026/07/07 08:16:11 INFO systemd watchdog enabled interval=15s
server # [   30.010590] niks3-server[932]: 2026/07/07 08:16:11 INFO Starting HTTP server address=0.0.0.0:5751
server # [   30.012305] systemd[1]: Started niks3 server.
server # [   30.013067] systemd[1]: Reached target Multi-User System.
server # [   30.014271] systemd[1]: Startup finished in 967ms (kernel) + 4.143s (initrd) + 24.894s (userspace) = 30.005s.
server: (finished: waiting for unit rustfs-setup.service, in 10.77 seconds)
server: waiting for unit mock-oidc.service
server: (finished: waiting for unit mock-oidc.service, in 0.09 seconds)
server: waiting for unit niks3.service
server: (finished: waiting for unit niks3.service, in 0.09 seconds)
server: waiting for TCP port 5751 on localhost
server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded!
server: (finished: waiting for TCP port 5751 on localhost, in 0.06 seconds)
server: waiting for TCP port 8080 on localhost
server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded!
server: (finished: waiting for TCP port 8080 on localhost, in 0.04 seconds)
server: waiting for TCP port 9000 on localhost
server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded!
server: (finished: waiting for TCP port 9000 on localhost, in 0.04 seconds)
server: must succeed: mkdir -p /tmp/test-config
server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.03 seconds)
server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token
server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.02 seconds)
server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3
server # [   30.683348] niks3-server[932]: 2026/07/07 08:16:12 INFO Received uploads request method=POST path=/api/pending_closures
server # time=2026-07-07T08:16:12.308Z level=INFO msg="Uploading 5 paths to server (0 already cached)"
server # time=2026-07-07T08:16:12.310Z level=INFO msg="Uploading qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3 (287.5KB)"
server # time=2026-07-07T08:16:12.312Z level=INFO msg="Uploading fqhxni8jcvyf5id4rvg9x2y00qx1963d-libidn2-2.3.8 (366.1KB)"
server # time=2026-07-07T08:16:12.316Z level=INFO msg="Uploading wp59zwnmv6h2glrrb9rd7siprz1mxn6r-xgcc-15.2.0-libgcc (150.1KB)"
server # time=2026-07-07T08:16:12.318Z level=INFO msg="Uploading 020cvdgp5va6j1mdz75s2nzfz25k37v2-libunistring-1.4.2 (2.0MB)"
server # time=2026-07-07T08:16:12.321Z level=INFO msg="Uploading jp8avbmpfcdnm0axwrzyk072nmq9cr0d-glibc-2.42-67 (44.4MB)"
server # [   32.850711] niks3-server[932]: 2026/07/07 08:16:14 INFO Received complete multipart upload request method=POST path=/api/multipart/complete
server # [   32.875145] niks3-server[932]: 2026/07/07 08:16:14 INFO Completed multipart upload object_key=nar/0n9jhh8wlbk6hqhzsklzl8vbv6xab1d7yi91hr9z948fzw5s80jd.nar.zst upload_id=NTg4MTYzY2ItYzIyNy00ZDAxLWIyMDQtMmY1YWU5MGRiMzgyLjFlNWNiNWI1LWQxYWItNDcxZS04YWQyLTAyZjY3MjIyZmU1OHgxNzgzNDEyMTcyMzAyMTEzMDAw parts=1
server # [   32.885225] niks3-server[932]: 2026/07/07 08:16:14 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign
server # time=2026-07-07T08:16:14.489Z level=INFO msg="Uploading 5 narinfos"
server # [   32.888892] niks3-server[932]: 2026/07/07 08:16:14 INFO Signed narinfos id=1 count=5
server # [   32.905271] niks3-server[932]: 2026/07/07 08:16:14 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete
server # time=2026-07-07T08:16:14.519Z level=INFO msg="Upload complete. (2.328s)"
server # [   32.923963] niks3-server[932]: 2026/07/07 08:16:14 INFO Completed upload id=1
server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3, in 2.52 seconds)
server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token
server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.03 seconds)
server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3
server # [   33.187106] niks3-server[932]: 2026/07/07 08:16:14 WARN Authentication failed token_preview=invalid-token token_length=13 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]
server # time=2026-07-07T08:16:14.792Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"
server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3, in 0.24 seconds)
server: waiting for unit nginx.service
server: (finished: waiting for unit nginx.service, in 0.06 seconds)
server: waiting for TCP port 443 on localhost
server # Connection to localhost (::1) 443 port [tcp/https] succeeded!
server: (finished: waiting for TCP port 443 on localhost, in 0.07 seconds)
server: must succeed: /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3
server # time=2026-07-07T08:16:15.082Z level=INFO msg="Configuring client TLS" cert=/etc/niks3-test-certs/client.pem key=/etc/niks3-test-certs/client.key ca=/etc/niks3-test-certs/ca.pem
server # [   33.607546] niks3-server[932]: 2026/07/07 08:16:15 INFO Received uploads request method=POST path=/api/pending_closures
server # time=2026-07-07T08:16:15.218Z level=INFO msg="Uploading 0 paths to server (5 already cached)"
server # [   33.619114] niks3-server[932]: 2026/07/07 08:16:15 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete
server # [   33.621847] niks3-server[932]: 2026/07/07 08:16:15 INFO Completed upload id=2
server # time=2026-07-07T08:16:15.225Z level=INFO msg="Upload complete. (137ms)"
server: (finished: must succeed: /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3, in 0.30 seconds)
server: must fail: /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3
server # time=2026-07-07T08:16:15.255Z level=ERROR msg="Fatal error" error="auth token is required (use --auth-token-path, --auth-token-script, NIKS3_AUTH_TOKEN_FILE, or $XDG_CONFIG_HOME/niks3/auth-token)"
server: (finished: must fail: /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3, in 0.03 seconds)
server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3
server # time=2026-07-07T08:16:15.376Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem
server # [   33.889418] niks3-server[932]: 2026/07/07 08:16:15 INFO Received uploads request method=POST path=/api/pending_closures
server # time=2026-07-07T08:16:15.501Z level=INFO msg="Uploading 0 paths to server (5 already cached)"
server # [   33.904222] niks3-server[932]: 2026/07/07 08:16:15 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete
server # [   33.907452] niks3-server[932]: 2026/07/07 08:16:15 INFO Completed upload id=3
server # time=2026-07-07T08:16:15.511Z level=INFO msg="Upload complete. (133ms)"
server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3, in 0.26 seconds)
server: must succeed: cd /etc/niks3-test-certs && /nix/store/y2wn0af8r6l6r44r407091nbzgy7aw8z-openssl-3.6.2-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client'
server # -----
server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/y2wn0af8r6l6r44r407091nbzgy7aw8z-openssl-3.6.2-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client', in 0.04 seconds)
server: must succeed: cd /etc/niks3-test-certs && /nix/store/y2wn0af8r6l6r44r407091nbzgy7aw8z-openssl-3.6.2-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem
server # Certificate request self-signature ok
server # subject=CN=other client
server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/y2wn0af8r6l6r44r407091nbzgy7aw8z-openssl-3.6.2-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem, in 0.06 seconds)
server: must fail: /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3
server # time=2026-07-07T08:16:15.738Z level=INFO msg="Configuring client TLS" cert=/etc/niks3-test-certs/other.pem key=/etc/niks3-test-certs/other.key ca=/etc/niks3-test-certs/ca.pem
server # [   34.235095] niks3-server[932]: 2026/07/07 08:16:15 WARN mTLS auth: subject not in bound subjects subject="CN=other client"
server # time=2026-07-07T08:16:15.839Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"
server: (finished: must fail: /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3, in 0.22 seconds)
server: must succeed: mkdir -p /tmp/test-store
server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.03 seconds)
server: must succeed: 
  export AWS_ACCESS_KEY_ID=rustfsadmin
export AWS_SECRET_ACCESS_KEY=rustfsadmin
  nix copy --from 's3://niks3-test?endpoint=http://server:9000&region=us-east-1' --to /tmp/test-store /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3

server # copying 5 paths...
server # copying path '/nix/store/020cvdgp5va6j1mdz75s2nzfz25k37v2-libunistring-1.4.2' from 's3://niks3-test?endpoint=http://server:9000&region=us-east-1'...
server # copying path '/nix/store/fqhxni8jcvyf5id4rvg9x2y00qx1963d-libidn2-2.3.8' from 's3://niks3-test?endpoint=http://server:9000&region=us-east-1'...
server # copying path '/nix/store/wp59zwnmv6h2glrrb9rd7siprz1mxn6r-xgcc-15.2.0-libgcc' from 's3://niks3-test?endpoint=http://server:9000&region=us-east-1'...
server # copying path '/nix/store/jp8avbmpfcdnm0axwrzyk072nmq9cr0d-glibc-2.42-67' from 's3://niks3-test?endpoint=http://server:9000&region=us-east-1'...
server # copying path '/nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3' from 's3://niks3-test?endpoint=http://server:9000&region=us-east-1'...
server: (finished: must succeed: 
  export AWS_ACCESS_KEY_ID=rustfsadmin
export AWS_SECRET_ACCESS_KEY=rustfsadmin
  nix copy --from 's3://niks3-test?endpoint=http://server:9000&region=us-east-1' --to /tmp/test-store /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3
, in 0.67 seconds)
server: must succeed: 
cat > /tmp/test-drv.nix << 'EOF'
derivation {
  name = "test-build-log";
  system = builtins.currentSystem;
  builder = "/bin/sh";
  args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];
}
EOF

server: (finished: must succeed: 
cat > /tmp/test-drv.nix << 'EOF'
derivation {
  name = "test-build-log";
  system = builtins.currentSystem;
  builder = "/bin/sh";
  args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];
}
EOF
, in 0.03 seconds)
server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix
server # this derivation will be built:
server #   /nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv
server # building '/nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv'...
server # test-build-log> test build log output
server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.32 seconds)
server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log
server # [   35.665759] niks3-server[932]: 2026/07/07 08:16:17 INFO Received uploads request method=POST path=/api/pending_closures
server # time=2026-07-07T08:16:17.275Z level=INFO msg="Uploading 1 paths to server (0 already cached)"
server # time=2026-07-07T08:16:17.277Z level=INFO msg="Uploading z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log (128B)"
server # [   35.699213] niks3-server[932]: 2026/07/07 08:16:17 INFO Received sign narinfos request method=POST path=/api/pending_closures/4/sign
server # time=2026-07-07T08:16:17.304Z level=INFO msg="Uploading 1 narinfos"
server # [   35.703981] niks3-server[932]: 2026/07/07 08:16:17 INFO Signed narinfos id=4 count=1
server # [   35.709590] niks3-server[932]: 2026/07/07 08:16:17 INFO Received complete upload request method=POST path=/api/pending_closures/4/complete
server # time=2026-07-07T08:16:17.317Z level=INFO msg="Upload complete. (263ms)"
server # [   35.718109] niks3-server[932]: 2026/07/07 08:16:17 INFO Completed upload id=4
server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log, in 0.43 seconds)
server: must succeed: 
  export AWS_ACCESS_KEY_ID=rustfsadmin
export AWS_SECRET_ACCESS_KEY=rustfsadmin
  nix log --store 's3://niks3-test?endpoint=http://server:9000&region=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log

server # got build log for '/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log' from 's3://niks3-test?endpoint=http://server:9000&region=us-east-1'
server: (finished: must succeed: 
  export AWS_ACCESS_KEY_ID=rustfsadmin
export AWS_SECRET_ACCESS_KEY=rustfsadmin
  nix log --store 's3://niks3-test?endpoint=http://server:9000&region=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log
, in 0.22 seconds)
server: must succeed: 
cat > /tmp/ca-test.nix << 'EOF'
derivation {
  name = "ca-test";
  system = builtins.currentSystem;
  builder = "/bin/sh";
  args = [ "-c" "echo 'Hello from CA derivation' > $out" ];
  __contentAddressed = true;
  outputHashMode = "recursive";
  outputHashAlgo = "sha256";
}
EOF

server: (finished: must succeed: 
cat > /tmp/ca-test.nix << 'EOF'
derivation {
  name = "ca-test";
  system = builtins.currentSystem;
  builder = "/bin/sh";
  args = [ "-c" "echo 'Hello from CA derivation' > $out" ];
  __contentAddressed = true;
  outputHashMode = "recursive";
  outputHashAlgo = "sha256";
}
EOF
, in 0.04 seconds)
server: must succeed: nix-build --log-format bar-with-logs /tmp/ca-test.nix --no-out-link
server # this derivation will be built:
server #   /nix/store/8j226mdc8yhan5fv0s31bbzx0iiny3pa-ca-test.drv
server # building '/nix/store/8j226mdc8yhan5fv0s31bbzx0iiny3pa-ca-test.drv'...
server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/ca-test.nix --no-out-link, in 0.30 seconds)
server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test
server # [   36.662257] niks3-server[932]: 2026/07/07 08:16:18 INFO Received uploads request method=POST path=/api/pending_closures
server # time=2026-07-07T08:16:18.274Z level=INFO msg="Uploading 1 paths to server (0 already cached)"
server # time=2026-07-07T08:16:18.275Z level=INFO msg="Uploading 5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test (144B)"
server # [   36.687439] niks3-server[932]: 2026/07/07 08:16:18 INFO Received sign narinfos request method=POST path=/api/pending_closures/5/sign
server # time=2026-07-07T08:16:18.292Z level=INFO msg="Uploading 1 narinfos"
server # [   36.693671] niks3-server[932]: 2026/07/07 08:16:18 INFO Signed narinfos id=5 count=1
server # [   36.697495] niks3-server[932]: 2026/07/07 08:16:18 INFO Received complete upload request method=POST path=/api/pending_closures/5/complete
server # time=2026-07-07T08:16:18.303Z level=INFO msg="Upload complete. (269ms)"
server # [   36.701737] niks3-server[932]: 2026/07/07 08:16:18 INFO Completed upload id=5
server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5gd2s7c2dagv3cknx5wvjnnnqj5d09j1-niks3-1.7.0/bin/niks3 push /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.42 seconds)
server: must succeed: mkdir -p /tmp/chroot-store
server: (finished: must succeed: mkdir -p /tmp/chroot-store, in 0.03 seconds)
server: must succeed: 
  export AWS_ACCESS_KEY_ID=rustfsadmin
export AWS_SECRET_ACCESS_KEY=rustfsadmin
  nix copy --from 's3://niks3-test?endpoint=http://server:9000&region=us-east-1' --to /tmp/chroot-store /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test

server # copying 1 paths...
server # copying path '/nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test' from 's3://niks3-test?endpoint=http://server:9000&region=us-east-1'...
server: (finished: must succeed: 
  export AWS_ACCESS_KEY_ID=rustfsadmin
export AWS_SECRET_ACCESS_KEY=rustfsadmin
  nix copy --from 's3://niks3-test?endpoint=http://server:9000&region=us-east-1' --to /tmp/chroot-store /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test
, in 0.21 seconds)
server: must succeed: nix --store /tmp/chroot-store store cat /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test
server: (finished: must succeed: nix --store /tmp/chroot-store store cat /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.14 seconds)
server: must succeed: nix --store /tmp/chroot-store realisation info /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test
server: (finished: must succeed: nix --store /tmp/chroot-store realisation info /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.15 seconds)
server: must succeed: readlink /etc/niks3-test/symlink-wrapper
server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.05 seconds)
server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper
server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.04 seconds)
server: must succeed: test -L /nix/store/pvdx502yvdpvia8734qkyzlwik5fyglv-symlink-wrapper
server: output: 
!!! Traceback (most recent call last):
!!!   File "<string>", line 139, in <module>
!!!     server.succeed(f"test -L {symlink_wrapper}")
!!! 
!!! RequestedAssertionFailed: command `test -L /nix/store/pvdx502yvdpvia8734qkyzlwik5fyglv-symlink-wrapper` failed (exit code 1)
cleanup
kill QemuMachine (pid 15)
builder # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/smwd56pl54f0pcjdspgh5vnf20c8xwsc-python3-3.13.13/bin/python3.13)
kill QemuMachine (pid 14)
server # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/smwd56pl54f0pcjdspgh5vnf20c8xwsc-python3-3.13.13/bin/python3.13)
(finished: cleanup, in 0.44 seconds)
additionally exposed symbols:
    builder, server,
    vlan1,
    start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh
Hello store path: /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3
Test output path: /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log
CA derivation output path: /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test
Realisation info in chroot store: /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test

Symlink wrapper store path: /nix/store/pvdx502yvdpvia8734qkyzlwik5fyglv-symlink-wrapper