nixbot

builds

failed vm-test-run-nixos-test-niks3 aarch64-linux.nixos-test-niks3-lix · build #77 · raw

1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600 seconds6run the VM test script7start all VMs8builder: starting vm9server: starting vm10server # Disk image does not exist, creating the virtualisation disk image...11server: QEMU running (pid 15)12server # Formatting '/build/vm-state-server/tmp.XRV7hZZ9Qn', fmt=raw size=107374182413builder: QEMU running (pid 14)14server # mke2fs 1.47.4 (6-Mar-2025)15builder # Disk image does not exist, creating the virtualisation disk image...16server # Discarding device blocks: 0/262144 done17builder # Formatting '/build/vm-state-builder/tmp.8uuccG6xfA', fmt=raw size=107374182418server # Creating filesystem with 262144 4k blocks and 65536 inodes19builder # mke2fs 1.47.4 (6-Mar-2025)20server # Filesystem UUID: f624c753-b1bf-4534-88ee-d924ff96a04721builder # Discarding device blocks: 0/262144 done22server # Superblock backups stored on blocks:23builder # Creating filesystem with 262144 4k blocks and 65536 inodes24server # 32768, 98304, 163840, 22937625builder # Filesystem UUID: 5b3158a0-7a49-496e-bfe8-122379b5f5f126server # 27builder # Superblock backups stored on blocks:28server # Allocating group tables: 0/8 done29builder # 32768, 98304, 163840, 22937630server # Writing inode tables: 0/8 done31builder # 32server # Creating journal (8192 blocks): done33builder # Allocating group tables: 0/8 done34server # Writing superblocks and filesystem accounting information: 0/8 done35builder # Writing inode tables: 0/8 done36server # 37builder # Creating journal (8192 blocks): done38server # Virtualisation disk image created.39builder # Writing superblocks and filesystem accounting information: 0/8 done40(finished: start all VMs, in 0.48 seconds)41builder # 42server: waiting for unit postgresql.service43builder # Virtualisation disk image created.44server: waiting for the VM to finish booting45server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]46server # [ 0.000000] Linux version 6.18.37 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Jun 27 10:06:50 UTC 202647server # [ 0.000000] KASLR enabled48server # [ 0.000000] random: crng init done49server # [ 0.000000] Machine model: linux,dummy-virt50server # [ 0.000000] efi: UEFI not found.51server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT52server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]53server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded740-0x7fdf0ebf]54server # [ 0.000000] Zone ranges:55server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]56server # [ 0.000000] DMA32 empty57server # [ 0.000000] Normal empty58server # [ 0.000000] Device empty59server # [ 0.000000] Movable zone start for each node60server # [ 0.000000] Early memory node ranges61server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]62server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]63server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000064server # [ 0.000000] psci: probing for conduit method from DT.65server # [ 0.000000] psci: PSCIv1.3 detected in firmware.66server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs67server # [ 0.000000] psci: Trusted OS migration not required68server # [ 0.000000] psci: SMC Calling Convention v1.169server # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)70server # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u31129671server # [ 0.000000] Detected PIPT I-cache on CPU072server # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)73server # [ 0.000000] CPU features: detected: GICv3 CPU interface74server # [ 0.000000] CPU features: detected: Spectre-v475server # [ 0.000000] CPU features: detected: Spectre-BHB76server # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_3877server # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_2378server # [ 0.000000] alternatives: applying boot alternatives79server # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/5hndw2rvi253kg6266rb7rcbm7dk1rb4-nixos-system-server-test/init regInfo=/nix/store/djsqhnl2abikhxf0jgz98s3f4d9nqq78-closure-info/registration console=ttyAMA0,115200n8 console=tty080server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/djsqhnl2abikhxf0jgz98s3f4d9nqq78-closure-info/registration", will be passed to user space.81server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes82server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)83server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)84server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB85server # [ 0.000000] software IO TLB: area num 1.86server # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)87server # [ 0.000000] Fallback order for Node 0: 088server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214489server # [ 0.000000] Policy zone: DMA90server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off91server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=192server # [ 0.000000] allocated 2097152 bytes of page_ext93server # [ 0.000000] ftrace: allocating 74702 entries in 292 pages94server # [ 0.000000] ftrace: allocated 292 pages with 3 groups95server # [ 0.000000] rcu: Hierarchical RCU implementation.96server # [ 0.000000] rcu: RCU event tracing is enabled.97server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.98server # [ 0.000000] Trampoline variant of Tasks RCU enabled.99server # [ 0.000000] Rude variant of Tasks RCU enabled.100server # [ 0.000000] Tracing variant of Tasks RCU enabled.101builder # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]102server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.103server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1104builder # [ 0.000000] Linux version 6.18.37 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Jun 27 10:06:50 UTC 2026105builder # [ 0.000000] KASLR enabled106builder # [ 0.000000] random: crng init done107server # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.108builder # [ 0.000000] Machine model: linux,dummy-virt109builder # [ 0.000000] efi: UEFI not found.110server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.111builder # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT112server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.113builder # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]114server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0115server # [ 0.000000] GICv3: 256 SPIs implemented116builder # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded740-0x7fdf0ebf]117builder # [ 0.000000] Zone ranges:118server # [ 0.000000] GICv3: 0 Extended SPIs implemented119server # [ 0.000000] Root IRQ handler: gic_handle_irq120builder # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]121builder # [ 0.000000] DMA32 empty122server # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI123builder # [ 0.000000] Normal empty124builder # [ 0.000000] Device empty125server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0126builder # [ 0.000000] Movable zone start for each node127server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000128builder # [ 0.000000] Early memory node ranges129server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]130builder # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]131server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)132builder # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]133builder # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000134server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)135builder # [ 0.000000] psci: probing for conduit method from DT.136builder # [ 0.000000] psci: PSCIv1.3 detected in firmware.137server # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000138builder # [ 0.000000] psci: Using standard PSCI v0.2 function IDs139server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000140builder # [ 0.000000] psci: Trusted OS migration not required141builder # [ 0.000000] psci: SMC Calling Convention v1.1142server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.143server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).144builder # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)145builder # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296146server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns147builder # [ 0.000000] Detected PIPT I-cache on CPU0148server # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns149builder # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)150server # [ 0.000030] arm-pv: using stolen time PV151builder # [ 0.000000] CPU features: detected: GICv3 CPU interface152builder # [ 0.000000] CPU features: detected: Spectre-v4153server # [ 0.000430] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)154builder # [ 0.000000] CPU features: detected: Spectre-BHB155server # [ 0.000588] Console: colour dummy device 80x25156builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38157server # [ 0.000596] printk: legacy console [tty0] enabled158builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23159builder # [ 0.000000] alternatives: applying boot alternatives160server # [ 0.000779] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)161server # [ 0.000786] pid_max: default: 32768 minimum: 301162server # [ 0.000864] LSM: initializing lsm=capability,landlock,yama,bpf,ima163server # [ 0.001037] landlock: Up and running.164server # [ 0.001040] Yama: becoming mindful.165server # [ 0.001506] LSM support for eBPF active166builder # [ 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/3gplg5x8kbppr871pjphnnm3bl8b3ppv-nixos-system-builder-test/init regInfo=/nix/store/5qwwb63f27p36c2firdcg8kr8cg6zbcg-closure-info/registration console=ttyAMA0,115200n8 console=tty0167server # [ 0.001614] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)168server # [ 0.001634] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)169server # [ 0.002696] cacheinfo: Unable to detect cache hierarchy for CPU 0170builder # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/5qwwb63f27p36c2firdcg8kr8cg6zbcg-closure-info/registration", will be passed to user space.171server # [ 0.003394] rcu: Hierarchical SRCU implementation.172builder # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes173server # [ 0.003398] rcu: Max phase no-delay instances is 1000.174server # [ 0.004597] fsl-mc MSI: its@8080000 domain created175builder # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)176server # [ 0.004687] EFI services will not be available.177server # [ 0.004752] smp: Bringing up secondary CPUs ...178builder # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)179server # [ 0.004760] smp: Brought up 1 node, 1 CPU180builder # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB181server # [ 0.004764] SMP: Total of 1 processors activated.182builder # [ 0.000000] software IO TLB: area num 1.183server # [ 0.004767] CPU: All CPU(s) started at EL1184builder # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)185server # [ 0.004778] CPU features: detected: Branch Target Identification186builder # [ 0.000000] Fallback order for Node 0: 0187server # [ 0.004783] CPU features: detected: ARMv8.4 Translation Table Level188builder # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144189builder # [ 0.000000] Policy zone: DMA190server # [ 0.004789] CPU features: detected: Instruction cache invalidation not required for I/D coherence191builder # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off192builder # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1193server # [ 0.004792] CPU features: detected: Data cache clean to the PoU not required for I/D coherence194builder # [ 0.000000] allocated 2097152 bytes of page_ext195server # [ 0.004796] CPU features: detected: Common not Private translations196builder # [ 0.000000] ftrace: allocating 74702 entries in 292 pages197server # [ 0.004799] CPU features: detected: CRC32 instructions198builder # [ 0.000000] ftrace: allocated 292 pages with 3 groups199builder # [ 0.000000] rcu: Hierarchical RCU implementation.200server # [ 0.004802] CPU features: detected: Data cache clean to Point of Deep Persistence201builder # [ 0.000000] rcu: RCU event tracing is enabled.202server # [ 0.004806] CPU features: detected: Data cache clean to Point of Persistence203builder # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.204builder # [ 0.000000] Trampoline variant of Tasks RCU enabled.205server # [ 0.004809] CPU features: detected: Data independent timing control (DIT)206server # [ 0.004812] CPU features: detected: E0PD207builder # [ 0.000000] Rude variant of Tasks RCU enabled.208builder # [ 0.000000] Tracing variant of Tasks RCU enabled.209server # [ 0.004815] CPU features: detected: Enhanced Counter Virtualization210builder # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.211server # [ 0.004818] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)212server # [ 0.004821] CPU features: detected: Enhanced Virtualization Traps213builder # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1214server # [ 0.004824] CPU features: detected: Fine Grained Traps215builder # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.216server # [ 0.004828] CPU features: detected: Generic authentication (architected QARMA5 algorithm)217builder # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.218server # [ 0.004833] CPU features: detected: RCpc load-acquire (LDAPR)219server # [ 0.004836] CPU features: detected: LSE atomic instructions220builder # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.221server # [ 0.004839] CPU features: detected: Privileged Access Never222builder # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0223server # [ 0.004841] CPU features: detected: PMUv3224builder # [ 0.000000] GICv3: 256 SPIs implemented225server # [ 0.004844] CPU features: detected: RAS Extension Support226builder # [ 0.000000] GICv3: 0 Extended SPIs implemented227builder # [ 0.000000] Root IRQ handler: gic_handle_irq228server # [ 0.004847] CPU features: detected: RASv1p1 Extension Support229builder # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI230server # [ 0.004849] CPU features: detected: Random Number Generator231builder # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0232server # [ 0.004852] CPU features: detected: Speculation barrier (SB)233builder # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000234server # [ 0.004854] CPU features: detected: Stage-2 Force Write-Back235builder # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]236server # [ 0.004857] CPU features: detected: TLB range maintenance instructions237builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)238server # [ 0.004862] CPU features: detected: Speculative Store Bypassing Safe (SSBS)239server # [ 0.004902] alternatives: applying system-wide alternatives240builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)241server # [ 0.007855] CPU features: detected: BBM Level 2 without TLB conflict abort242builder # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000243builder # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000244server # [ 0.008009] Memory: 895044K/1048576K available (24384K kernel code, 7086K rwdata, 26324K rodata, 4736K init, 1102K bss, 112248K reserved, 32768K cma-reserved)245builder # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.246server # [ 0.008394] devtmpfs: initialized247builder # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).248server # [ 0.010100] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns249builder # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns250server # [ 0.010125] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)251builder # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns252server # [ 0.010146] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).253builder # [ 0.000028] arm-pv: using stolen time PV254server # [ 0.010322] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL255server # [ 0.010326] 0 pages in range for non-PLT usage256builder # [ 0.000382] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)257server # [ 0.010328] 508320 pages in range for PLT usage258builder # [ 0.000522] Console: colour dummy device 80x25259server # [ 0.010446] pinctrl core: initialized pinctrl subsystem260builder # [ 0.000530] printk: legacy console [tty0] enabled261builder # [ 0.000708] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)262builder # [ 0.000715] pid_max: default: 32768 minimum: 301263builder # [ 0.000794] LSM: initializing lsm=capability,landlock,yama,bpf,ima264builder # [ 0.000964] landlock: Up and running.265builder # [ 0.000967] Yama: becoming mindful.266builder # [ 0.001384] LSM support for eBPF active267builder # [ 0.001496] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)268builder # [ 0.001515] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)269builder # [ 0.002549] cacheinfo: Unable to detect cache hierarchy for CPU 0270builder # [ 0.003287] rcu: Hierarchical SRCU implementation.271builder # [ 0.003292] rcu: Max phase no-delay instances is 1000.272server # [ 0.011237] DMI not present or invalid.273builder # [ 0.004530] fsl-mc MSI: its@8080000 domain created274builder # [ 0.004619] EFI services will not be available.275server # [ 0.014388] NET: Registered PF_NETLINK/PF_ROUTE protocol family276builder # [ 0.004685] smp: Bringing up secondary CPUs ...277server # [ 0.016640] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations278builder # [ 0.004693] smp: Brought up 1 node, 1 CPU279builder # [ 0.004696] SMP: Total of 1 processors activated.280server # [ 0.016807] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations281builder # [ 0.004699] CPU: All CPU(s) started at EL1282server # [ 0.016967] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations283builder # [ 0.004709] CPU features: detected: Branch Target Identification284server # [ 0.016989] audit: initializing netlink subsys (disabled)285builder # [ 0.004713] CPU features: detected: ARMv8.4 Translation Table Level286server # [ 0.017502] thermal_sys: Registered thermal governor 'fair_share'287builder # [ 0.004719] CPU features: detected: Instruction cache invalidation not required for I/D coherence288server # [ 0.017504] thermal_sys: Registered thermal governor 'bang_bang'289server # [ 0.017507] thermal_sys: Registered thermal governor 'step_wise'290builder # [ 0.004722] CPU features: detected: Data cache clean to the PoU not required for I/D coherence291server # [ 0.017510] thermal_sys: Registered thermal governor 'user_space'292builder # [ 0.004726] CPU features: detected: Common not Private translations293server # [ 0.017513] thermal_sys: Registered thermal governor 'power_allocator'294builder # [ 0.004729] CPU features: detected: CRC32 instructions295server # [ 0.017537] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1296builder # [ 0.004732] CPU features: detected: Data cache clean to Point of Deep Persistence297server # [ 0.017547] cpuidle: using governor ladder298builder # [ 0.004736] CPU features: detected: Data cache clean to Point of Persistence299server # [ 0.017553] cpuidle: using governor menu300builder # [ 0.004739] CPU features: detected: Data independent timing control (DIT)301server # [ 0.017746] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.302builder # [ 0.004742] CPU features: detected: E0PD303server # [ 0.017764] ASID allocator initialised with 65536 entries304builder # [ 0.004745] CPU features: detected: Enhanced Counter Virtualization305server # [ 0.019225] Serial: AMBA PL011 UART driver306builder # [ 0.004748] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)307server # [ 0.024525] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1308builder # [ 0.004752] CPU features: detected: Enhanced Virtualization Traps309server # [ 0.024682] printk: console [ttyAMA0] enabled310builder # [ 0.004754] CPU features: detected: Fine Grained Traps311server # [ 0.153806] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages312builder # [ 0.004758] CPU features: detected: Generic authentication (architected QARMA5 algorithm)313server # [ 0.153831] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page314builder # [ 0.004762] CPU features: detected: RCpc load-acquire (LDAPR)315server # [ 0.153837] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages316builder # [ 0.004765] CPU features: detected: LSE atomic instructions317builder # [ 0.004768] CPU features: detected: Privileged Access Never318server # [ 0.153841] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page319builder # [ 0.004771] CPU features: detected: PMUv3320server # [ 0.153845] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages321builder # [ 0.004773] CPU features: detected: RAS Extension Support322server # [ 0.153849] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page323builder # [ 0.004776] CPU features: detected: RASv1p1 Extension Support324server # [ 0.153853] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages325builder # [ 0.004778] CPU features: detected: Random Number Generator326builder # [ 0.004781] CPU features: detected: Speculation barrier (SB)327server # [ 0.153857] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page328builder # [ 0.004784] CPU features: detected: Stage-2 Force Write-Back329builder # [ 0.004787] CPU features: detected: TLB range maintenance instructions330builder # [ 0.004791] CPU features: detected: Speculative Store Bypassing Safe (SSBS)331server # [ 0.161602] fbcon: Taking over console332builder # [ 0.004827] alternatives: applying system-wide alternatives333server # [ 0.161617] ACPI: Interpreter disabled.334server # [ 0.163494] iommu: Default domain type: Translated335server # [ 0.163506] iommu: DMA domain TLB invalidation policy: strict mode336server # [ 0.165222] SCSI subsystem initialized337builder # [ 0.007770] CPU features: detected: BBM Level 2 without TLB conflict abort338server # [ 0.172595] usbcore: registered new interface driver usbfs339builder # [ 0.007929] Memory: 895084K/1048576K available (24384K kernel code, 7086K rwdata, 26324K rodata, 4736K init, 1102K bss, 112248K reserved, 32768K cma-reserved)340server # [ 0.172627] usbcore: registered new interface driver hub341builder # [ 0.008315] devtmpfs: initialized342server # [ 0.172642] usbcore: registered new device driver usb343builder # [ 0.009999] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns344server # [ 0.172927] pps_core: LinuxPPS API ver. 1 registered345builder # [ 0.010024] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)346server # [ 0.172933] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>347server # [ 0.172943] PTP clock support registered348builder # [ 0.010046] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).349server # [ 0.172998] EDAC MC: Ver: 3.0.0350builder # [ 0.010225] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL351server # [ 0.177880] scmi_core: SCMI protocol bus registered352builder # [ 0.010229] 0 pages in range for non-PLT usage353builder # [ 0.010230] 508320 pages in range for PLT usage354server # [ 0.178885] FPGA manager framework355builder # [ 0.010354] pinctrl core: initialized pinctrl subsystem356server # [ 0.179866] vgaarb: loaded357builder # [ 0.011134] DMI not present or invalid.358builder # [ 0.014287] NET: Registered PF_NETLINK/PF_ROUTE protocol family359server # [ 0.180526] clocksource: Switched to clocksource arch_sys_counter360builder # [ 0.016775] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations361server # [ 0.181004] VFS: Disk quotas dquot_6.6.0362server # [ 0.181031] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)363builder # [ 0.016937] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations364server # [ 0.183466] netfs: FS-Cache loaded365builder # [ 0.017100] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations366server # [ 0.183581] pnp: PnP ACPI: disabled367builder # [ 0.017125] audit: initializing netlink subsys (disabled)368builder # [ 0.017650] thermal_sys: Registered thermal governor 'fair_share'369builder # [ 0.017652] thermal_sys: Registered thermal governor 'bang_bang'370builder # [ 0.017656] thermal_sys: Registered thermal governor 'step_wise'371builder # [ 0.017659] thermal_sys: Registered thermal governor 'user_space'372builder # [ 0.017662] thermal_sys: Registered thermal governor 'power_allocator'373builder # [ 0.017686] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1374builder # [ 0.017697] cpuidle: using governor ladder375builder # [ 0.017702] cpuidle: using governor menu376builder # [ 0.017890] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.377builder # [ 0.017906] ASID allocator initialised with 65536 entries378builder # [ 0.019017] Serial: AMBA PL011 UART driver379server # [ 0.189707] NET: Registered PF_INET protocol family380builder # [ 0.024171] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1381server # [ 0.189872] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)382builder # [ 0.024318] printk: console [ttyAMA0] enabled383builder # [ 0.151731] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages384builder # [ 0.151751] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page385builder # [ 0.151757] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages386builder # [ 0.151762] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page387builder # [ 0.151766] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages388builder # [ 0.151770] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page389builder # [ 0.151774] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages390builder # [ 0.151778] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page391builder # [ 0.159418] fbcon: Taking over console392builder # [ 0.159432] ACPI: Interpreter disabled.393builder # [ 0.161318] iommu: Default domain type: Translated394builder # [ 0.161328] iommu: DMA domain TLB invalidation policy: strict mode395builder # [ 0.163050] SCSI subsystem initialized396builder # [ 0.170285] usbcore: registered new interface driver usbfs397builder # [ 0.170324] usbcore: registered new interface driver hub398builder # [ 0.170359] usbcore: registered new device driver usb399builder # [ 0.170618] pps_core: LinuxPPS API ver. 1 registered400builder # [ 0.170624] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>401builder # [ 0.170634] PTP clock support registered402builder # [ 0.170690] EDAC MC: Ver: 3.0.0403builder # [ 0.175491] scmi_core: SCMI protocol bus registered404builder # [ 0.176504] FPGA manager framework405builder # [ 0.177521] vgaarb: loaded406builder # [ 0.178167] clocksource: Switched to clocksource arch_sys_counter407builder # [ 0.178620] VFS: Disk quotas dquot_6.6.0408builder # [ 0.178646] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)409builder # [ 0.181048] netfs: FS-Cache loaded410builder # [ 0.181172] pnp: PnP ACPI: disabled411server # [ 0.218533] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)412server # [ 0.218579] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)413server # [ 0.218604] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)414server # [ 0.218646] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)415server # [ 0.218723] TCP: Hash tables configured (established 8192 bind 8192)416server # [ 0.218806] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)417server # [ 0.218867] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)418server # [ 0.218915] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)419server # [ 0.219034] NET: Registered PF_UNIX/PF_LOCAL protocol family420server # [ 0.219073] NET: Registered PF_XDP protocol family421server # [ 0.219091] PCI: CLS 0 bytes, default 64422builder # [ 0.187226] NET: Registered PF_INET protocol family423server # [ 0.219346] Trying to unpack rootfs image as initramfs...424builder # [ 0.187395] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)425server # [ 0.234223] kvm [1]: HYP mode not available426builder # [ 0.216547] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)427builder # [ 0.216586] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)428builder # [ 0.216611] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)429builder # [ 0.216656] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)430builder # [ 0.216731] TCP: Hash tables configured (established 8192 bind 8192)431builder # [ 0.216811] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)432builder # [ 0.216865] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)433builder # [ 0.216912] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)434builder # [ 0.217038] NET: Registered PF_UNIX/PF_LOCAL protocol family435builder # [ 0.217089] NET: Registered PF_XDP protocol family436builder # [ 0.217108] PCI: CLS 0 bytes, default 64437builder # [ 0.217360] Trying to unpack rootfs image as initramfs...438builder # [ 0.231944] kvm [1]: HYP mode not available439server # [ 0.325076] Initialise system trusted keyrings440server # [ 0.325835] workingset: timestamp_bits=42 max_order=18 bucket_order=0441server # [ 0.327143] squashfs: version 4.0 (2009/01/31) Phillip Lougher442server # [ 0.327920] 9p: Installing v9fs 9p2000 file system support443server # [ 0.348518] Key type asymmetric registered444server # [ 0.356639] Asymmetric key parser 'x509' registered445server # [ 0.356716] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)446server # [ 0.358587] io scheduler mq-deadline registered447server # [ 0.358599] io scheduler kyber registered448server # [ 0.368694] pl061_gpio 9030000.pl061: PL061 GPIO chip registered449builder # [ 0.326719] Initialise system trusted keyrings450builder # [ 0.327483] workingset: timestamp_bits=42 max_order=18 bucket_order=0451builder # [ 0.328829] squashfs: version 4.0 (2009/01/31) Phillip Lougher452server # [ 0.370116] ledtrig-cpu: registered to indicate activity on CPUs453builder # [ 0.329634] 9p: Installing v9fs 9p2000 file system support454server # [ 0.370506] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:455server # [ 0.370524] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000456server # [ 0.370539] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000457server # [ 0.370548] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000458server # [ 0.370569] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits459server # [ 0.370596] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]460server # [ 0.370671] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00461server # [ 0.370681] pci_bus 0000:00: root bus resource [bus 00-ff]462server # [ 0.370687] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]463server # [ 0.370692] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]464server # [ 0.370697] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]465server # [ 0.370768] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint466server # [ 0.371241] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint467server # [ 0.371431] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]468server # [ 0.371449] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]469server # [ 0.371479] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]470server # [ 0.371500] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]471server # [ 0.371965] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint472server # [ 0.372151] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]473server # [ 0.372168] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]474server # [ 0.372198] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]475server # [ 0.392153] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint476server # [ 0.392342] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]477server # [ 0.392359] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]478server # [ 0.392392] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]479server # [ 0.396066] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint480server # [ 0.396251] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]481server # [ 0.396268] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]482server # [ 0.396298] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]483builder # [ 0.358403] Key type asymmetric registered484builder # [ 0.358429] Asymmetric key parser 'x509' registered485builder # [ 0.358495] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)486builder # [ 0.360715] io scheduler mq-deadline registered487builder # [ 0.360726] io scheduler kyber registered488server # [ 0.404899] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint489server # [ 0.405110] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]490server # [ 0.405129] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]491server # [ 0.405160] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]492server # [ 0.405624] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint493server # [ 0.405811] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]494server # [ 0.405828] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]495builder # [ 0.370326] pl061_gpio 9030000.pl061: PL061 GPIO chip registered496server # [ 0.405858] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]497server # [ 0.406312] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint498server # [ 0.406498] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]499server # [ 0.406514] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]500builder # [ 0.371734] ledtrig-cpu: registered to indicate activity on CPUs501server # [ 0.406545] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]502builder # [ 0.372128] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:503server # [ 0.406562] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]504builder # [ 0.372147] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000505server # [ 0.407027] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint506server # [ 0.407237] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]507builder # [ 0.372182] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000508server # [ 0.407268] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]509builder # [ 0.372191] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000510server # [ 0.407720] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint511builder # [ 0.372214] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits512server # [ 0.407905] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]513server # [ 0.407935] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]514builder # [ 0.372251] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]515server # [ 0.408330] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint516builder # [ 0.372339] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00517builder # [ 0.372350] pci_bus 0000:00: root bus resource [bus 00-ff]518server # [ 0.408514] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]519builder # [ 0.372355] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]520builder # [ 0.372360] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]521builder # [ 0.372366] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]522server # [ 0.426334] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint523builder # [ 0.372424] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint524server # [ 0.426591] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]525builder # [ 0.372871] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint526server # [ 0.426609] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]527builder # [ 0.373059] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]528server # [ 0.426640] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]529builder # [ 0.373075] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]530server # [ 0.427103] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint531builder # [ 0.373106] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]532server # [ 0.427309] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]533builder # [ 0.373122] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]534server # [ 0.427326] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]535builder # [ 0.373580] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint536server # [ 0.427358] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]537builder # [ 0.373764] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]538server # [ 0.427975] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned539builder # [ 0.373779] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]540builder # [ 0.373808] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]541server # [ 0.427986] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned542server # [ 0.427993] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned543builder # [ 0.394064] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint544server # [ 0.428038] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned545server # [ 0.428086] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned546server # [ 0.428136] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned547builder # [ 0.395262] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]548server # [ 0.428183] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned549builder # [ 0.395281] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]550server # [ 0.428229] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned551builder # [ 0.395314] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]552server # [ 0.428277] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned553builder # [ 0.395808] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint554server # [ 0.428324] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned555builder # [ 0.396000] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]556builder # [ 0.396017] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]557server # [ 0.428371] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned558builder # [ 0.396046] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]559server # [ 0.428418] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned560builder # [ 0.396519] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint561server # [ 0.428488] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned562builder # [ 0.396705] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]563builder # [ 0.396721] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]564builder # [ 0.396750] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]565builder # [ 0.397214] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint566builder # [ 0.397398] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]567builder # [ 0.397414] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]568builder # [ 0.397444] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]569builder # [ 0.397907] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint570builder # [ 0.398091] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]571builder # [ 0.398107] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]572builder # [ 0.398137] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]573server # [ 0.452616] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned574builder # [ 0.398152] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]575server # [ 0.452655] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned576server # [ 0.452679] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned577server # [ 0.452702] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned578server # [ 0.452725] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned579server # [ 0.452748] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned580server # [ 0.452774] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned581server # [ 0.452797] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned582server # [ 0.452821] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned583server # [ 0.452845] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned584server # [ 0.452868] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned585builder # [ 0.418730] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint586server # [ 0.452892] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned587builder # [ 0.418940] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]588server # [ 0.452915] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned589builder # [ 0.418971] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]590server # [ 0.452937] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned591server # [ 0.452959] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned592builder # [ 0.419439] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint593server # [ 0.452982] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned594builder # [ 0.419632] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]595server # [ 0.453004] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned596builder # [ 0.419662] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]597server # [ 0.453026] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned598builder # [ 0.420062] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint599server # [ 0.453049] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned600builder # [ 0.420263] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]601server # [ 0.453072] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned602server # [ 0.453094] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned603builder # [ 0.420525] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint604builder # [ 0.420806] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]605server # [ 0.453140] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]606builder # [ 0.420828] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]607server # [ 0.453151] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]608server # [ 0.453156] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]609builder # [ 0.420858] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]610server # [ 0.454368] pci 0000:00:0a.0: enabling device (0000 -> 0002)611builder # [ 0.421343] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint612builder # [ 0.421529] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]613builder # [ 0.421545] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]614builder # [ 0.421575] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]615builder # [ 0.442210] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned616builder # [ 0.442234] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned617builder # [ 0.442240] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned618builder # [ 0.442319] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned619builder # [ 0.442367] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned620builder # [ 0.442415] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned621builder # [ 0.442464] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned622builder # [ 0.442511] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned623builder # [ 0.442557] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned624builder # [ 0.442606] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned625builder # [ 0.442654] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned626builder # [ 0.442701] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned627builder # [ 0.442834] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned628builder # [ 0.442883] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned629builder # [ 0.442905] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned630builder # [ 0.442927] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned631server # [ 0.502614] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)632builder # [ 0.442951] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned633builder # [ 0.442972] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned634builder # [ 0.442994] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned635builder # [ 0.443019] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned636builder # [ 0.443040] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned637builder # [ 0.443062] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned638builder # [ 0.443086] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned639builder # [ 0.443111] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned640builder # [ 0.443133] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned641builder # [ 0.443155] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned642builder # [ 0.443176] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned643builder # [ 0.443197] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned644server # [ 0.505846] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)645builder # [ 0.443218] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned646server # [ 0.507860] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)647builder # [ 0.443239] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned648server # [ 0.509942] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)649builder # [ 0.443261] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned650server # [ 0.512001] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)651builder # [ 0.443282] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned652builder # [ 0.443303] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned653builder # [ 0.443325] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned654builder # [ 0.443357] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]655builder # [ 0.443367] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]656builder # [ 0.443372] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]657builder # [ 0.444577] pci 0000:00:0a.0: enabling device (0000 -> 0002)658server # [ 0.530265] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)659server # [ 0.532361] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)660server # [ 0.536186] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)661server # [ 0.539110] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)662builder # [ 0.504494] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)663server # [ 0.548701] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)664server # [ 0.551234] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)665builder # [ 0.507538] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)666builder # [ 0.509658] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)667builder # [ 0.511850] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)668builder # [ 0.513964] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)669server # [ 0.569053] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled670server # [ 0.571707] msm_serial: driver initialized671server # [ 0.571850] SuperH (H)SCI(F) driver initialized672server # [ 0.571903] STM32 USART driver initialized673builder # [ 0.531685] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)674builder # [ 0.533919] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)675builder # [ 0.537768] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)676builder # [ 0.546318] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)677builder # [ 0.548483] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)678server # [ 0.605799] loop: module loaded679server # [ 0.605973] virtio_blk virtio5: 1/0/0 default/read/poll queues680server # [ 0.606788] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)681builder # [ 0.566752] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)682builder # [ 0.572706] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled683server # [ 0.613149] megasas: 07.734.00.00-rc1684server # [ 0.613813] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]685builder # [ 0.575213] msm_serial: driver initialized686builder # [ 0.575347] SuperH (H)SCI(F) driver initialized687builder # [ 0.575402] STM32 USART driver initialized688server # [ 0.624594] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000689server # [ 0.624619] Intel/Sharp Extended Query Table at 0x0031690server # [ 0.626824] Using buffer write method691server # [ 0.626892] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]692server # [ 0.628728] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000693server # [ 0.628747] Intel/Sharp Extended Query Table at 0x0031694server # [ 0.630447] Using buffer write method695server # [ 0.630473] Concatenating MTD devices:696server # [ 0.630477] (0): "0.flash"697server # [ 0.630481] (1): "0.flash"698server # [ 0.630484] into device "0.flash"699builder # [ 0.611760] loop: module loaded700builder # [ 0.611968] virtio_blk virtio5: 1/0/0 default/read/poll queues701builder # [ 0.612804] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)702builder # [ 0.618804] megasas: 07.734.00.00-rc1703builder # [ 0.619481] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]704builder # [ 0.622125] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000705builder # [ 0.622149] Intel/Sharp Extended Query Table at 0x0031706builder # [ 0.631261] Using buffer write method707builder # [ 0.631328] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]708builder # [ 0.642199] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000709builder # [ 0.642223] Intel/Sharp Extended Query Table at 0x0031710builder # [ 0.643800] Using buffer write method711builder # [ 0.643828] Concatenating MTD devices:712builder # [ 0.643832] (0): "0.flash"713builder # [ 0.643836] (1): "0.flash"714builder # [ 0.643839] into device "0.flash"715server # [ 0.872214] Freeing initrd memory: 25520K716server # [ 0.878153] tun: Universal TUN/TAP device driver, 1.6717server # [ 0.881977] thunder_xcv, ver 1.0718server # [ 0.882023] thunder_bgx, ver 1.0719server # [ 0.882046] nicpf, ver 1.0720server # [ 0.882578] e1000: Intel(R) PRO/1000 Network Driver721server # [ 0.882586] e1000: Copyright (c) 1999-2006 Intel Corporation.722server # [ 0.882615] e1000e: Intel(R) PRO/1000 Network Driver723server # [ 0.882622] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.724server # [ 0.882651] igb: Intel(R) Gigabit Ethernet Network Driver725server # [ 0.882657] igb: Copyright (c) 2007-2014 Intel Corporation.726server # [ 0.882679] igbvf: Intel(R) Gigabit Virtual Function Network Driver727server # [ 0.882685] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.728server # [ 0.882816] sky2: driver version 1.30729server # [ 0.884366] usbcore: registered new interface driver usb-storage730server # [ 0.884413] usbcore: registered new interface driver usbserial_generic731server # [ 0.884427] usbserial: USB Serial support registered for generic732server # [ 0.894727] hv_vmbus: registering driver hyperv_keyboard733server # [ 0.896794] ehci-pci 0000:00:0a.0: EHCI Host Controller734server # [ 0.896823] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1735server # [ 0.897026] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000736server # [ 0.899689] rtc-pl031 9010000.pl031: registered as rtc0737server # [ 0.899725] rtc-pl031 9010000.pl031: setting system clock to 2026-07-07T08:16:41 UTC (1783412201)738server # [ 0.900057] i2c_dev: i2c /dev entries driver739server # [ 0.905056] sdhci: Secure Digital Host Controller Interface driver740server # [ 0.905066] sdhci: Copyright(c) Pierre Ossman741server # [ 0.905323] Synopsys Designware Multimedia Card Interface Driver742server # [ 0.905680] sdhci-pltfm: SDHCI platform and OF driver helper743server # [ 0.907297] hid: raw HID events driver (C) Jiri Kosina744server # [ 0.907518] usbcore: registered new interface driver usbhid745server # [ 0.907525] usbhid: USB HID core driver746server # [ 0.913310] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00747server # [ 0.913611] hub 1-0:1.0: USB hub found748server # [ 0.913631] hub 1-0:1.0: 6 ports detected749server # [ 0.915788] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available750server # [ 0.918387] drop_monitor: Initializing network drop monitor service751server # [ 0.918593] NET: Registered PF_INET6 protocol family752server # [ 0.920839] Segment Routing with IPv6753server # [ 0.920859] In-situ OAM (IOAM) with IPv6754server # [ 0.920888] NET: Registered PF_PACKET protocol family755server # [ 0.922540] 9pnet: Installing 9P2000 support756server # [ 0.925403] Key type dns_resolver registered757builder # [ 0.887647] Freeing initrd memory: 25520K758server # [ 0.931676] registered taskstats version 1759server # [ 0.931824] Loading compiled-in X.509 certificates760builder # [ 0.893623] tun: Universal TUN/TAP device driver, 1.6761server # [ 0.940248] Demotion targets for Node 0: null762server # [ 0.940354] Key type .fscrypt registered763server # [ 0.940362] Key type fscrypt-provisioning registered764builder # [ 0.897439] thunder_xcv, ver 1.0765builder # [ 0.897486] thunder_bgx, ver 1.0766builder # [ 0.897510] nicpf, ver 1.0767builder # [ 0.898048] e1000: Intel(R) PRO/1000 Network Driver768server # [ 0.940458] ima: No TPM chip found, activating TPM-bypass!769builder # [ 0.898057] e1000: Copyright (c) 1999-2006 Intel Corporation.770server # [ 0.940479] ima: Allocated hash algorithm: sha1771server # [ 0.940498] ima: No architecture policies found772builder # [ 0.898089] e1000e: Intel(R) PRO/1000 Network Driver773builder # [ 0.898096] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.774builder # [ 0.898127] igb: Intel(R) Gigabit Ethernet Network Driver775server # [ 0.944871] input: gpio-keys as /devices/platform/gpio-keys/input/input0776builder # [ 0.898132] igb: Copyright (c) 2007-2014 Intel Corporation.777builder # [ 0.898154] igbvf: Intel(R) Gigabit Virtual Function Network Driver778builder # [ 0.898160] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.779builder # [ 0.905199] sky2: driver version 1.30780builder # [ 0.906798] usbcore: registered new interface driver usb-storage781builder # [ 0.906881] usbcore: registered new interface driver usbserial_generic782builder # [ 0.906894] usbserial: USB Serial support registered for generic783builder # [ 0.907468] hv_vmbus: registering driver hyperv_keyboard784builder # [ 0.908857] ehci-pci 0000:00:0a.0: EHCI Host Controller785builder # [ 0.908885] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1786builder # [ 0.909053] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000787builder # [ 0.915225] rtc-pl031 9010000.pl031: registered as rtc0788builder # [ 0.915256] rtc-pl031 9010000.pl031: setting system clock to 2026-07-07T08:16:41 UTC (1783412201)789builder # [ 0.915603] i2c_dev: i2c /dev entries driver790builder # [ 0.918695] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00791builder # [ 0.918996] hub 1-0:1.0: USB hub found792builder # [ 0.919020] hub 1-0:1.0: 6 ports detected793server # [ 0.962391] clk: Disabling unused clocks794server # [ 0.962420] PM: genpd: Disabling unused power domains795server # [ 0.966815] Freeing unused kernel memory: 4736K796server # [ 0.967008] Run /init as init process797builder # [ 0.922979] sdhci: Secure Digital Host Controller Interface driver798builder # [ 0.922989] sdhci: Copyright(c) Pierre Ossman799builder # [ 0.923256] Synopsys Designware Multimedia Card Interface Driver800builder # [ 0.923616] sdhci-pltfm: SDHCI platform and OF driver helper801builder # [ 0.925316] hid: raw HID events driver (C) Jiri Kosina802builder # [ 0.925553] usbcore: registered new interface driver usbhid803builder # [ 0.925561] usbhid: USB HID core driver804builder # [ 0.931422] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available805builder # [ 0.932926] drop_monitor: Initializing network drop monitor service806builder # [ 0.933076] NET: Registered PF_INET6 protocol family807builder # [ 0.936249] Segment Routing with IPv6808builder # [ 0.936271] In-situ OAM (IOAM) with IPv6809builder # [ 0.936311] NET: Registered PF_PACKET protocol family810builder # [ 0.937990] 9pnet: Installing 9P2000 support811server # [ 0.982871] systemd[1]: Successfully made /usr/ read-only.812builder # [ 0.940793] Key type dns_resolver registered813builder # [ 0.947278] registered taskstats version 1814builder # [ 0.947426] Loading compiled-in X.509 certificates815builder # [ 0.955758] Demotion targets for Node 0: null816builder # [ 0.955866] Key type .fscrypt registered817builder # [ 0.955874] Key type fscrypt-provisioning registered818builder # [ 0.955977] ima: No TPM chip found, activating TPM-bypass!819builder # [ 0.955996] ima: Allocated hash algorithm: sha1820builder # [ 0.956018] ima: No architecture policies found821builder # [ 0.960333] input: gpio-keys as /devices/platform/gpio-keys/input/input0822builder # [ 0.979318] clk: Disabling unused clocks823builder # [ 0.979350] PM: genpd: Disabling unused power domains824builder # [ 0.983804] Freeing unused kernel memory: 4736K825builder # [ 0.984022] Run /init as init process826builder # [ 1.000553] systemd[1]: Successfully made /usr/ read-only.827server # [ 1.160626] usb 1-1: new high-speed USB device number 2 using ehci-pci828builder # [ 1.166254] usb 1-1: new high-speed USB device number 2 using ehci-pci829server # [ 1.314925] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1830server # [ 1.321524] 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)831server # [ 1.334227] systemd[1]: Detected virtualization qemu.832server # [ 1.334327] systemd[1]: Detected architecture arm64.833server # [ 1.334353] systemd[1]: Running in initrd.834server # [ 1.335502] systemd[1]: Initializing machine ID from random generator.835server # [ 1.335785] systemd[1]: Hostname set to <server>.836builder # [ 1.318443] 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/input1837builder # [ 1.335562] 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)838builder # [ 1.347973] systemd[1]: Detected virtualization qemu.839builder # [ 1.350141] systemd[1]: Detected architecture arm64.840builder # [ 1.352124] systemd[1]: Running in initrd.841builder # [ 1.354937] systemd[1]: Initializing machine ID from random generator.842builder # [ 1.357837] systemd[1]: Hostname set to <builder>.843server # [ 1.405099] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0844builder # [ 1.402594] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0845server # [ 1.459159] systemd[1]: Queued start job for default target Initrd Default Target.846server # [ 1.470722] systemd[1]: Created slice Slice /system/modprobe.847server # [ 1.471937] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.848server # [ 1.473303] systemd[1]: Expecting device /dev/disk/by-label/nixos...849server # [ 1.474345] systemd[1]: Reached target Path Units.850server # [ 1.475155] systemd[1]: Reached target Slice Units.851server # [ 1.476153] systemd[1]: Reached target Swaps.852server # [ 1.476971] systemd[1]: Reached target Timer Units.853server # [ 1.477937] systemd[1]: Listening on D-Bus System Message Bus Socket.854server # [ 1.479182] systemd[1]: Listening on Journal Socket (/dev/log).855server # [ 1.480331] systemd[1]: Listening on Journal Sockets.856server # [ 1.481373] systemd[1]: Listening on udev Control Socket.857server # [ 1.481486] systemd[1]: Listening on udev Kernel Socket.858server # [ 1.481509] systemd[1]: Reached target Socket Units.859server # [ 1.485576] systemd[1]: Starting Create List of Static Device Nodes...860server # [ 1.495798] systemd[1]: Starting Load Kernel Module 9pnet_virtio...861server # [ 1.497040] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs862server # [ 1.500234] systemd[1]: Mounting Kernel Configuration File System...863server # [ 1.510601] systemd[1]: Starting Journal Service...864builder # [ 1.475534] systemd[1]: Queued start job for default target Initrd Default Target.865builder # [ 1.484162] systemd[1]: Created slice Slice /system/modprobe.866builder # [ 1.485342] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.867builder # [ 1.486664] systemd[1]: Expecting device /dev/disk/by-label/nixos...868builder # [ 1.487687] systemd[1]: Reached target Path Units.869builder # [ 1.488475] systemd[1]: Reached target Slice Units.870builder # [ 1.489270] systemd[1]: Reached target Swaps.871builder # [ 1.489980] systemd[1]: Reached target Timer Units.872server # [ 1.532708] usb 1-2: new high-speed USB device number 3 using ehci-pci873server # [ 1.534048] systemd[1]: Starting Load Kernel Modules...874builder # [ 1.491006] systemd[1]: Listening on D-Bus System Message Bus Socket.875builder # [ 1.492224] systemd[1]: Listening on Journal Socket (/dev/log).876server # [ 1.534816] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki877builder # [ 1.493356] systemd[1]: Listening on Journal Sockets.878builder # [ 1.494356] systemd[1]: Listening on udev Control Socket.879builder # [ 1.495332] systemd[1]: Listening on udev Kernel Socket.880builder # [ 1.496220] systemd[1]: Reached target Socket Units.881builder # [ 1.498501] systemd[1]: Starting Create List of Static Device Nodes...882server # [ 1.544665] systemd[1]: Starting Coldplug All udev Devices...883builder # [ 1.506363] systemd[1]: Starting Load Kernel Module 9pnet_virtio...884server # [ 1.549906] systemd[1]: Finished Create List of Static Device Nodes.885builder # [ 1.507483] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs886server # [ 1.551704] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.887builder # [ 1.513361] systemd[1]: Mounting Kernel Configuration File System...888server # [ 1.557305] systemd[1]: Finished Load Kernel Module 9pnet_virtio.889builder # [ 1.524232] systemd[1]: Starting Journal Service...890builder # [ 1.530238] usb 1-2: new high-speed USB device number 3 using ehci-pci891server # [ 1.573944] systemd-journald[73]: Collecting audit messages is disabled.892server # [ 1.575010] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...893server # [ 1.576337] systemd[1]: Mounted Kernel Configuration File System.894builder # [ 1.550520] systemd[1]: Starting Load Kernel Modules...895builder # [ 1.551469] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki896builder # [ 1.558469] systemd[1]: Starting Coldplug All udev Devices...897server # [ 1.612343] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.898builder # [ 1.574653] systemd[1]: Finished Create List of Static Device Nodes.899server # [ 1.621145] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.900builder # [ 1.575590] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.901builder # [ 1.575950] systemd[1]: Finished Load Kernel Module 9pnet_virtio.902builder # [ 1.576238] systemd[1]: Mounted Kernel Configuration File System.903server # [ 1.623433] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev904builder # [ 1.594522] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...905server # [ 1.632985] systemd[1]: Starting Create Static Device Nodes in /dev...906server # [ 1.640333] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0907server # [ 1.640607] [drm] features: -virgl +edid -resource_blob -host_visible908server # [ 1.640616] [drm] features: -context_init909server # [ 1.641330] [drm] number of scanouts: 1910server # [ 1.641350] [drm] number of cap sets: 0911server # [ 1.653685] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic912server # [ 1.653704] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0913builder # [ 1.610169] systemd-journald[73]: Collecting audit messages is disabled.914builder # [ 1.619834] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.915builder # [ 1.634607] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev916server # [ 1.684833] Console: switching to colour frame buffer device 160x50917builder # [ 1.646054] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0918builder # [ 1.655215] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.919builder # [ 1.657832] systemd[1]: Starting Create Static Device Nodes in /dev...920server # [ 1.701385] systemd[1]: Finished Create Static Device Nodes in /dev.921server # [ 1.701612] systemd[1]: Reached target Preparation for Local File Systems.922server # [ 1.701636] systemd[1]: Reached target Local File Systems.923builder # [ 1.662539] [drm] features: -virgl +edid -resource_blob -host_visible924builder # [ 1.662560] [drm] features: -context_init925builder # [ 1.663317] [drm] number of scanouts: 1926builder # [ 1.663337] [drm] number of cap sets: 0927server # [ 1.709158] systemd[1]: Starting Rule-based Manager for Device Events and Files...928server # [ 1.711197] 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/input2929server # [ 1.711333] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0930builder # [ 1.670572] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic931builder # [ 1.670592] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0932server # [ 1.727018] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device933server # [ 1.739775] systemd[1]: Finished Load Kernel Modules.934builder # [ 1.693391] Console: switching to colour frame buffer device 160x50935server # [ 1.745287] systemd[1]: Starting Apply Kernel Variables...936builder # [ 1.707455] 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/input2937builder # [ 1.707587] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0938builder # [ 1.726840] systemd[1]: Finished Create Static Device Nodes in /dev.939builder # [ 1.727206] systemd[1]: Reached target Preparation for Local File Systems.940builder # [ 1.727233] systemd[1]: Reached target Local File Systems.941builder # [ 1.731231] systemd[1]: Starting Rule-based Manager for Device Events and Files...942builder # [ 1.740096] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device943server # [ 1.784279] systemd[1]: Started Journal Service.944server # [ 1.770955] systemd-modules-load[74]: Inserted module 'dm_mod'945server # [ 1.776427] systemd-modules-load[74]: Module 'virtio_balloon' is built in946server # [ 1.777535] systemd-modules-load[74]: Module 'virtio_console' is built in947server # [ 1.778651] systemd-modules-load[74]: Inserted module 'virtio_gpu'948server # [ 1.779645] systemd-modules-load[74]: Module 'virtio_rng' is built in949builder # [ 1.754471] systemd[1]: Finished Load Kernel Modules.950server # [ 1.800354] systemd[1]: Starting Create System Files and Directories...951builder # [ 1.770514] systemd[1]: Starting Apply Kernel Variables...952server # [ 1.801712] systemd-udevd[81]: Using default interface naming scheme 'v260'.953server # [ 1.808093] systemd[1]: Finished Apply Kernel Variables.954server # [ 1.830130] systemd[1]: Finished Create System Files and Directories.955builder # [ 1.818645] systemd[1]: Finished Apply Kernel Variables.956server # [ 1.847707] systemd[1]: Started Rule-based Manager for Device Events and Files.957builder # [ 1.829084] systemd[1]: Started Journal Service.958builder # [ 1.828395] systemd-modules-load[74]: Inserted module 'dm_mod'959builder # [ 1.829762] systemd-modules-load[74]: Module 'virtio_balloon' is built in960builder # [ 1.830887] systemd-modules-load[74]: Module 'virtio_console' is built in961builder # [ 1.831978] systemd-modules-load[74]: Inserted module 'virtio_gpu'962builder # [ 1.842107] systemd-modules-load[74]: Module 'virtio_rng' is built in963builder # [ 1.843567] systemd-udevd[80]: Using default interface naming scheme 'v260'.964builder # [ 1.847154] systemd[1]: Starting Create System Files and Directories...965server # [ 1.916125] systemd[1]: Starting Virtual Console Setup...966builder # [ 1.879377] systemd[1]: Finished Create System Files and Directories.967builder # [ 1.887084] systemd[1]: Started Rule-based Manager for Device Events and Files.968server # [ 1.984136] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.969server # [ 1.988860] systemd[1]: Finished Virtual Console Setup.970builder # [ 1.964109] systemd[1]: Starting Virtual Console Setup...971builder # [ 2.032905] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.972builder # [ 2.040223] systemd[1]: Finished Virtual Console Setup.973server # [ 2.716320] systemd[1]: Finished Coldplug All udev Devices.974server # [ 2.717371] systemd[1]: Reached target System Initialization.975server # [ 2.718194] systemd[1]: Reached target Basic System.976builder # [ 2.766998] systemd[1]: Finished Coldplug All udev Devices.977builder # [ 2.768737] systemd[1]: Reached target System Initialization.978builder # [ 2.769642] systemd[1]: Reached target Basic System.979server # [ 2.896331] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.980server # [ 2.912401] (udev-worker)[99]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.981server # [ 2.915687] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.982server # [ 2.968504] systemd[1]: Found device /dev/disk/by-label/nixos.983server # [ 2.969849] systemd[1]: Reached target Initrd Root Device.984builder # [ 2.930880] (udev-worker)[96]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.985server # [ 2.973698] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...986builder # [ 2.952972] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.987builder # [ 2.956656] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.988server # [ 3.029611] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks989server # [ 3.036959] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.990server # [ 3.046089] systemd[1]: Mounting /sysroot...991builder # [ 3.007680] systemd[1]: Found device /dev/disk/by-label/nixos.992builder # [ 3.013068] systemd[1]: Reached target Initrd Root Device.993builder # [ 3.018078] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...994server # [ 3.090082] EXT4-fs (vda): mounted filesystem f624c753-b1bf-4534-88ee-d924ff96a047 r/w with ordered data mode. Quota mode: none.995server # [ 3.078777] systemd[1]: Mounted /sysroot.996server # [ 3.080124] systemd[1]: Reached target Initrd Root File System.997server # [ 3.082094] systemd[1]: Starting Mountpoints Configured in the Real Root...998server # [ 3.108138] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.999builder # [ 3.070111] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks1000server # [ 3.113821] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...1001server # [ 3.119170] systemd[1]: Reloading...1002builder # [ 3.078370] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1003builder # [ 3.083331] systemd[1]: Mounting /sysroot...1004builder # [ 3.136916] EXT4-fs (vda): mounted filesystem 5b3158a0-7a49-496e-bfe8-122379b5f5f1 r/w with ordered data mode. Quota mode: none.1005builder # [ 3.127430] systemd[1]: Mounted /sysroot.1006builder # [ 3.128522] systemd[1]: Reached target Initrd Root File System.1007builder # [ 3.131933] systemd[1]: Starting Mountpoints Configured in the Real Root...1008builder # [ 3.158751] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.1009builder # [ 3.163650] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...1010builder # [ 3.168678] systemd[1]: Reloading...1011server # [ 3.335742] systemd[1]: Reloading finished in 219 ms.1012server # [ 3.350522] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...1013server # [ 3.356328] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...1014server # [ 3.360572] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1015server # [ 3.364903] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1016server # [ 3.368986] systemd[1]: Finished Mountpoints Configured in the Real Root.1017server # [ 3.370210] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1018server # [ 3.397892] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1019server # [ 3.398992] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1020builder # [ 3.392126] systemd[1]: Reloading finished in 224 ms.1021builder # [ 3.411042] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...1022builder # [ 3.415038] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...1023builder # [ 3.424811] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1024builder # [ 3.427088] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1025builder # [ 3.437172] systemd[1]: Finished Mountpoints Configured in the Real Root.1026builder # [ 3.440238] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1027builder # [ 3.453710] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1028builder # [ 3.455808] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1029server # [ 3.539552] systemd[1]: Mounting /sysroot/nix/.ro-store...1030server # [ 3.551155] systemd[1]: Mounting /sysroot/nix/.rw-store...1031server # [ 3.561960] systemd[1]: Mounting /sysroot/run...1032server # [ 3.576247] systemd[1]: Mounting /sysroot/tmp/shared...1033builder # [ 3.561546] systemd[1]: Mounting /sysroot/nix/.ro-store...1034server # [ 3.609802] systemd[1]: Mounting /sysroot/tmp/xchg...1035builder # [ 3.571750] systemd[1]: Mounting /sysroot/nix/.rw-store...1036server # [ 3.618744] systemd[1]: Mounted /sysroot/nix/.rw-store.1037server # [ 3.620321] systemd[1]: Mounted /sysroot/run.1038builder # [ 3.585498] systemd[1]: Mounting /sysroot/run...1039builder # [ 3.596207] systemd[1]: Mounting /sysroot/tmp/shared...1040server # [ 3.642762] systemd[1]: Starting rw-sysroot-nix-store.service...1041server # [ 3.645432] systemd[1]: Mounted /sysroot/nix/.ro-store.1042builder # [ 3.608750] systemd[1]: Mounting /sysroot/tmp/xchg...1043server # [ 3.658190] systemd[1]: Mounted /sysroot/tmp/shared.1044server # [ 3.671428] systemd[1]: Mounted /sysroot/tmp/xchg.1045server # [ 3.683446] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1046server # [ 3.685332] systemd[1]: Finished rw-sysroot-nix-store.service.1047server # [ 3.688920] systemd[1]: Mounting /sysroot/nix/store...1048builder # [ 3.654692] systemd[1]: Mounted /sysroot/nix/.rw-store.1049builder # [ 3.660096] systemd[1]: Mounted /sysroot/nix/.ro-store.1050builder # [ 3.675744] systemd[1]: Mounted /sysroot/run.1051builder # [ 3.678526] systemd[1]: Mounted /sysroot/tmp/shared.1052builder # [ 3.685334] systemd[1]: Mounted /sysroot/tmp/xchg.1053server # [ 3.729272] systemd[1]: Mounted /sysroot/nix/store.1054server # [ 3.730938] systemd[1]: Reached target Initrd File Systems.1055builder # [ 3.692992] systemd[1]: Starting rw-sysroot-nix-store.service...1056server # [ 3.734690] systemd[1]: Starting Find NixOS closure...1057server # [ 3.746088] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1058builder # [ 3.718273] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1059builder # [ 3.719394] systemd[1]: Finished rw-sysroot-nix-store.service.1060server # [ 3.785906] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1061server # [ 3.799994] systemd[1]: Finished Find NixOS closure.1062server # [ 3.802104] systemd[1]: Reached target Initrd Default Target.1063server # [ 3.811600] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1064server # [ 3.834182] systemd[1]: Stopped target Initrd Default Target.1065server # [ 3.836833] systemd[1]: Stopped target Basic System.1066server # [ 3.841552] systemd[1]: Stopped target Initrd Root Device.1067server # [ 3.842417] systemd[1]: Stopped target Path Units.1068server # [ 3.844111] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1069server # [ 3.846957] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1070server # [ 3.852244] systemd[1]: Stopped target Slice Units.1071server # [ 3.854157] systemd[1]: Stopped target Socket Units.1072server # [ 3.855518] systemd[1]: Stopped target System Initialization.1073server # [ 3.861044] systemd[1]: Stopped target Swaps.1074server # [ 3.861734] systemd[1]: Stopped target Timer Units.1075server # [ 3.862455] systemd[1]: dbus.socket: Deactivated successfully.1076server # [ 3.863310] systemd[1]: Closed D-Bus System Message Bus Socket.1077server # [ 3.869340] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1078server # [ 3.874217] systemd[1]: Stopped Find NixOS closure.1079server # [ 3.876437] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1080server # [ 3.881536] systemd[1]: Starting rw-sysroot-nix-store.service...1081server # [ 3.882457] systemd[1]: systemd-sysctl.service: Deactivated successfully.1082server # [ 3.884564] systemd[1]: Stopped Apply Kernel Variables.1083server # [ 3.887189] systemd[1]: systemd-modules-load.service: Deactivated successfully.1084server # [ 3.892341] systemd[1]: Stopped Load Kernel Modules.1085server # [ 3.895987] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1086server # [ 3.904225] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1087server # [ 3.910542] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1088server # [ 3.913076] systemd[1]: Stopped Create System Files and Directories.1089server # [ 3.917906] systemd[1]: Stopped target Local File Systems.1090server # [ 3.920211] systemd[1]: Stopped target Preparation for Local File Systems.1091server # [ 3.923717] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1092server # [ 3.925025] systemd[1]: Stopped Coldplug All udev Devices.1093server # [ 3.925821] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1094server # [ 3.926873] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1095server # [ 3.927914] systemd[1]: Stopped Virtual Console Setup.1096server # [ 3.932222] systemd[1]: initrd-cleanup.service: Deactivated successfully.1097server # [ 3.933230] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1098server # [ 3.934118] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1099server # [ 3.935305] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1100server # [ 3.941640] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1101server # [ 3.943220] systemd[1]: Finished rw-sysroot-nix-store.service.1102server # [ 3.944202] systemd[1]: systemd-udevd.service: Deactivated successfully.1103server # [ 3.945156] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1104server # [ 3.946154] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1105server # [ 3.947167] systemd[1]: Closed udev Control Socket.1106server # [ 3.947877] systemd[1]: Starting Cleanup udev Database...1107server # [ 3.948852] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1108server # [ 3.949912] systemd[1]: Stopped Create Static Device Nodes in /dev.1109server # [ 3.950813] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1110server # [ 3.951940] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1111server # [ 3.960178] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1112server # [ 3.961197] systemd[1]: Stopped Create List of Static Device Nodes.1113server # [ 3.980395] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1114server # [ 3.982379] systemd[1]: Finished Cleanup udev Database.1115server # [ 3.984489] systemd[1]: Reached target Switch Root.1116server # [ 3.988482] systemd[1]: Starting NixOS Activation...1117server # [ 4.142690] initrd-nixos-activation-start[173]: booting system configuration /nix/store/5hndw2rvi253kg6266rb7rcbm7dk1rb4-nixos-system-server-test1118server # [ 4.202597] initrd-nixos-activation-start[173]: running activation script...1119builder # [ 4.395109] (udev-worker)[97]: mtd0ro: Failed to find and pin callout binary "/nix/store/k2s978i7i26km4cr56fvm64p2q6mpl02-systemd-260.2/lib/udev/mtd_probe": No such file or directory1120builder # [ 4.398643] (udev-worker)[97]: mtd0ro: /nix/store/k2s978i7i26km4cr56fvm64p2q6mpl02-systemd-260.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1121builder # [ 4.423259] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1122builder # [ 4.426667] systemd[1]: Stopped Virtual Console Setup.1123builder # [ 4.427866] systemd[1]: Stopping Virtual Console Setup...1124builder # [ 4.431887] systemd[1]: Starting Virtual Console Setup...1125builder # [ 4.440391] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1126builder # [ 4.441872] systemd[1]: Stopped Virtual Console Setup.1127builder # [ 4.448386] systemd[1]: Starting Virtual Console Setup...1128builder # [ 4.475883] systemd-vconsole-setup[155]: Configuration of first virtual console was skipped, ignoring remaining ones.1129builder # [ 4.479458] systemd[1]: Finished Virtual Console Setup.1130builder # [ 4.561454] systemd[1]: Mounting /sysroot/nix/store...1131server # [ 4.616533] initrd-nixos-activation-start[196]: setting up /etc...1132builder # [ 4.607229] systemd[1]: Mounted /sysroot/nix/store.1133builder # [ 4.609917] systemd[1]: Reached target Initrd File Systems.1134builder # [ 4.614353] systemd[1]: Starting Find NixOS closure...1135builder # [ 4.622025] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1136builder # [ 4.660464] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1137builder # [ 4.663894] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1138builder # [ 4.677754] systemd[1]: Finished Find NixOS closure.1139builder # [ 4.678796] systemd[1]: Reached target Initrd Default Target.1140builder # [ 4.684246] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1141builder # [ 4.709801] systemd[1]: initrd-cleanup.service: Deactivated successfully.1142builder # [ 4.711549] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1143builder # [ 4.714826] systemd[1]: Stopped target Initrd Default Target.1144builder # [ 4.718771] systemd[1]: Stopped target Basic System.1145builder # [ 4.719886] systemd[1]: Stopped target Initrd Root Device.1146builder # [ 4.721372] systemd[1]: Stopped target Path Units.1147builder # [ 4.723555] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1148builder # [ 4.728252] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1149builder # [ 4.729565] systemd[1]: Stopped target Slice Units.1150builder # [ 4.730415] systemd[1]: Stopped target Socket Units.1151builder # [ 4.731246] systemd[1]: Stopped target System Initialization.1152builder # [ 4.736234] systemd[1]: Stopped target Swaps.1153builder # [ 4.737010] systemd[1]: Stopped target Timer Units.1154builder # [ 4.737808] systemd[1]: dbus.socket: Deactivated successfully.1155builder # [ 4.738736] systemd[1]: Closed D-Bus System Message Bus Socket.1156builder # [ 4.739644] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1157builder # [ 4.746198] systemd[1]: Stopped Find NixOS closure.1158builder # [ 4.747902] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1159builder # [ 4.750111] systemd[1]: Starting rw-sysroot-nix-store.service...1160builder # [ 4.756228] systemd[1]: systemd-sysctl.service: Deactivated successfully.1161builder # [ 4.760512] systemd[1]: Stopped Apply Kernel Variables.1162builder # [ 4.764347] systemd[1]: systemd-modules-load.service: Deactivated successfully.1163builder # [ 4.769262] systemd[1]: Stopped Load Kernel Modules.1164builder # [ 4.770587] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1165builder # [ 4.771756] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1166builder # [ 4.773015] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1167builder # [ 4.774053] systemd[1]: Stopped Create System Files and Directories.1168builder # [ 4.774943] systemd[1]: Stopped target Local File Systems.1169builder # [ 4.775731] systemd[1]: Stopped target Preparation for Local File Systems.1170builder # [ 4.781056] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1171builder # [ 4.782180] systemd[1]: Stopped Coldplug All udev Devices.1172builder # [ 4.782956] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1173builder # [ 4.783955] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1174builder # [ 4.790226] systemd[1]: Stopped Virtual Console Setup.1175builder # [ 4.791496] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1176builder # [ 4.792720] systemd[1]: Finished rw-sysroot-nix-store.service.1177builder # [ 4.793553] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1178builder # [ 4.794586] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1179builder # [ 4.795435] systemd[1]: systemd-udevd.service: Deactivated successfully.1180builder # [ 4.796506] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1181builder # [ 4.797520] systemd[1]: systemd-udevd.service: Consumed 1.565s CPU time over 3.062s wall clock time, 22.8M memory peak.1182builder # [ 4.798960] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1183builder # [ 4.799964] systemd[1]: Closed udev Control Socket.1184builder # [ 4.804221] systemd[1]: Starting Cleanup udev Database...1185builder # [ 4.808151] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1186builder # [ 4.809406] systemd[1]: Stopped Create Static Device Nodes in /dev.1187builder # [ 4.810446] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1188builder # [ 4.816116] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1189builder # [ 4.817166] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1190builder # [ 4.818155] systemd[1]: Stopped Create List of Static Device Nodes.1191server # [ 4.877786] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1192server # [ 4.880534] systemd[1]: Finished NixOS Activation.1193server # [ 4.884191] systemd[1]: Starting Switch Root...1194builder # [ 4.846168] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1195builder # [ 4.849048] systemd[1]: Finished Cleanup udev Database.1196builder # [ 4.852626] systemd[1]: Reached target Switch Root.1197builder # [ 4.853415] systemd[1]: Starting NixOS Activation...1198server # [ 4.902838] systemd[1]: Switching root.1199server # [ 4.970672] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1200builder # [ 5.013298] initrd-nixos-activation-start[182]: booting system configuration /nix/store/3gplg5x8kbppr871pjphnnm3bl8b3ppv-nixos-system-builder-test1201builder # [ 5.074308] initrd-nixos-activation-start[182]: running activation script...1202builder # [ 5.473188] initrd-nixos-activation-start[205]: setting up /etc...1203server # [ 5.524757] 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)1204server # [ 5.536931] systemd[1]: Detected virtualization qemu.1205server # [ 5.538995] systemd[1]: Detected architecture arm64.1206server # [ 5.541333] systemd[1]: Detected first boot.1207server # [ 5.548391] systemd[1]: Initializing machine ID from random generator.1208builder # [ 5.731215] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1209builder # [ 5.734037] systemd[1]: Finished NixOS Activation.1210builder # [ 5.736102] systemd[1]: Starting Switch Root...1211builder # [ 5.758610] systemd[1]: Switching root.1212builder # [ 5.819727] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1213server # [ 5.864369] systemd[1]: bpf-restrict-fs: LSM BPF program attached1214server # [ 6.062546] systemd[1]: Applying preset policy.1215builder # [ 6.375814] 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)1216builder # [ 6.388852] systemd[1]: Detected virtualization qemu.1217builder # [ 6.391990] systemd[1]: Detected architecture arm64.1218builder # [ 6.395851] systemd[1]: Detected first boot.1219builder # [ 6.402367] systemd[1]: Initializing machine ID from random generator.1220server # [ 6.584041] systemd[1]: Populated /etc with preset unit settings.1221builder # [ 6.720823] systemd[1]: bpf-restrict-fs: LSM BPF program attached1222builder # [ 6.915959] systemd[1]: Applying preset policy.1223server # [ 7.068300] systemd[1]: initrd-switch-root.service: Deactivated successfully.1224server # [ 7.069786] systemd[1]: Stopped initrd-switch-root.service.1225server # [ 7.071321] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1226server # [ 7.075664] systemd[1]: Created slice Slice /system/getty.1227server # [ 7.077871] systemd[1]: Created slice User and Session Slice.1228server # [ 7.079958] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1229server # [ 7.082519] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1230server # [ 7.084718] systemd[1]: Expecting device /dev/hvc0...1231server # [ 7.086514] systemd[1]: Expecting device /dev/ttyAMA0...1232server # [ 7.088349] systemd[1]: Reached target Local Encrypted Volumes.1233server # [ 7.090285] systemd[1]: Stopped target initrd-fs.target.1234server # [ 7.092107] systemd[1]: Stopped target initrd-root-fs.target.1235server # [ 7.094045] systemd[1]: Stopped target initrd-switch-root.target.1236server # [ 7.095964] systemd[1]: Reached target Virtual Machines and Containers.1237server # [ 7.098035] systemd[1]: Reached target Path Units.1238server # [ 7.099797] systemd[1]: Reached target Remote File Systems.1239server # [ 7.101733] systemd[1]: Reached target Slice Units.1240server # [ 7.103494] systemd[1]: Reached target Swaps.1241server # [ 7.107417] systemd[1]: Listening on Query the User Interactively for a Password.1242server # [ 7.112244] systemd[1]: Listening on Process Core Dump Socket.1243server # [ 7.116201] systemd[1]: Listening on Credential Encryption/Decryption.1244server # [ 7.120053] systemd[1]: Listening on Factory Reset Management.1245server # [ 7.125615] systemd[1]: Starting Journal Log Access Socket...1246server # [ 7.128383] systemd[1]: Listening on Journal Audit Socket.1247server # [ 7.132490] systemd[1]: Listening on Console Output Muting Service Socket.1248server # [ 7.135180] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1249server # [ 7.137473] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki1250server # [ 7.140156] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1251server # [ 7.150799] systemd[1]: Listening on Disk Repartitioning Service Socket.1252server # [ 7.151270] systemd[1]: Listening on udev Control Socket.1253server # [ 7.151627] systemd[1]: Listening on udev Varlink Socket.1254server # [ 7.157026] systemd[1]: Mounting Huge Pages File System...1255server # [ 7.162303] systemd[1]: Mounting POSIX Message Queue File System...1256server # [ 7.169952] systemd[1]: Mounting Kernel Debug File System...1257server # [ 7.180980] systemd[1]: Mounting Kernel Trace File System...1258server # [ 7.187770] systemd[1]: Starting Create List of Static Device Nodes...1259server # [ 7.204904] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1260server # [ 7.209089] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1261server # [ 7.219904] systemd[1]: Mounting Kernel Configuration File System...1262server # [ 7.223460] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1263server # [ 7.228671] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1264server # [ 7.238082] systemd[1]: Starting Load Kernel Module fuse...1265server # [ 7.240250] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671266server # [ 7.252657] systemd[1]: Starting Journal Service...1267server # [ 7.298655] systemd[1]: Starting Load Kernel Modules...1268server # [ 7.319226] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1269server # [ 7.334807] systemd[1]: Starting Remount Root and Kernel File Systems...1270server # [ 7.338965] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1271server # [ 7.353954] systemd[1]: Starting Coldplug All udev Devices...1272server # [ 7.361512] systemd[1]: Listening on Journal Log Access Socket.1273server # [ 7.364063] systemd[1]: Mounted Huge Pages File System.1274server # [ 7.368881] systemd[1]: Mounted POSIX Message Queue File System.1275server # [ 7.369340] systemd[1]: Mounted Kernel Debug File System.1276server # [ 7.369706] systemd[1]: Mounted Kernel Trace File System.1277server # [ 7.370066] systemd[1]: Mounted Kernel Configuration File System.1278server # [ 7.400486] systemd[1]: Finished Create List of Static Device Nodes.1279server # [ 7.416395] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1280builder # [ 7.418887] systemd[1]: Populated /etc with preset unit settings.1281server # [ 7.472341] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1282server # [ 7.475146] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1283server # [ 7.506247] EXT4-fs (vda): re-mounted f624c753-b1bf-4534-88ee-d924ff96a047.1284server # [ 7.523085] systemd[1]: Finished Remount Root and Kernel File Systems.1285server # [ 7.523709] systemd[1]: Listening on Disk Image Download Service Socket.1286server # [ 7.524011] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1287server # [ 7.537494] systemd[1]: Starting Load/Save OS Random Seed...1288server # [ 7.538902] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1289server # [ 7.548117] systemd-journald[267]: Collecting audit messages is enabled.1290server # [ 7.567615] fuse: init (API version 7.45)1291server # [ 7.571945] systemd[1]: Started Journal Service.1292server # [ 7.564344] systemd[1]: Queued start job for default target Multi-User System.1293server # [ 7.565963] systemd[1]: systemd-journald.service: Deactivated successfully.1294server # [ 7.577777] systemd-modules-load[268]: Module 'atkbd' is built in1295server # [ 7.594267] systemd-modules-load[268]: Module 'loop' is built in1296server # [ 7.608609] systemd-modules-load[268]: Inserted module 'tls'1297server # [ 7.613279] systemd-modules-load[268]: Module 'tun' is built in1298server # [ 7.626735] systemd[1]: Starting Flush Journal to Persistent Storage...1299server # [ 7.635430] systemd[1]: Finished Load Kernel Modules.1300server # [ 7.645661] systemd[1]: Starting Firewall...1301server # [ 7.656222] systemd[1]: Starting Apply Kernel Variables...1302server # [ 7.664659] systemd[1]: modprobe@fuse.service: Deactivated successfully.1303server # [ 7.674597] systemd[1]: Finished Load Kernel Module fuse.1304server # [ 7.684207] systemd-oomd[269]: No swap; memory pressure usage will be degraded1305server # [ 7.709616] systemd-journald[267]: Received client request to flush runtime journal.1306server # [ 7.782245] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1307server # [ 7.787349] systemd[1]: Finished Load/Save OS Random Seed.1308server # [ 7.797301] systemd[1]: Reached target First Boot Complete.1309server # [ 7.804490] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1310server # [ 7.818304] systemd[1]: Starting Create Static Device Nodes in /dev...1311server # [ 7.819647] systemd[1]: Finished Apply Kernel Variables.1312server # [ 7.823345] systemd[1]: Finished Flush Journal to Persistent Storage.1313server # [ 7.888520] systemd[1]: Finished Create Static Device Nodes in /dev.1314server # [ 7.889715] systemd[1]: Reached target Preparation for Local File Systems.1315server # [ 7.893536] systemd[1]: Starting Rule-based Manager for Device Events and Files...1316builder # [ 7.919356] systemd[1]: initrd-switch-root.service: Deactivated successfully.1317builder # [ 7.920818] systemd[1]: Stopped initrd-switch-root.service.1318builder # [ 7.924484] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1319builder # [ 7.927667] systemd[1]: Created slice Slice /system/getty.1320builder # [ 7.929890] systemd[1]: Created slice User and Session Slice.1321builder # [ 7.932317] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1322builder # [ 7.934730] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1323builder # [ 7.936143] systemd[1]: Expecting device /dev/hvc0...1324builder # [ 7.938398] systemd[1]: Expecting device /dev/ttyAMA0...1325builder # [ 7.940327] systemd[1]: Reached target Local Encrypted Volumes.1326builder # [ 7.942307] systemd[1]: Stopped target initrd-fs.target.1327builder # [ 7.944168] systemd[1]: Stopped target initrd-root-fs.target.1328builder # [ 7.946068] systemd[1]: Stopped target initrd-switch-root.target.1329builder # [ 7.948148] systemd[1]: Reached target Virtual Machines and Containers.1330builder # [ 7.950295] systemd[1]: Reached target Path Units.1331builder # [ 7.952121] systemd[1]: Reached target Remote File Systems.1332builder # [ 7.954000] systemd[1]: Reached target Slice Units.1333builder # [ 7.955839] systemd[1]: Reached target Swaps.1334builder # [ 7.960210] systemd[1]: Listening on Query the User Interactively for a Password.1335builder # [ 7.965049] systemd[1]: Listening on Process Core Dump Socket.1336builder # [ 7.969013] systemd[1]: Listening on Credential Encryption/Decryption.1337builder # [ 7.972924] systemd[1]: Listening on Factory Reset Management.1338builder # [ 7.978899] systemd[1]: Starting Journal Log Access Socket...1339builder # [ 7.980620] systemd[1]: Listening on Journal Audit Socket.1340builder # [ 7.984583] systemd[1]: Listening on Console Output Muting Service Socket.1341builder # [ 7.986255] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1342builder # [ 7.988071] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki1343builder # [ 7.990605] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1344builder # [ 8.002032] systemd[1]: Listening on Disk Repartitioning Service Socket.1345builder # [ 8.004241] systemd[1]: Listening on udev Control Socket.1346builder # [ 8.006120] systemd[1]: Listening on udev Varlink Socket.1347builder # [ 8.010814] systemd[1]: Mounting Huge Pages File System...1348builder # [ 8.016098] systemd[1]: Mounting POSIX Message Queue File System...1349builder # [ 8.020575] systemd[1]: Mounting Kernel Debug File System...1350builder # [ 8.034080] systemd[1]: Mounting Kernel Trace File System...1351server # [ 8.059747] systemd[1]: Mounting /run/wrappers...1352builder # [ 8.045884] systemd[1]: Starting Create List of Static Device Nodes...1353server # [ 8.076198] systemd[1]: Mounting FUSE Control File System...1354builder # [ 8.055934] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1355builder # [ 8.059496] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1356builder # [ 8.069557] systemd[1]: Mounting Kernel Configuration File System...1357builder # [ 8.072769] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1358builder # [ 8.078315] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1359server # [ 8.109581] systemd[1]: Mounted FUSE Control File System.1360builder # [ 8.090131] systemd[1]: Starting Load Kernel Module fuse...1361builder # [ 8.090602] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671362builder # [ 8.101583] systemd[1]: Starting Journal Service...1363server # [ 8.132975] systemd[1]: Mounted /run/wrappers.1364server # [ 8.135002] systemd[1]: Reached target Local File Systems.1365server # [ 8.141486] systemd[1]: Listening on Boot Loader Control Service Socket.1366server # [ 8.154017] systemd[1]: Starting register-nix-paths.service...1367server # [ 8.158443] systemd[1]: Starting Create SUID/SGID Wrappers...1368server # [ 8.160667] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1369server # [ 8.180389] systemd[1]: Starting Save Transient machine-id to Disk...1370server # [ 8.187903] systemd[1]: Starting Create System Files and Directories...1371builder # [ 8.192202] systemd[1]: Starting Load Kernel Modules...1372builder # [ 8.224406] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1373builder # [ 8.234819] systemd[1]: Starting Remount Root and Kernel File Systems...1374builder # [ 8.237711] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1375builder # [ 8.250826] systemd[1]: Starting Coldplug All udev Devices...1376server # [ 8.278660] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1377builder # [ 8.256781] systemd[1]: Listening on Journal Log Access Socket.1378server # [ 8.286212] systemd[1]: Finished Save Transient machine-id to Disk.1379builder # [ 8.262511] systemd[1]: Mounted Huge Pages File System.1380builder # [ 8.264812] systemd[1]: Mounted POSIX Message Queue File System.1381builder # [ 8.267225] systemd[1]: Mounted Kernel Debug File System.1382builder # [ 8.270440] systemd[1]: Mounted Kernel Trace File System.1383builder # [ 8.273210] systemd[1]: Mounted Kernel Configuration File System.1384builder # [ 8.321529] systemd[1]: Finished Create List of Static Device Nodes.1385builder # [ 8.329488] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1386builder # [ 8.392742] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1387builder # [ 8.395664] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1388server # [ 8.439494] systemd[1]: Finished Create System Files and Directories.1389builder # [ 8.414371] EXT4-fs (vda): re-mounted 5b3158a0-7a49-496e-bfe8-122379b5f5f1.1390builder # [ 8.422721] systemd[1]: Finished Remount Root and Kernel File Systems.1391builder # [ 8.425183] systemd[1]: Listening on Disk Image Download Service Socket.1392builder # [ 8.425537] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1393server # [ 8.443411] systemd[1]: Starting Rebuild Journal Catalog...1394builder # [ 8.437483] systemd[1]: Starting Load/Save OS Random Seed...1395server # [ 8.465495] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1396builder # [ 8.439585] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1397builder # [ 8.446641] systemd[1]: Finished Load Kernel Modules.1398builder # [ 8.458677] systemd[1]: Starting Firewall...1399server # [ 8.489576] systemd-udevd[307]: Using default interface naming scheme 'v260'.1400builder # [ 8.471905] fuse: init (API version 7.45)1401builder # [ 8.479362] systemd[1]: Starting Apply Kernel Variables...1402builder # [ 8.487403] systemd-journald[275]: Collecting audit messages is enabled.1403builder # [ 8.513456] systemd[1]: Started Journal Service.1404builder # [ 8.502456] systemd[1]: Queued start job for default target Multi-User System.1405builder # [ 8.508333] systemd[1]: systemd-journald.service: Deactivated successfully.1406builder # [ 8.529620] systemd-modules-load[276]: Module 'atkbd' is built in1407builder # [ 8.541900] systemd-modules-load[276]: Module 'loop' is built in1408server # [ 8.597980] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1409builder # [ 8.560114] systemd-modules-load[276]: Module 'tun' is built in1410builder # [ 8.570104] systemd[1]: Starting Flush Journal to Persistent Storage...1411builder # [ 8.582630] systemd[1]: modprobe@fuse.service: Deactivated successfully.1412builder # [ 8.587352] systemd[1]: Finished Load Kernel Module fuse.1413builder # [ 8.597010] systemd[1]: Mounting FUSE Control File System...1414builder # [ 8.602296] systemd[1]: Mounted FUSE Control File System.1415builder # [ 8.612616] systemd-oomd[277]: No swap; memory pressure usage will be degraded1416server # [ 8.657880] systemd[1]: Finished Rebuild Journal Catalog.1417builder # [ 8.617823] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1418server # [ 8.665769] systemd[1]: Starting Update is Completed...1419builder # [ 8.628406] systemd[1]: Finished Load/Save OS Random Seed.1420builder # [ 8.633166] systemd[1]: Reached target First Boot Complete.1421builder # [ 8.677095] systemd-journald[275]: Received client request to flush runtime journal.1422server # [ 8.782601] systemd[1]: Finished Update is Completed.1423builder # [ 8.744882] systemd[1]: Finished Apply Kernel Variables.1424builder # [ 8.748686] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1425builder # [ 8.752922] systemd[1]: Starting Create Static Device Nodes in /dev...1426builder # [ 8.756465] systemd[1]: Finished Flush Journal to Persistent Storage.1427server # [ 8.802804] systemd[1]: Started Rule-based Manager for Device Events and Files.1428builder # [ 8.831587] systemd[1]: Finished Create Static Device Nodes in /dev.1429builder # [ 8.833061] systemd[1]: Reached target Preparation for Local File Systems.1430builder # [ 8.836826] systemd[1]: Starting Rule-based Manager for Device Events and Files...1431builder # [ 8.910499] systemd[1]: Mounting /run/wrappers...1432builder # [ 8.973476] systemd[1]: Mounted /run/wrappers.1433builder # [ 8.980202] systemd[1]: Reached target Local File Systems.1434builder # [ 8.986404] systemd-udevd[319]: Using default interface naming scheme 'v260'.1435builder # [ 8.997921] systemd[1]: Listening on Boot Loader Control Service Socket.1436builder # [ 9.002730] systemd[1]: Starting register-nix-paths.service...1437builder # [ 9.015373] systemd[1]: Starting Create SUID/SGID Wrappers...1438builder # [ 9.018621] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1439builder # [ 9.026941] systemd[1]: Starting Save Transient machine-id to Disk...1440builder # [ 9.036356] systemd[1]: Starting Create System Files and Directories...1441builder # [ 9.113009] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1442builder # [ 9.118696] systemd[1]: Finished Save Transient machine-id to Disk.1443builder # [ 9.260119] systemd[1]: Finished Create System Files and Directories.1444builder # [ 9.266599] systemd[1]: Starting Rebuild Journal Catalog...1445builder # [ 9.273636] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1446server # [ 9.322418] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1447server # [ 9.325416] systemd[1]: Finished Create SUID/SGID Wrappers.1448builder # [ 9.321043] systemd[1]: Started Rule-based Manager for Device Events and Files.1449builder # [ 9.408733] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1450server # [ 9.522502] systemd[1]: Finished register-nix-paths.service.1451builder # [ 9.488497] systemd[1]: Finished Rebuild Journal Catalog.1452builder # [ 9.491645] systemd[1]: Starting Update is Completed...1453builder # [ 9.562222] systemd[1]: Finished Update is Completed.1454server # [ 9.830793] systemd[1]: Finished Firewall.1455builder # [ 10.005212] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1456builder # [ 10.010174] systemd[1]: Finished Create SUID/SGID Wrappers.1457server # [ 10.080238] systemd[1]: Finished Coldplug All udev Devices.1458server # [ 10.081735] systemd[1]: Reached target System Initialization.1459server # [ 10.082656] systemd[1]: Started Discard unused filesystem blocks once a week.1460server # [ 10.088319] systemd[1]: Started niks3 garbage collection timer.1461server # [ 10.089266] systemd[1]: Started Daily Cleanup of Temporary Directories.1462server # [ 10.090949] systemd[1]: Reached target Timer Units.1463server # [ 10.092195] systemd[1]: Listening on D-Bus System Message Bus Socket.1464server # [ 10.094058] systemd[1]: Listening on niks3 server socket.1465server # [ 10.104921] systemd[1]: Listening on Nix Daemon Socket.1466server # [ 10.105824] systemd[1]: Listening on Hostname Service Socket.1467server # [ 10.106799] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1468server # [ 10.108127] systemd[1]: Reached target Socket Units.1469server # [ 10.113559] systemd[1]: Reached target Basic System.1470server # [ 10.120686] systemd[1]: Starting Import lastlog data into lastlog2 database...1471server # [ 10.122239] systemd[1]: Starting Generate test mTLS certs...1472server # [ 10.129488] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1473server # [ 10.141967] systemd[1]: Starting Post-Boot Actions...1474server # [ 10.161963] systemd[1]: Started Reset console on configuration changes.1475server # [ 10.166703] systemd[1]: Starting resolvconf update...1476server # [ 10.175484] systemd[1]: Starting D-Bus System Message Bus...1477builder # [ 10.150348] systemd[1]: Finished register-nix-paths.service.1478server # [ 10.256794] systemd[1]: Finished Post-Boot Actions.1479server # [ 10.317058] systemd[1]: Started Name Service Cache Daemon (nsncd).1480server # [ 10.323290] nsncd[496]: Jul 07 08:16:50.933 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1481server # [ 10.334281] systemd[1]: Reached target Host and Network Name Lookups.1482server # [ 10.339313] systemd[1]: Reached target User and Group Name Lookups.1483server # [ 10.347891] systemd[1]: Starting User Login Management...1484server # [ 10.355843] systemd[1]: Finished Import lastlog data into lastlog2 database.1485builder # [ 10.340809] systemd[1]: Finished Firewall.1486server # [ 10.407050] niks3-test-certs-start[508]: -----1487server # [ 10.417715] dbus-broker-launch[500]: Looking up NSS user entry for 'systemd-timesync'...1488server # [ 10.445049] dbus-broker-launch[500]: NSS returned no entry for 'systemd-timesync'1489server # [ 10.450226] dbus-broker-launch[500]: Invalid user-name in /nix/store/66xdhnmp54w1sqcvav9qgb2v3likb85j-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1490server # [ 10.464870] niks3-test-certs-start[539]: -----1491server # [ 10.501092] systemd[1]: Started D-Bus System Message Bus.1492server # [ 10.556613] dbus-broker-launch[500]: Ready1493server # [ 10.564862] systemd-logind[510]: New seat seat0.1494server # [ 10.578232] systemd[1]: Stopped target Host and Network Name Lookups.1495server # [ 10.587212] systemd[1]: Stopping Host and Network Name Lookups...1496server # [ 10.596809] systemd[1]: Stopped target User and Group Name Lookups.1497server # [ 10.606151] systemd[1]: Stopping User and Group Name Lookups...1498server # [ 10.612845] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1499server # [ 10.617306] systemd[1]: Started User Login Management.1500server # [ 10.620184] systemd[1]: nscd.service: Deactivated successfully.1501server # [ 10.626307] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1502server # [ 10.630797] systemd[1]: Starting linger-users.service...1503server # [ 10.635373] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1504server # [ 10.649465] niks3-test-certs-start[548]: Certificate request self-signature ok1505server # [ 10.655797] niks3-test-certs-start[548]: subject=CN=server1506builder # [ 10.620540] systemd[1]: Finished Coldplug All udev Devices.1507builder # [ 10.621558] systemd[1]: Reached target System Initialization.1508builder # [ 10.623279] systemd[1]: Started Discard unused filesystem blocks once a week.1509builder # [ 10.626897] systemd[1]: Started Daily Cleanup of Temporary Directories.1510builder # [ 10.631209] systemd[1]: Reached target Timer Units.1511builder # [ 10.640976] systemd[1]: Listening on D-Bus System Message Bus Socket.1512builder # [ 10.641983] systemd[1]: Starting niks3 auto-upload socket...1513builder # [ 10.643130] systemd[1]: Listening on Nix Daemon Socket.1514builder # [ 10.643953] systemd[1]: Listening on Hostname Service Socket.1515builder # [ 10.646987] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1516builder # [ 10.655022] systemd[1]: Starting D-Bus System Message Bus...1517builder # [ 10.657389] systemd[1]: Listening on niks3 auto-upload socket.1518builder # [ 10.658335] systemd[1]: Reached target Socket Units.1519server # [ 10.704825] systemd[1]: linger-users.service: Deactivated successfully.1520server # [ 10.709388] systemd[1]: Finished linger-users.service.1521server # [ 10.715699] niks3-test-certs-start[574]: -----1522builder # [ 10.680864] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1523server # [ 10.727324] systemd[1]: Started Name Service Cache Daemon (nsncd).1524server # [ 10.732839] nsncd[563]: Jul 07 08:16:51.344 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1525server # [ 10.741164] systemd[1]: Reached target Host and Network Name Lookups.1526server # [ 10.743713] systemd[1]: Reached target User and Group Name Lookups.1527server # [ 10.771984] systemd[1]: Finished resolvconf update.1528server # [ 10.777073] systemd[1]: Reached target Preparation for Network.1529server # [ 10.781734] systemd[1]: Starting DHCP Client...1530server # [ 10.785283] systemd[1]: Starting Extra networking commands....1531builder # [ 10.757106] dbus-broker-launch[492]: Looking up NSS user entry for 'systemd-timesync'...1532builder # [ 10.770125] dbus-broker-launch[492]: NSS returned no entry for 'systemd-timesync'1533builder # [ 10.772829] dbus-broker-launch[492]: Invalid user-name in /nix/store/4mzz1dahk3p5fp5rs73xzcsbfhp1jax0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1534server # [ 10.817795] niks3-test-certs-start[578]: Certificate request self-signature ok1535server # [ 10.830551] niks3-test-certs-start[578]: subject=CN=niks3 test client1536builder # [ 10.800397] systemd[1]: Started D-Bus System Message Bus.1537builder # [ 10.828781] dbus-broker-launch[492]: Ready1538server # [ 10.870457] systemd[1]: Finished Generate test mTLS certs.1539builder # [ 10.835204] systemd[1]: Reached target Basic System.1540builder # [ 10.838112] systemd[1]: Starting Import lastlog data into lastlog2 database...1541builder # [ 10.843397] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1542builder # [ 10.856530] systemd[1]: Starting Post-Boot Actions...1543builder # [ 10.859034] systemd[1]: Started Reset console on configuration changes.1544builder # [ 10.869441] systemd[1]: Starting resolvconf update...1545builder # [ 10.960533] systemd[1]: Finished Post-Boot Actions.1546server # [ 11.032930] dhcpcd[600]: dhcpcd-10.3.2 starting1547builder # [ 11.000415] systemd[1]: Started Name Service Cache Daemon (nsncd).1548builder # [ 11.006692] systemd[1]: Reached target Host and Network Name Lookups.1549server # [ 11.049880] dhcpcd[634]: dev: loaded udev1550builder # [ 11.013108] nsncd[495]: Jul 07 08:16:51.602 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1551server # [ 11.057793] dhcpcd[634]: no valid interfaces found1552server # [ 11.059558] dhcpcd[634]: libudev: received NULL device1553server # [ 11.062613] dhcpcd[634]: no valid interfaces found1554server # [ 11.064872] dhcpcd[634]: libudev: received NULL device1555builder # [ 11.023396] systemd[1]: Reached target User and Group Name Lookups.1556builder # [ 11.028557] systemd[1]: Starting User Login Management...1557builder # [ 11.035250] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1558server # [ 11.085944] systemd[1]: Finished Extra networking commands..1559builder # [ 11.045538] systemd[1]: Finished Import lastlog data into lastlog2 database.1560server # [ 11.087818] systemd[1]: Reached target Network.1561server # [ 11.093159] systemd[1]: Started Mock OIDC server for testing.1562server # [ 11.099239] systemd[1]: Starting Nginx Web Server...1563server # [ 11.105036] systemd[1]: Starting PostgreSQL Server...1564server # [ 11.116594] systemd[1]: Started RustFS S3-compatible object storage.1565server # [ 11.122019] systemd[1]: Starting Setup RustFS bucket...1566server # [ 11.134021] systemd[1]: Starting Permit User Sessions...1567builder # [ 11.111081] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1568builder # [ 11.119006] systemd[1]: Started backdoor.service.1569builder # [ 11.188939] systemd-logind[516]: New seat seat0.1570builder # [ 11.196300] systemd[1]: Started User Login Management.1571builder # [ 11.204727] systemd[1]: Starting linger-users.service...1572builder # [ 11.208989] systemd[1]: Stopped target Host and Network Name Lookups.1573server # [ 11.254801] systemd[1]: Finished Permit User Sessions.1574builder # [ 11.223833] systemd[1]: Stopping Host and Network Name Lookups...1575server # [ 11.266022] systemd[1]: Started Getty on tty1.1576server # [ 11.269141] systemd[1]: Reached target Login Prompts.1577builder # [ 11.232382] systemd[1]: Stopped target User and Group Name Lookups.1578builder # [ 11.234672] systemd[1]: Stopping User and Group Name Lookups...1579builder # [ 11.240929] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1580builder # [ 11.245250] systemd[1]: nscd.service: Deactivated successfully.1581builder # [ 11.252999] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1582builder # [ 11.256359] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1583builder # connecting to host...1584builder # [ 11.311672] systemd[1]: linger-users.service: Deactivated successfully.1585builder # [ 11.315890] systemd[1]: Finished linger-users.service.1586builder # [ 11.342837] systemd[1]: Started Name Service Cache Daemon (nsncd).1587builder # [ 11.343763] systemd[1]: Reached target Host and Network Name Lookups.1588server # [ 11.385716] mock-oidc-server[643]: Mock OIDC Server running1589builder # [ 11.347734] systemd[1]: Reached target User and Group Name Lookups.1590server # [ 11.393295] mock-oidc-server[643]: OIDC Address: 127.0.0.1:80801591builder # [ 11.351674] nsncd[559]: Jul 07 08:16:51.943 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1592server # [ 11.397664] mock-oidc-server[643]: Issue Address: 127.0.0.1:80811593server # [ 11.404735] mock-oidc-server[643]: Issuer: http://127.0.0.1:8080/oidc1594server # [ 11.407468] mock-oidc-server[643]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json1595server # [ 11.416119] mock-oidc-server[643]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration1596builder # [ 11.376944] systemd[1]: Finished resolvconf update.1597builder # [ 11.381113] systemd[1]: Reached target Preparation for Network.1598server # [ 11.423078] mock-oidc-server[643]: Issue tokens: http://127.0.0.1:8081/issue?sub=...1599builder # [ 11.386465] systemd[1]: Starting DHCP Client...1600builder # [ 11.389531] systemd[1]: Starting Extra networking commands....1601builder # [ 11.533858] (udev-worker)[370]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1602builder # [ 11.546649] (udev-worker)[370]: Network interface NamePolicy= disabled on kernel command line.1603builder # [ 11.575137] (udev-worker)[380]: Network interface NamePolicy= disabled on kernel command line.1604server # [ 11.674989] nginx-pre-start[667]: nginx: the configuration file /nix/store/q2qwgbfznnzrr87lhic3cd6hyk8rk12a-nginx.conf syntax is ok1605builder # [ 11.637744] dhcpcd[595]: dhcpcd-10.3.2 starting1606server # [ 11.678951] nginx-pre-start[667]: nginx: configuration file /nix/store/q2qwgbfznnzrr87lhic3cd6hyk8rk12a-nginx.conf test is successful1607server # [ 11.686667] postgresql-pre-start[669]: The files belonging to this database system will be owned by user "postgres".1608server # [ 11.693155] postgresql-pre-start[669]: This user must also own the server process.1609builder # [ 11.654443] dhcpcd[632]: dev: loaded udev1610server # [ 11.699142] systemd[1]: Started Nginx Web Server.1611server # [ 11.712196] postgresql-pre-start[669]: The database cluster will be initialized with locale "en_US.UTF-8".1612server # [ 11.713707] postgresql-pre-start[669]: The default database encoding has accordingly been set to "UTF8".1613server # [ 11.722265] postgresql-pre-start[669]: The default text search configuration will be set to "english".1614server # [ 11.727380] postgresql-pre-start[669]: Data page checksums are disabled.1615server # [ 11.730357] postgresql-pre-start[669]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok1616builder # [ 11.692506] systemd[1]: Finished Extra networking commands..1617builder # [ 11.693403] systemd[1]: Reached target Network.1618builder # [ 11.711944] 8021q: 802.1Q VLAN Support v1.81619server # [ 11.741314] postgresql-pre-start[669]: creating subdirectories ... ok1620builder # [ 11.701952] systemd[1]: Starting Permit User Sessions...1621server # [ 11.745833] postgresql-pre-start[669]: selecting dynamic shared memory implementation ... posix1622builder # [ 11.800714] cfg80211: Loading compiled-in X.509 certificates for regulatory database1623builder # [ 11.797855] systemd[1]: Finished Permit User Sessions.1624builder # [ 11.800911] systemd[1]: Started Getty on tty1.1625builder # [ 11.804880] systemd[1]: Reached target Login Prompts.1626builder # [ 11.836863] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1627builder # [ 11.837341] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1628builder # [ 11.842140] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21629builder # [ 11.842492] cfg80211: failed to load regulatory.db1630server # [ 11.943997] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31631server # [ 11.933144] postgresql-pre-start[669]: selecting default "max_connections" ... 1001632builder # [ 11.893540] systemd[1]: Condition check resulted in Virtio network device being skipped.1633builder # [ 11.899546] systemd[1]: Starting Address configuration of eth1...1634builder # [ 11.908160] systemd-logind[516]: Watching system buttons on /dev/input/event0 (gpio-keys)1635builder # [ 11.934274] 8021q: adding VLAN 0 to HW filter on device eth01636builder # [ 11.923188] dhcpcd[632]: eth0: waiting for carrier1637builder # [ 11.927251] dhcpcd[632]: eth0: carrier acquired1638builder # [ 11.945180] dhcpcd[632]: DUID 00:01:00:01:31:df:72:74:52:54:00:12:34:561639builder # [ 11.948364] dhcpcd[632]: eth0: IAID 00:12:34:561640builder # [ 11.951515] dhcpcd[632]: eth0: adding address fe80::5054:ff:fe12:34561641builder # [ 11.953462] dhcpcd[632]: eth0: IAID 00:12:34:561642builder # [ 12.019815] 8021q: adding VLAN 0 to HW filter on device eth11643builder # [ 12.023989] network-addresses-eth1-start[656]: adding address 192.168.1.1/24... done1644builder # [ 12.036620] network-addresses-eth1-start[656]: adding address 2001:db8:1::1/64... done1645builder # [ 12.060155] systemd[1]: Finished Address configuration of eth1.1646server # [ 12.099783] postgresql-pre-start[669]: selecting default "shared_buffers" ... 128MB1647builder # [ 12.140803] mousedev: PS/2 mouse device common for all mice1648builder # [ 12.158040] systemd-logind[516]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1649server # [ 12.349559] rustfs[646]: {"timestamp":"2026-07-07T08:16:52.96438522Z","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)"}1650server # [ 12.622533] systemd[1]: Starting Virtual Console Setup...1651server # [ 12.648690] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1652server # [ 12.653976] systemd[1]: Stopped Virtual Console Setup.1653server # [ 12.656957] systemd[1]: Starting Virtual Console Setup...1654server # [ 12.660157] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1655server # [ 12.880563] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1656server # [ 12.932085] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1657server # [ 12.937854] systemd[1]: Started backdoor.service.1658builder # [ 12.920606] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31659server # [ 13.027949] 8021q: 802.1Q VLAN Support v1.81660server # connecting to host...1661server # [ 13.119118] cfg80211: Loading compiled-in X.509 certificates for regulatory database1662server # [ 13.150168] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1663server # [ 13.150645] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1664server # [ 13.155346] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21665server # [ 13.155671] cfg80211: failed to load regulatory.db1666server: Guest shell says: b'Spawning backdoor root shell...\n'1667server: connected to guest root shell1668server # [ 13.159587] (udev-worker)[678]: Network interface NamePolicy= disabled on kernel command line.1669server: (connecting took 13.46 seconds)1670server: (finished: waiting for the VM to finish booting, in 13.46 seconds)1671server # [ 13.197665] (udev-worker)[675]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1672server # [ 13.205105] (udev-worker)[675]: Network interface NamePolicy= disabled on kernel command line.1673builder # [ 13.211160] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1674builder # [ 13.245806] systemd[1]: Starting Virtual Console Setup...1675server # [ 13.294678] systemd-vconsole-setup[712]: Configuration of first virtual console was skipped, ignoring remaining ones.1676builder # [ 13.270490] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1677builder # [ 13.271585] systemd[1]: Stopped Virtual Console Setup.1678builder # [ 13.275694] systemd[1]: Starting Virtual Console Setup...1679server # [ 13.337027] systemd[1]: Finished Virtual Console Setup.1680builder # [ 13.314758] systemd-logind[516]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1681builder # [ 13.381987] dhcpcd[632]: eth0: soliciting a DHCP lease1682builder # [ 13.388480] dhcpcd[632]: eth0: offered 10.0.2.15 from 10.0.2.21683builder # [ 13.396190] dhcpcd[632]: eth0: probing address 10.0.2.15/241684server # [ 13.442107] systemd[1]: Condition check resulted in Virtio network device being skipped.1685server # [ 13.448430] systemd[1]: Starting Address configuration of eth1...1686server # [ 13.509360] 8021q: adding VLAN 0 to HW filter on device eth01687server # [ 13.495601] dhcpcd[634]: eth0: waiting for carrier1688server # [ 13.499577] dhcpcd[634]: eth0: carrier acquired1689server # [ 13.518550] dhcpcd[634]: DUID 00:01:00:01:31:df:72:76:52:54:00:12:34:561690server # [ 13.522021] dhcpcd[634]: eth0: IAID 00:12:34:561691server # [ 13.523952] dhcpcd[634]: eth0: adding address fe80::5054:ff:fe12:34561692server # [ 13.576757] 8021q: adding VLAN 0 to HW filter on device eth11693server # [ 13.580289] network-addresses-eth1-start[738]: adding address 192.168.1.2/24... done1694server # [ 13.595893] network-addresses-eth1-start[738]: adding address 2001:db8:1::2/64... done1695builder # [ 13.559599] dhcpcd[632]: eth0: soliciting an IPv6 router1696builder # [ 13.560886] dhcpcd[632]: eth0: Router Advertisement from fe80::21697builder # [ 13.561747] dhcpcd[632]: eth0: adding address fec0::5054:ff:fe12:3456/641698builder # [ 13.562878] dhcpcd[632]: eth0: adding route to fec0::/641699builder # [ 13.563765] dhcpcd[632]: eth0: adding default route via fe80::21700server # [ 13.617630] systemd[1]: Finished Address configuration of eth1.1701server # [ 13.622731] systemd-logind[510]: Watching system buttons on /dev/input/event0 (gpio-keys)1702server # [ 13.731759] mousedev: PS/2 mouse device common for all mice1703builder # [ 13.687617] systemd-vconsole-setup[685]: Configuration of first virtual console was skipped, ignoring remaining ones.1704builder # [ 13.692341] systemd[1]: Finished Virtual Console Setup.1705server # [ 13.829793] dhcpcd[634]: eth0: soliciting a DHCP lease1706server # [ 13.836534] dhcpcd[634]: eth0: offered 10.0.2.15 from 10.0.2.21707server # [ 13.844260] dhcpcd[634]: eth0: probing address 10.0.2.15/241708server # [ 13.872314] systemd-logind[510]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1709server # [ 15.075106] postgresql-pre-start[669]: selecting default time zone ... UTC1710server # [ 15.080562] postgresql-pre-start[669]: creating configuration files ... ok1711server # [ 15.540456] dhcpcd[634]: eth0: soliciting an IPv6 router1712server # [ 15.548331] dhcpcd[634]: eth0: Router Advertisement from fe80::21713server # [ 15.549229] dhcpcd[634]: eth0: adding address fec0::5054:ff:fe12:3456/641714server # [ 15.550156] dhcpcd[634]: eth0: adding route to fec0::/641715server # [ 15.550919] dhcpcd[634]: eth0: adding default route via fe80::21716server # [ 15.608952] postgresql-pre-start[669]: running bootstrap script ... ok1717server # [ 15.941133] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1718server # [ 15.976505] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1719server # [ 15.980271] systemd[1]: Stopped Virtual Console Setup.1720server # [ 15.982589] systemd[1]: Stopping Virtual Console Setup...1721server # [ 15.986688] systemd[1]: Starting Virtual Console Setup...1722server # [ 16.016891] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1723server # [ 16.020401] systemd[1]: Stopped Virtual Console Setup.1724server # [ 16.022854] systemd[1]: Starting Virtual Console Setup...1725server # [ 16.071539] systemd-logind[510]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1726server # [ 16.417225] systemd-vconsole-setup[781]: Configuration of first virtual console was skipped, ignoring remaining ones.1727server # [ 16.439540] systemd[1]: Finished Virtual Console Setup.1728server # [ 16.571519] postgresql-pre-start[669]: performing post-bootstrap initialization ... ok1729server # [ 16.743125] postgresql-pre-start[669]: syncing data to disk ... ok1730server # [ 16.745521] postgresql-pre-start[669]: initdb: warning: enabling "trust" authentication for local connections1731server # [ 16.746918] postgresql-pre-start[669]: 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.1732server # [ 16.749180] postgresql-pre-start[669]: Success. You can now start the database server using:1733server # [ 16.750435] postgresql-pre-start[669]: pg_ctl -D /var/lib/postgresql/17 -l logfile start1734server # [ 16.874347] postgres[796]: [796] LOG: starting PostgreSQL 17.10 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit1735server # [ 16.878302] postgres[796]: [796] LOG: listening on IPv6 address "::1", port 54321736server # [ 16.879550] postgres[796]: [796] LOG: listening on IPv4 address "127.0.0.1", port 54321737server # [ 16.882557] postgres[796]: [796] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1738server # [ 16.895210] postgres[802]: [802] LOG: database system was shut down at 2026-07-07 08:16:57 GMT1739server # [ 16.904757] postgres[796]: [796] LOG: database system is ready to accept connections1740server # [ 16.911040] systemd[1]: Started PostgreSQL Server.1741server # [ 16.916419] systemd[1]: Starting PostgreSQL Setup Scripts...1742server # [ 17.127132] postgresql-setup-start[813]: CREATE DATABASE1743server # [ 17.181112] postgresql-setup-start[818]: CREATE ROLE1744server # [ 17.203753] postgresql-setup-start[820]: ALTER DATABASE1745server # [ 17.210778] systemd[1]: Finished PostgreSQL Setup Scripts.1746server # [ 17.212483] systemd[1]: Reached target PostgreSQL.1747server: (finished: waiting for unit postgresql.service, in 17.99 seconds)1748server: waiting for unit rustfs.service1749server: (finished: waiting for unit rustfs.service, in 0.08 seconds)1750server: waiting for unit rustfs-setup.service1751builder # [ 18.490559] dhcpcd[632]: eth0: leased 10.0.2.15 for 86400 seconds1752builder # [ 18.493257] dhcpcd[632]: eth0: adding route to 10.0.2.0/241753builder # [ 18.496140] dhcpcd[632]: eth0: adding default route via 10.0.2.21754builder # [ 18.627984] systemd[1]: Started DHCP Client.1755builder # [ 18.630335] systemd[1]: Reached target Multi-User System.1756builder # [ 18.631150] systemd[1]: Startup finished in 975ms (kernel) + 4.929s (initrd) + 12.725s (userspace) = 18.630s.1757server # [ 19.238387] dhcpcd[634]: eth0: leased 10.0.2.15 for 86400 seconds1758server # [ 19.241996] dhcpcd[634]: eth0: adding route to 10.0.2.0/241759server # [ 19.245227] dhcpcd[634]: eth0: adding default route via 10.0.2.21760server # [ 19.395896] systemd[1]: Started DHCP Client.1761server # [ 28.105021] rustfs-setup-start[921]: mb s3://niks3-test1762server # [ 28.113614] systemd[1]: Finished Setup RustFS bucket.1763server # [ 28.121770] systemd[1]: Starting niks3 server...1764server # [ 28.311769] postgres[933]: [933] ERROR: relation "goose_db_version" does not exist at character 361765server # [ 28.313525] postgres[933]: [933] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1766server # [ 28.343575] niks3-server[928]: 2026/07/07 08:17:08 OK 20241026095416_initial_model.sql (18.39ms)1767server # [ 28.355737] niks3-server[928]: 2026/07/07 08:17:08 OK 20251210153512_drop_unused_gin_index.sql (2.23ms)1768server # [ 28.358180] niks3-server[928]: 2026/07/07 08:17:08 OK 20251218171726_add_pins.sql (4.34ms)1769server # [ 28.359402] niks3-server[928]: 2026/07/07 08:17:08 OK 20260628120000_add_object_size_and_stats.sql (5.67ms)1770server # [ 28.361199] niks3-server[928]: 2026/07/07 08:17:08 goose: successfully migrated database to version: 202606281200001771server # [ 28.365319] niks3-server[928]: 2026/07/07 08:17:08 OK 1_commit_pending_closure.sql (7.49ms)1772server # [ 28.368042] niks3-server[928]: 2026/07/07 08:17:08 OK 2_object_stats_trigger.sql (2.71ms)1773server # [ 28.369438] niks3-server[928]: 2026/07/07 08:17:08 goose: up to current file version: 21774server # [ 28.385783] niks3-server[928]: 2026/07/07 08:17:09 INFO OIDC provider initialized name=test1775server # [ 28.387172] niks3-server[928]: 2026/07/07 08:17:09 INFO OIDC authentication enabled config=/nix/store/2za6kyy80mq2xh1w4ydfs7gg8psbv5g3-niks3-oidc.json1776server # [ 28.390231] niks3-server[928]: 2026/07/07 08:17:09 INFO Loaded signing key name=niks3-test-1 path=/nix/store/1cz670ydszbfkf0naknmhg579xvlcyr7-niks3-signing-key1777server # [ 28.420481] rustfs[646]: {"timestamp":"2026-07-07T08:17:09.03633074Z","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)"}1778server # [ 28.427088] niks3-server[928]: 2026/07/07 08:17:09 INFO Created nix-cache-info in bucket bucket=niks3-test1779server # [ 28.431970] niks3-server[928]: 2026/07/07 08:17:09 INFO Using socket-activated listener address=0.0.0.0:57511780server # [ 28.433433] niks3-server[928]: 2026/07/07 08:17:09 INFO systemd watchdog enabled interval=15s1781server # [ 28.434632] niks3-server[928]: 2026/07/07 08:17:09 INFO Starting HTTP server address=0.0.0.0:57511782server # [ 28.435796] systemd[1]: Started niks3 server.1783server # [ 28.436513] systemd[1]: Reached target Multi-User System.1784server # [ 28.437257] systemd[1]: Startup finished in 955ms (kernel) + 4.096s (initrd) + 23.379s (userspace) = 28.431s.1785server: (finished: waiting for unit rustfs-setup.service, in 10.85 seconds)1786server: waiting for unit mock-oidc.service1787server: (finished: waiting for unit mock-oidc.service, in 0.08 seconds)1788server: waiting for unit niks3.service1789server: (finished: waiting for unit niks3.service, in 0.07 seconds)1790server: waiting for TCP port 5751 on localhost1791server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded!1792server: (finished: waiting for TCP port 5751 on localhost, in 0.06 seconds)1793server: waiting for TCP port 8080 on localhost1794server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded!1795server: (finished: waiting for TCP port 8080 on localhost, in 0.04 seconds)1796server: waiting for TCP port 9000 on localhost1797server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded!1798server: (finished: waiting for TCP port 9000 on localhost, in 0.04 seconds)1799server: must succeed: mkdir -p /tmp/test-config1800server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.03 seconds)1801server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token1802server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.02 seconds)1803server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/xpvqkjf5qfnwa90538299wai62qi10kb-niks3-1.7.0/bin/niks3 push /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.31804server # [ 29.195702] systemd[1]: Created slice Slice /system/nix-daemon.1805server # [ 29.201063] systemd[1]: Started Nix Daemon instance (PID 977/UID 0).1806server # [ 29.288481] nix-daemon[979]: remote pid 977 is unknown user (trusted)1807server # [ 29.309945] systemd[1]: nix-daemon@0-1-977_978-0.service: Deactivated successfully.1808server # [ 29.325603] niks3-server[928]: 2026/07/07 08:17:09 INFO Received uploads request method=POST path=/api/pending_closures1809server # time=2026-07-07T08:17:09.961Z level=INFO msg="Uploading 5 paths to server (0 already cached)"1810server # time=2026-07-07T08:17:09.962Z level=INFO msg="Uploading jp8avbmpfcdnm0axwrzyk072nmq9cr0d-glibc-2.42-67 (44.4MB)"1811server # time=2026-07-07T08:17:09.964Z level=INFO msg="Uploading fqhxni8jcvyf5id4rvg9x2y00qx1963d-libidn2-2.3.8 (366.1KB)"1812server # time=2026-07-07T08:17:09.968Z level=INFO msg="Uploading qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3 (287.5KB)"1813server # time=2026-07-07T08:17:09.970Z level=INFO msg="Uploading wp59zwnmv6h2glrrb9rd7siprz1mxn6r-xgcc-15.2.0-libgcc (150.1KB)"1814server # time=2026-07-07T08:17:09.971Z level=INFO msg="Uploading 020cvdgp5va6j1mdz75s2nzfz25k37v2-libunistring-1.4.2 (2.0MB)"1815server # [ 31.057272] niks3-server[928]: 2026/07/07 08:17:11 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1816server # [ 31.070668] niks3-server[928]: 2026/07/07 08:17:11 INFO Completed multipart upload object_key=nar/0n9jhh8wlbk6hqhzsklzl8vbv6xab1d7yi91hr9z948fzw5s80jd.nar.zst upload_id=Y2E2MWI1YjAtOGYxNy00NDBkLWFjYTItODUyNDI4YWM5MzQ1LmYzZDk4OTc3LWM5NzItNDhjNS1hZjgyLWIyYWQ0YzgzOTQwY3gxNzgzNDEyMjI5OTU2OTE5OTYw parts=11817server # [ 31.081974] niks3-server[928]: 2026/07/07 08:17:11 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1818server # [ 31.083658] niks3-server[928]: 2026/07/07 08:17:11 INFO Signed narinfos id=1 count=51819server # time=2026-07-07T08:17:11.701Z level=INFO msg="Uploading 5 narinfos"1820server # [ 31.096367] niks3-server[928]: 2026/07/07 08:17:11 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1821server # time=2026-07-07T08:17:11.720Z level=INFO msg="Upload complete. (1.975s)"1822server # [ 31.104196] niks3-server[928]: 2026/07/07 08:17:11 INFO Completed upload id=11823server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/xpvqkjf5qfnwa90538299wai62qi10kb-niks3-1.7.0/bin/niks3 push /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3, in 2.14 seconds)1824server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token1825server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.02 seconds)1826server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/xpvqkjf5qfnwa90538299wai62qi10kb-niks3-1.7.0/bin/niks3 push /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.31827server # [ 31.283864] systemd[1]: Started Nix Daemon instance (PID 1006/UID 0).1828server # [ 31.368180] nix-daemon[1008]: remote pid 1006 is unknown user (trusted)1829server # [ 31.384941] systemd[1]: nix-daemon@1-2-1006_1007-0.service: Deactivated successfully.1830server # [ 31.394688] niks3-server[928]: 2026/07/07 08:17:12 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]1831server # time=2026-07-07T08:17:12.014Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"1832server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/xpvqkjf5qfnwa90538299wai62qi10kb-niks3-1.7.0/bin/niks3 push /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3, in 0.27 seconds)1833server: waiting for unit nginx.service1834server: (finished: waiting for unit nginx.service, in 0.06 seconds)1835server: waiting for TCP port 443 on localhost1836server # Connection to localhost (::1) 443 port [tcp/https] succeeded!1837server: (finished: waiting for TCP port 443 on localhost, in 0.03 seconds)1838server: must succeed: /nix/store/xpvqkjf5qfnwa90538299wai62qi10kb-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.31839server # time=2026-07-07T08:17:12.202Z 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.pem1840server # [ 31.655829] systemd[1]: Started Nix Daemon instance (PID 1035/UID 0).1841server # [ 31.741465] nix-daemon[1037]: remote pid 1035 is unknown user (trusted)1842server # [ 31.758356] systemd[1]: nix-daemon@2-3-1035_1036-0.service: Deactivated successfully.1843server # [ 31.779208] niks3-server[928]: 2026/07/07 08:17:12 INFO Received uploads request method=POST path=/api/pending_closures1844server # time=2026-07-07T08:17:12.404Z level=INFO msg="Uploading 0 paths to server (5 already cached)"1845server # [ 31.790876] niks3-server[928]: 2026/07/07 08:17:12 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1846server # [ 31.793138] niks3-server[928]: 2026/07/07 08:17:12 INFO Completed upload id=21847server # time=2026-07-07T08:17:12.411Z level=INFO msg="Upload complete. (203ms)"1848server: (finished: must succeed: /nix/store/xpvqkjf5qfnwa90538299wai62qi10kb-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)1849server: must fail: /nix/store/xpvqkjf5qfnwa90538299wai62qi10kb-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.31850server # time=2026-07-07T08:17:12.436Z 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)"1851server: (finished: must fail: /nix/store/xpvqkjf5qfnwa90538299wai62qi10kb-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)1852server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/xpvqkjf5qfnwa90538299wai62qi10kb-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.31853server # time=2026-07-07T08:17:12.524Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem1854server # [ 31.972390] systemd[1]: Started Nix Daemon instance (PID 1061/UID 0).1855server # [ 32.055190] nix-daemon[1063]: remote pid 1061 is unknown user (trusted)1856server # [ 32.072771] systemd[1]: nix-daemon@3-4-1061_1062-0.service: Deactivated successfully.1857server # [ 32.084002] niks3-server[928]: 2026/07/07 08:17:12 INFO Received uploads request method=POST path=/api/pending_closures1858server # time=2026-07-07T08:17:12.705Z level=INFO msg="Uploading 0 paths to server (5 already cached)"1859server # [ 32.091160] niks3-server[928]: 2026/07/07 08:17:12 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1860server # time=2026-07-07T08:17:12.710Z level=INFO msg="Upload complete. (184ms)"1861server # [ 32.094696] niks3-server[928]: 2026/07/07 08:17:12 INFO Completed upload id=31862server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/xpvqkjf5qfnwa90538299wai62qi10kb-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.27 seconds)1863server: 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'1864server # -----1865server: (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.03 seconds)1866server: 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.pem1867server # Certificate request self-signature ok1868server # subject=CN=other client1869server: (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.05 seconds)1870server: must fail: /nix/store/xpvqkjf5qfnwa90538299wai62qi10kb-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.31871server # time=2026-07-07T08:17:12.871Z 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.pem1872server # [ 32.323596] systemd[1]: Started Nix Daemon instance (PID 1089/UID 0).1873server # [ 32.398407] nix-daemon[1091]: remote pid 1089 is unknown user (trusted)1874server # [ 32.416503] systemd[1]: nix-daemon@4-5-1089_1090-0.service: Deactivated successfully.1875server # [ 32.427206] niks3-server[928]: 2026/07/07 08:17:13 WARN mTLS auth: subject not in bound subjects subject="CN=other client"1876server # time=2026-07-07T08:17:13.045Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"1877server: (finished: must fail: /nix/store/xpvqkjf5qfnwa90538299wai62qi10kb-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.26 seconds)1878server: must succeed: mkdir -p /tmp/test-store1879server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.02 seconds)1880server: must succeed: 1881 export AWS_ACCESS_KEY_ID=rustfsadmin1882export AWS_SECRET_ACCESS_KEY=rustfsadmin1883 nix copy --from 's3://niks3-test?endpoint=http://server:9000&region=us-east-1' --to /tmp/test-store /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.318841885server: (finished: must succeed: 1886 export AWS_ACCESS_KEY_ID=rustfsadmin1887export AWS_SECRET_ACCESS_KEY=rustfsadmin1888 nix copy --from 's3://niks3-test?endpoint=http://server:9000&region=us-east-1' --to /tmp/test-store /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.31889, in 0.81 seconds)1890server: must succeed: 1891cat > /tmp/test-drv.nix << 'EOF'1892derivation {1893 name = "test-build-log";1894 system = builtins.currentSystem;1895 builder = "/bin/sh";1896 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];1897}1898EOF18991900server: (finished: must succeed: 1901cat > /tmp/test-drv.nix << 'EOF'1902derivation {1903 name = "test-build-log";1904 system = builtins.currentSystem;1905 builder = "/bin/sh";1906 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];1907}1908EOF1909, in 0.03 seconds)1910server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix1911server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring1912server # [ 33.392898] systemd[1]: Started Nix Daemon instance (PID 1134/UID 0).1913server # [ 33.474280] nix-daemon[1138]: remote pid 1134 is unknown user (trusted)1914server # this derivation will be built:1915server # /nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv1916server # building '/nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv'...1917server # test-build-log> test build log output1918server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.34 seconds)1919server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/xpvqkjf5qfnwa90538299wai62qi10kb-niks3-1.7.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1920server # [ 33.666693] systemd[1]: nix-daemon@5-6-1134_1135-0.service: Deactivated successfully.1921server # [ 33.805681] systemd[1]: Started Nix Daemon instance (PID 1164/UID 0).1922server # [ 33.886332] nix-daemon[1166]: remote pid 1164 is unknown user (trusted)1923server # [ 33.903389] systemd[1]: nix-daemon@6-7-1164_1165-0.service: Deactivated successfully.1924server # [ 33.912892] niks3-server[928]: 2026/07/07 08:17:14 INFO Received uploads request method=POST path=/api/pending_closures1925server # time=2026-07-07T08:17:14.533Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1926server # time=2026-07-07T08:17:14.534Z level=INFO msg="Uploading z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log (128B)"1927server # time=2026-07-07T08:17:14.546Z level=INFO msg="Uploading 1 narinfos"1928server # [ 33.930930] niks3-server[928]: 2026/07/07 08:17:14 INFO Received sign narinfos request method=POST path=/api/pending_closures/4/sign1929server # [ 33.933430] niks3-server[928]: 2026/07/07 08:17:14 INFO Signed narinfos id=4 count=11930server # [ 33.937105] niks3-server[928]: 2026/07/07 08:17:14 INFO Received complete upload request method=POST path=/api/pending_closures/4/complete1931server # time=2026-07-07T08:17:14.556Z level=INFO msg="Upload complete. (198ms)"1932server # [ 33.940411] niks3-server[928]: 2026/07/07 08:17:14 INFO Completed upload id=41933server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/xpvqkjf5qfnwa90538299wai62qi10kb-niks3-1.7.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log, in 0.30 seconds)1934server: must succeed: 1935 export AWS_ACCESS_KEY_ID=rustfsadmin1936export AWS_SECRET_ACCESS_KEY=rustfsadmin1937 nix log --store 's3://niks3-test?endpoint=http://server:9000&region=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log19381939server: (finished: must succeed: 1940 export AWS_ACCESS_KEY_ID=rustfsadmin1941export AWS_SECRET_ACCESS_KEY=rustfsadmin1942 nix log --store 's3://niks3-test?endpoint=http://server:9000&region=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1943, in 0.19 seconds)1944server: must succeed: readlink /etc/niks3-test/symlink-wrapper1945server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.03 seconds)1946server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper1947server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.02 seconds)1948server: must succeed: test -L /nix/store/pvdx502yvdpvia8734qkyzlwik5fyglv-symlink-wrapper1949server: output: 1950!!! Traceback (most recent call last):1951!!! File "<string>", line 101, in <module>1952!!! server.succeed(f"test -L {symlink_wrapper}")1953!!! 1954!!! RequestedAssertionFailed: command `test -L /nix/store/pvdx502yvdpvia8734qkyzlwik5fyglv-symlink-wrapper` failed (exit code 1)1955cleanup1956kill QemuMachine (pid 14)1957builder # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/smwd56pl54f0pcjdspgh5vnf20c8xwsc-python3-3.13.13/bin/python3.13)1958kill QemuMachine (pid 15)1959server # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/smwd56pl54f0pcjdspgh5vnf20c8xwsc-python3-3.13.13/bin/python3.13)1960(finished: cleanup, in 0.48 seconds)1961additionally exposed symbols:1962 builder, server,1963 vlan1,1964 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_ssh1965Hello store path: /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.31966Test output path: /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1967Symlink wrapper store path: /nix/store/pvdx502yvdpvia8734qkyzlwik5fyglv-symlink-wrapper