vm-test-run-nixos-test-niks3
checks.aarch64-linux.nixos-test-niks3-lix
· build #137
· 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 vm10builder # Disk image does not exist, creating the virtualisation disk image...11builder: QEMU running (pid 47)12server # Disk image does not exist, creating the virtualisation disk image...13server: QEMU running (pid 48)14server # Formatting '/build/vm-state-server/tmp.XELLQqY7S2', fmt=raw size=107374182415builder # Formatting '/build/vm-state-builder/tmp.2j3Sctuine', fmt=raw size=107374182416server # mke2fs 1.47.4 (6-Mar-2025)17builder # mke2fs 1.47.4 (6-Mar-2025)18server # Discarding device blocks: 0/262144 done19builder # Discarding device blocks: 0/262144 done20server # Creating filesystem with 262144 4k blocks and 65536 inodes21builder # Creating filesystem with 262144 4k blocks and 65536 inodes22server # Filesystem UUID: cb199e25-24cb-40ac-91fe-ff508551eedf23builder # Filesystem UUID: ba9e0d27-5c71-4fdb-83e6-bb74f6c36cf324server # Superblock backups stored on blocks:25builder # Superblock backups stored on blocks:26server # 32768, 98304, 163840, 22937627builder # 32768, 98304, 163840, 22937628server # 29builder # 30server # Allocating group tables: 0/8 done31builder # Allocating group tables: 0/8 done32server # Writing inode tables: 0/8 done33builder # Writing inode tables: 0/8 done34server # Creating journal (8192 blocks): done35builder # Creating journal (8192 blocks): done36server # Writing superblocks and filesystem accounting information: 0/8 done37builder # Writing superblocks and filesystem accounting information: 0/8 done38server # 39builder # 40server # Virtualisation disk image created.41builder # Virtualisation disk image created.42(finished: start all VMs, in 0.54 seconds)43server: waiting for unit postgresql.service44server: waiting for the VM to finish booting45server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]46server # [ 0.000000] Linux version 6.18.41 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Thu Jul 30 11:05:57 UTC 202647server # [ 0.000000] KASLR enabled48server # [ 0.000000] random: crng init done49server # [ 0.000000] Machine model: linux,dummy-virt50builder # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]51server # [ 0.000000] efi: UEFI not found.52server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT53builder # [ 0.000000] Linux version 6.18.41 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Thu Jul 30 11:05:57 UTC 202654server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]55builder # [ 0.000000] KASLR enabled56builder # [ 0.000000] random: crng init done57server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded740-0x7fdf0ebf]58server # [ 0.000000] Zone ranges:59builder # [ 0.000000] Machine model: linux,dummy-virt60builder # [ 0.000000] efi: UEFI not found.61server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]62server # [ 0.000000] DMA32 empty63builder # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT64server # [ 0.000000] Normal empty65server # [ 0.000000] Device empty66builder # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]67server # [ 0.000000] Movable zone start for each node68server # [ 0.000000] Early memory node ranges69builder # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded740-0x7fdf0ebf]70builder # [ 0.000000] Zone ranges:71server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]72builder # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]73server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]74builder # [ 0.000000] DMA32 empty75builder # [ 0.000000] Normal empty76server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000077builder # [ 0.000000] Device empty78server # [ 0.000000] psci: probing for conduit method from DT.79builder # [ 0.000000] Movable zone start for each node80server # [ 0.000000] psci: PSCIv1.3 detected in firmware.81builder # [ 0.000000] Early memory node ranges82server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs83builder # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]84server # [ 0.000000] psci: Trusted OS migration not required85builder # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]86server # [ 0.000000] psci: SMC Calling Convention v1.187builder # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000088server # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)89builder # [ 0.000000] psci: probing for conduit method from DT.90builder # [ 0.000000] psci: PSCIv1.3 detected in firmware.91server # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u31129692server # [ 0.000000] Detected PIPT I-cache on CPU093builder # [ 0.000000] psci: Using standard PSCI v0.2 function IDs94builder # [ 0.000000] psci: Trusted OS migration not required95server # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)96builder # [ 0.000000] psci: SMC Calling Convention v1.197server # [ 0.000000] CPU features: detected: GICv3 CPU interface98server # [ 0.000000] CPU features: detected: Spectre-v499builder # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)100server # [ 0.000000] CPU features: detected: Spectre-BHB101builder # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u311296102server # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38103builder # [ 0.000000] Detected PIPT I-cache on CPU0104server # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23105builder # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)106server # [ 0.000000] alternatives: applying boot alternatives107builder # [ 0.000000] CPU features: detected: GICv3 CPU interface108builder # [ 0.000000] CPU features: detected: Spectre-v4109builder # [ 0.000000] CPU features: detected: Spectre-BHB110builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38111builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23112builder # [ 0.000000] alternatives: applying boot alternatives113server # [ 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/1vzs4qrnpfk7rzw0pfhrpk16i4r7mq7a-nixos-system-server-test/init regInfo=/nix/store/c4sf6bfns1b7cb6dr4fk61ml8z8hyg85-closure-info/registration console=ttyAMA0,115200n8 console=tty0114server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/c4sf6bfns1b7cb6dr4fk61ml8z8hyg85-closure-info/registration", will be passed to user space.115server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes116server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)117builder # [ 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/ghs2rkvqvf5isixsi658wqd4xnxccx1i-nixos-system-builder-test/init regInfo=/nix/store/ckaaz1nyb30c83blqcp3hvd0bhdnk8cb-closure-info/registration console=ttyAMA0,115200n8 console=tty0118server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)119server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB120builder # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/ckaaz1nyb30c83blqcp3hvd0bhdnk8cb-closure-info/registration", will be passed to user space.121server # [ 0.000000] software IO TLB: area num 1.122builder # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes123server # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)124server # [ 0.000000] Fallback order for Node 0: 0125builder # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)126server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144127server # [ 0.000000] Policy zone: DMA128builder # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)129server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off130builder # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB131builder # [ 0.000000] software IO TLB: area num 1.132server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1133server # [ 0.000000] allocated 2097152 bytes of page_ext134builder # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)135server # [ 0.000000] ftrace: allocating 74844 entries in 294 pages136builder # [ 0.000000] Fallback order for Node 0: 0137server # [ 0.000000] ftrace: allocated 294 pages with 4 groups138builder # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144139server # [ 0.000000] rcu: Hierarchical RCU implementation.140builder # [ 0.000000] Policy zone: DMA141server # [ 0.000000] rcu: RCU event tracing is enabled.142builder # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off143server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.144builder # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1145server # [ 0.000000] Trampoline variant of Tasks RCU enabled.146builder # [ 0.000000] allocated 2097152 bytes of page_ext147server # [ 0.000000] Rude variant of Tasks RCU enabled.148builder # [ 0.000000] ftrace: allocating 74844 entries in 294 pages149server # [ 0.000000] Tracing variant of Tasks RCU enabled.150builder # [ 0.000000] ftrace: allocated 294 pages with 4 groups151server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.152builder # [ 0.000000] rcu: Hierarchical RCU implementation.153builder # [ 0.000000] rcu: RCU event tracing is enabled.154server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1155builder # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.156server # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.157builder # [ 0.000000] Trampoline variant of Tasks RCU enabled.158builder # [ 0.000000] Rude variant of Tasks RCU enabled.159server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.160builder # [ 0.000000] Tracing variant of Tasks RCU enabled.161server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.162builder # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.163server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0164builder # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1165server # [ 0.000000] GICv3: 256 SPIs implemented166server # [ 0.000000] GICv3: 0 Extended SPIs implemented167builder # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.168server # [ 0.000000] Root IRQ handler: gic_handle_irq169server # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI170builder # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.171server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0172server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000173builder # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.174server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]175builder # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0176builder # [ 0.000000] GICv3: 256 SPIs implemented177server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)178builder # [ 0.000000] GICv3: 0 Extended SPIs implemented179builder # [ 0.000000] Root IRQ handler: gic_handle_irq180server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)181builder # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI182server # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000183builder # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0184server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000185builder # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000186builder # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]187server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.188builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)189server # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns190server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).191builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)192builder # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000193server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns194builder # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000195server # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns196builder # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.197server # [ 0.000029] arm-pv: using stolen time PV198builder # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns199server # [ 0.000379] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)200server # [ 0.000552] Console: colour dummy device 80x25201builder # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).202server # [ 0.000560] printk: legacy console [tty0] enabled203builder # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns204server # [ 0.000739] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)205server # [ 0.000746] pid_max: default: 32768 minimum: 301206builder # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns207builder # [ 0.000029] arm-pv: using stolen time PV208server # [ 0.000823] LSM: initializing lsm=capability,landlock,yama,bpf,ima209server # [ 0.000956] landlock: Up and running.210server # [ 0.000958] Yama: becoming mindful.211builder # [ 0.000417] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)212server # [ 0.001394] LSM support for eBPF active213builder # [ 0.000623] Console: colour dummy device 80x25214server # [ 0.001492] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)215builder # [ 0.000631] printk: legacy console [tty0] enabled216server # [ 0.001511] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)217builder # [ 0.000812] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)218server # [ 0.002626] cacheinfo: Unable to detect cache hierarchy for CPU 0219builder # [ 0.000819] pid_max: default: 32768 minimum: 301220server # [ 0.003324] rcu: Hierarchical SRCU implementation.221builder # [ 0.000900] LSM: initializing lsm=capability,landlock,yama,bpf,ima222server # [ 0.003328] rcu: Max phase no-delay instances is 1000.223builder # [ 0.001028] landlock: Up and running.224server # [ 0.004501] fsl-mc MSI: its@8080000 domain created225builder # [ 0.001031] Yama: becoming mindful.226server # [ 0.004590] EFI services will not be available.227builder # [ 0.001492] LSM support for eBPF active228server # [ 0.004659] smp: Bringing up secondary CPUs ...229builder # [ 0.001602] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)230server # [ 0.004667] smp: Brought up 1 node, 1 CPU231server # [ 0.004670] SMP: Total of 1 processors activated.232builder # [ 0.001621] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)233server # [ 0.004673] CPU: All CPU(s) started at EL1234builder # [ 0.002649] cacheinfo: Unable to detect cache hierarchy for CPU 0235server # [ 0.004682] CPU features: detected: Branch Target Identification236builder # [ 0.003367] rcu: Hierarchical SRCU implementation.237server # [ 0.004689] CPU features: detected: ARMv8.4 Translation Table Level238builder # [ 0.003371] rcu: Max phase no-delay instances is 1000.239builder # [ 0.004509] fsl-mc MSI: its@8080000 domain created240server # [ 0.004693] CPU features: detected: Instruction cache invalidation not required for I/D coherence241builder # [ 0.004599] EFI services will not be available.242builder # [ 0.004674] smp: Bringing up secondary CPUs ...243server # [ 0.004696] CPU features: detected: Data cache clean to the PoU not required for I/D coherence244builder # [ 0.004682] smp: Brought up 1 node, 1 CPU245server # [ 0.004700] CPU features: detected: Common not Private translations246builder # [ 0.004685] SMP: Total of 1 processors activated.247server # [ 0.004703] CPU features: detected: CRC32 instructions248builder # [ 0.004688] CPU: All CPU(s) started at EL1249builder # [ 0.004699] CPU features: detected: Branch Target Identification250server # [ 0.004706] CPU features: detected: Data cache clean to Point of Deep Persistence251builder # [ 0.004706] CPU features: detected: ARMv8.4 Translation Table Level252server # [ 0.004709] CPU features: detected: Data cache clean to Point of Persistence253server # [ 0.004712] CPU features: detected: Data independent timing control (DIT)254builder # [ 0.004709] CPU features: detected: Instruction cache invalidation not required for I/D coherence255server # [ 0.004716] CPU features: detected: E0PD256server # [ 0.004718] CPU features: detected: Enhanced Counter Virtualization257builder # [ 0.004713] CPU features: detected: Data cache clean to the PoU not required for I/D coherence258builder # [ 0.004716] CPU features: detected: Common not Private translations259server # [ 0.004721] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)260builder # [ 0.004720] CPU features: detected: CRC32 instructions261server # [ 0.004724] CPU features: detected: Enhanced Virtualization Traps262server # [ 0.004727] CPU features: detected: Fine Grained Traps263builder # [ 0.004722] CPU features: detected: Data cache clean to Point of Deep Persistence264server # [ 0.004731] CPU features: detected: Generic authentication (architected QARMA5 algorithm)265builder # [ 0.004726] CPU features: detected: Data cache clean to Point of Persistence266server # [ 0.004736] CPU features: detected: RCpc load-acquire (LDAPR)267builder # [ 0.004729] CPU features: detected: Data independent timing control (DIT)268builder # [ 0.004732] CPU features: detected: E0PD269server # [ 0.004739] CPU features: detected: LSE atomic instructions270server # [ 0.004742] CPU features: detected: Privileged Access Never271builder # [ 0.004734] CPU features: detected: Enhanced Counter Virtualization272server # [ 0.004745] CPU features: detected: PMUv3273builder # [ 0.004737] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)274server # [ 0.004748] CPU features: detected: RAS Extension Support275builder # [ 0.004741] CPU features: detected: Enhanced Virtualization Traps276server # [ 0.004751] CPU features: detected: RASv1p1 Extension Support277builder # [ 0.004744] CPU features: detected: Fine Grained Traps278server # [ 0.004753] CPU features: detected: Random Number Generator279server # [ 0.004756] CPU features: detected: Speculation barrier (SB)280builder # [ 0.004747] CPU features: detected: Generic authentication (architected QARMA5 algorithm)281server # [ 0.004758] CPU features: detected: Stage-2 Force Write-Back282builder # [ 0.004753] CPU features: detected: RCpc load-acquire (LDAPR)283server # [ 0.004762] CPU features: detected: TLB range maintenance instructions284builder # [ 0.004756] CPU features: detected: LSE atomic instructions285server # [ 0.004766] CPU features: detected: Speculative Store Bypassing Safe (SSBS)286builder # [ 0.004758] CPU features: detected: Privileged Access Never287builder # [ 0.004761] CPU features: detected: PMUv3288server # [ 0.004802] alternatives: applying system-wide alternatives289builder # [ 0.004764] CPU features: detected: RAS Extension Support290server # [ 0.007682] CPU features: detected: BBM Level 2 without TLB conflict abort291builder # [ 0.004766] CPU features: detected: RASv1p1 Extension Support292builder # [ 0.004769] CPU features: detected: Random Number Generator293server # [ 0.007840] Memory: 893432K/1048576K available (24384K kernel code, 7090K rwdata, 26336K rodata, 4736K init, 1106K bss, 113896K reserved, 32768K cma-reserved)294builder # [ 0.004772] CPU features: detected: Speculation barrier (SB)295builder # [ 0.004774] CPU features: detected: Stage-2 Force Write-Back296server # [ 0.008162] devtmpfs: initialized297builder # [ 0.004778] CPU features: detected: TLB range maintenance instructions298server # [ 0.009915] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)299builder # [ 0.004783] CPU features: detected: Speculative Store Bypassing Safe (SSBS)300server # [ 0.009937] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).301builder # [ 0.004819] alternatives: applying system-wide alternatives302server # [ 0.010134] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL303builder # [ 0.007778] CPU features: detected: BBM Level 2 without TLB conflict abort304server # [ 0.010138] 0 pages in range for non-PLT usage305server # [ 0.010139] 508304 pages in range for PLT usage306server # [ 0.010247] pinctrl core: initialized pinctrl subsystem307server # [ 0.010971] DMI not present or invalid.308builder # [ 0.007918] Memory: 893356K/1048576K available (24384K kernel code, 7090K rwdata, 26336K rodata, 4736K init, 1106K bss, 113904K reserved, 32768K cma-reserved)309builder # [ 0.008258] devtmpfs: initialized310server # [ 0.013862] NET: Registered PF_NETLINK/PF_ROUTE protocol family311builder # [ 0.009905] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)312server # [ 0.016286] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations313builder # [ 0.009926] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).314server # [ 0.016455] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations315builder # [ 0.010107] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL316server # [ 0.016615] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations317builder # [ 0.010112] 0 pages in range for non-PLT usage318server # [ 0.016637] audit: initializing netlink subsys (disabled)319builder # [ 0.010113] 508304 pages in range for PLT usage320server # [ 0.017204] thermal_sys: Registered thermal governor 'fair_share'321server # [ 0.017206] thermal_sys: Registered thermal governor 'bang_bang'322builder # [ 0.010222] pinctrl core: initialized pinctrl subsystem323builder # [ 0.010960] DMI not present or invalid.324server # [ 0.017209] thermal_sys: Registered thermal governor 'step_wise'325builder # [ 0.014068] NET: Registered PF_NETLINK/PF_ROUTE protocol family326server # [ 0.017212] thermal_sys: Registered thermal governor 'user_space'327builder # [ 0.016456] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations328server # [ 0.017215] thermal_sys: Registered thermal governor 'power_allocator'329builder # [ 0.016615] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations330server # [ 0.017241] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1331server # [ 0.017251] cpuidle: using governor ladder332builder # [ 0.016773] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations333server # [ 0.017256] cpuidle: using governor menu334builder # [ 0.016795] audit: initializing netlink subsys (disabled)335server # [ 0.017454] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.336builder # [ 0.017347] thermal_sys: Registered thermal governor 'fair_share'337server # [ 0.017469] ASID allocator initialised with 65536 entries338server # [ 0.018527] Serial: AMBA PL011 UART driver339builder # [ 0.017349] thermal_sys: Registered thermal governor 'bang_bang'340builder # [ 0.017352] thermal_sys: Registered thermal governor 'step_wise'341server # [ 0.023624] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1342server # [ 0.023749] printk: console [ttyAMA0] enabled343builder # [ 0.017355] thermal_sys: Registered thermal governor 'user_space'344builder # [ 0.017358] thermal_sys: Registered thermal governor 'power_allocator'345builder # [ 0.017383] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1346builder # [ 0.017393] cpuidle: using governor ladder347builder # [ 0.017399] cpuidle: using governor menu348builder # [ 0.017592] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.349builder # [ 0.017608] ASID allocator initialised with 65536 entries350builder # [ 0.018730] Serial: AMBA PL011 UART driver351builder # [ 0.023816] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1352builder # [ 0.023940] printk: console [ttyAMA0] enabled353server # [ 0.147055] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages354server # [ 0.147072] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page355server # [ 0.147079] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages356server # [ 0.147083] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page357server # [ 0.147087] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages358server # [ 0.147092] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page359server # [ 0.147097] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages360server # [ 0.147101] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page361builder # [ 0.150543] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages362builder # [ 0.150561] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page363server # [ 0.154595] fbcon: Taking over console364builder # [ 0.150566] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages365server # [ 0.154609] ACPI: Interpreter disabled.366builder # [ 0.150570] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page367builder # [ 0.150575] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages368builder # [ 0.150579] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page369builder # [ 0.150583] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages370builder # [ 0.150587] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page371builder # [ 0.158325] fbcon: Taking over console372builder # [ 0.158340] ACPI: Interpreter disabled.373server # [ 0.163566] iommu: Default domain type: Translated374server # [ 0.163576] iommu: DMA domain TLB invalidation policy: strict mode375server # [ 0.163924] SCSI subsystem initialized376server # [ 0.165943] usbcore: registered new interface driver usbfs377server # [ 0.165978] usbcore: registered new interface driver hub378server # [ 0.165995] usbcore: registered new device driver usb379server # [ 0.166237] pps_core: LinuxPPS API ver. 1 registered380server # [ 0.166243] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>381server # [ 0.166253] PTP clock support registered382server # [ 0.166299] EDAC MC: Ver: 3.0.0383builder # [ 0.166999] iommu: Default domain type: Translated384builder # [ 0.167010] iommu: DMA domain TLB invalidation policy: strict mode385server # [ 0.171008] scmi_core: SCMI protocol bus registered386builder # [ 0.167348] SCSI subsystem initialized387server # [ 0.172004] FPGA manager framework388server # [ 0.172950] vgaarb: loaded389builder # [ 0.169408] usbcore: registered new interface driver usbfs390builder # [ 0.169446] usbcore: registered new interface driver hub391server # [ 0.173570] clocksource: Switched to clocksource arch_sys_counter392builder # [ 0.169463] usbcore: registered new device driver usb393server # [ 0.174004] VFS: Disk quotas dquot_6.6.0394builder # [ 0.169724] pps_core: LinuxPPS API ver. 1 registered395server # [ 0.174042] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)396builder # [ 0.169731] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>397server # [ 0.176512] netfs: FS-Cache loaded398builder # [ 0.169741] PTP clock support registered399server # [ 0.176631] pnp: PnP ACPI: disabled400builder # [ 0.169788] EDAC MC: Ver: 3.0.0401builder # [ 0.174596] scmi_core: SCMI protocol bus registered402builder # [ 0.175603] FPGA manager framework403builder # [ 0.176540] vgaarb: loaded404builder # [ 0.177164] clocksource: Switched to clocksource arch_sys_counter405builder # [ 0.177613] VFS: Disk quotas dquot_6.6.0406builder # [ 0.177644] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)407builder # [ 0.180158] netfs: FS-Cache loaded408builder # [ 0.180264] pnp: PnP ACPI: disabled409server # [ 0.184757] NET: Registered PF_INET protocol family410server # [ 0.184920] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)411builder # [ 0.188856] NET: Registered PF_INET protocol family412builder # [ 0.189035] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)413server # [ 0.213373] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)414server # [ 0.213412] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)415server # [ 0.213436] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)416server # [ 0.213478] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)417server # [ 0.213554] TCP: Hash tables configured (established 8192 bind 8192)418server # [ 0.218363] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)419server # [ 0.218396] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)420server # [ 0.218442] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)421server # [ 0.218519] NET: Registered PF_UNIX/PF_LOCAL protocol family422builder # [ 0.217716] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)423server # [ 0.218569] NET: Registered PF_XDP protocol family424server # [ 0.218589] PCI: CLS 0 bytes, default 64425builder # [ 0.217758] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)426builder # [ 0.217782] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)427builder # [ 0.217827] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)428builder # [ 0.217902] TCP: Hash tables configured (established 8192 bind 8192)429builder # [ 0.217986] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)430builder # [ 0.218048] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)431builder # [ 0.218094] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)432builder # [ 0.218175] NET: Registered PF_UNIX/PF_LOCAL protocol family433builder # [ 0.218201] NET: Registered PF_XDP protocol family434server # [ 0.223257] Trying to unpack rootfs image as initramfs...435builder # [ 0.218218] PCI: CLS 0 bytes, default 64436server # [ 0.230302] kvm [1]: HYP mode not available437builder # [ 0.227844] Trying to unpack rootfs image as initramfs...438builder # [ 0.233928] kvm [1]: HYP mode not available439server # [ 0.323219] Initialise system trusted keyrings440server # [ 0.323989] workingset: timestamp_bits=42 max_order=18 bucket_order=0441server # [ 0.325281] squashfs: version 4.0 (2009/01/31) Phillip Lougher442builder # [ 0.325716] Initialise system trusted keyrings443builder # [ 0.326477] workingset: timestamp_bits=42 max_order=18 bucket_order=0444builder # [ 0.327729] squashfs: version 4.0 (2009/01/31) Phillip Lougher445builder # [ 0.328508] 9p: Installing v9fs 9p2000 file system support446server # [ 0.326154] 9p: Installing v9fs 9p2000 file system support447server # [ 0.346143] Key type asymmetric registered448server # [ 0.346166] Asymmetric key parser 'x509' registered449server # [ 0.346229] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)450builder # [ 0.349127] Key type asymmetric registered451builder # [ 0.349154] Asymmetric key parser 'x509' registered452server # [ 0.353657] io scheduler mq-deadline registered453server # [ 0.353678] io scheduler kyber registered454builder # [ 0.357278] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)455builder # [ 0.358495] io scheduler mq-deadline registered456builder # [ 0.358507] io scheduler kyber registered457server # [ 0.361705] pl061_gpio 9030000.pl061: PL061 GPIO chip registered458server # [ 0.363071] ledtrig-cpu: registered to indicate activity on CPUs459server # [ 0.363451] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:460server # [ 0.363468] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000461server # [ 0.363487] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000462server # [ 0.363496] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000463server # [ 0.363516] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits464server # [ 0.363540] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]465server # [ 0.363612] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00466server # [ 0.363622] pci_bus 0000:00: root bus resource [bus 00-ff]467server # [ 0.363628] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]468server # [ 0.363633] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]469server # [ 0.363638] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]470server # [ 0.363775] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint471server # [ 0.364207] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint472server # [ 0.364402] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]473server # [ 0.364419] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]474server # [ 0.364476] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]475builder # [ 0.369301] pl061_gpio 9030000.pl061: PL061 GPIO chip registered476server # [ 0.364492] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]477builder # [ 0.369917] ledtrig-cpu: registered to indicate activity on CPUs478builder # [ 0.370288] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:479server # [ 0.364934] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint480server # [ 0.365111] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]481builder # [ 0.370306] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000482server # [ 0.365126] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]483builder # [ 0.370319] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000484server # [ 0.365155] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]485builder # [ 0.370327] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000486builder # [ 0.370347] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits487builder # [ 0.370372] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]488builder # [ 0.370464] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00489builder # [ 0.370474] pci_bus 0000:00: root bus resource [bus 00-ff]490builder # [ 0.370480] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]491builder # [ 0.370486] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]492builder # [ 0.370492] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]493builder # [ 0.370583] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint494builder # [ 0.371028] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint495builder # [ 0.371218] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]496builder # [ 0.371235] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]497builder # [ 0.371265] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]498builder # [ 0.371281] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]499builder # [ 0.371732] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint500builder # [ 0.371913] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]501builder # [ 0.371930] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]502builder # [ 0.371960] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]503builder # [ 0.372426] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint504builder # [ 0.372607] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]505server # [ 0.393660] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint506server # [ 0.393861] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]507builder # [ 0.372623] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]508server # [ 0.393879] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]509builder # [ 0.372653] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]510server # [ 0.393909] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]511builder # [ 0.373102] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint512server # [ 0.394397] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint513builder # [ 0.373305] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]514server # [ 0.394578] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]515builder # [ 0.373322] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]516server # [ 0.394594] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]517builder # [ 0.373351] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]518server # [ 0.394624] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]519builder # [ 0.373818] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint520server # [ 0.395078] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint521builder # [ 0.374001] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]522server # [ 0.395259] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]523builder # [ 0.374017] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]524server # [ 0.395275] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]525builder # [ 0.374047] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]526server # [ 0.395305] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]527builder # [ 0.374509] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint528server # [ 0.395750] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint529builder # [ 0.374691] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]530server # [ 0.395930] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]531builder # [ 0.374708] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]532server # [ 0.395946] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]533builder # [ 0.374737] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]534server # [ 0.395976] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]535builder # [ 0.375183] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint536server # [ 0.396419] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint537builder # [ 0.375364] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]538server # [ 0.396616] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]539builder # [ 0.375380] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]540server # [ 0.396632] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]541builder # [ 0.375410] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]542server # [ 0.396661] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]543builder # [ 0.375426] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]544server # [ 0.396678] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]545builder # [ 0.375876] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint546server # [ 0.397155] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint547builder # [ 0.376061] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]548server # [ 0.397338] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]549builder # [ 0.376091] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]550server # [ 0.397368] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]551builder # [ 0.376547] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint552server # [ 0.397841] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint553builder # [ 0.376732] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]554server # [ 0.398024] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]555builder # [ 0.376762] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]556server # [ 0.398054] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]557builder # [ 0.377145] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint558server # [ 0.398437] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint559server # [ 0.398623] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]560server # [ 0.398863] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint561server # [ 0.399147] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]562server # [ 0.399166] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]563server # [ 0.399195] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]564server # [ 0.399646] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint565server # [ 0.399826] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]566server # [ 0.399842] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]567server # [ 0.399871] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]568server # [ 0.400430] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned569builder # [ 0.425390] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]570server # [ 0.400449] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned571builder # [ 0.425701] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint572server # [ 0.400456] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned573builder # [ 0.425972] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]574builder # [ 0.425991] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]575server # [ 0.400501] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned576builder # [ 0.426021] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]577server # [ 0.400547] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned578builder # [ 0.426477] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint579server # [ 0.400593] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned580builder # [ 0.426659] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]581server # [ 0.400639] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned582builder # [ 0.426675] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]583builder # [ 0.426705] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]584server # [ 0.400685] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned585builder # [ 0.427286] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned586server # [ 0.400730] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned587builder # [ 0.427298] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned588server # [ 0.400776] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned589builder # [ 0.427304] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned590server # [ 0.400823] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned591builder # [ 0.427349] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned592server # [ 0.400869] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned593builder # [ 0.427396] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned594server # [ 0.400942] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned595builder # [ 0.427443] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned596server # [ 0.400988] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned597server # [ 0.401009] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned598builder # [ 0.427489] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned599server # [ 0.401030] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned600builder # [ 0.427535] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned601server # [ 0.401052] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned602builder # [ 0.427583] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned603server # [ 0.401074] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned604builder # [ 0.427631] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned605server # [ 0.401095] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned606builder # [ 0.427678] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned607server # [ 0.401120] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned608server # [ 0.401142] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned609builder # [ 0.427725] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned610server # [ 0.401163] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned611builder # [ 0.427786] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned612server # [ 0.401185] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned613builder # [ 0.427831] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned614server # [ 0.401207] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned615builder # [ 0.427853] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned616server # [ 0.401229] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned617builder # [ 0.427875] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned618server # [ 0.401251] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned619builder # [ 0.427896] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned620server # [ 0.401272] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned621builder # [ 0.427918] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned622server # [ 0.401293] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned623builder # [ 0.427940] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned624server # [ 0.401314] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned625server # [ 0.401335] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned626builder # [ 0.427966] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned627server # [ 0.401357] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned628builder # [ 0.427988] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned629server # [ 0.401378] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned630builder # [ 0.428010] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned631server # [ 0.401399] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned632builder # [ 0.428033] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned633server # [ 0.401421] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned634builder # [ 0.428055] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned635server # [ 0.401447] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]636builder # [ 0.428078] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned637server # [ 0.401457] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]638builder # [ 0.428099] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned639server # [ 0.401462] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]640builder # [ 0.428121] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned641builder # [ 0.428142] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned642builder # [ 0.428164] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned643builder # [ 0.428186] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned644builder # [ 0.428207] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned645builder # [ 0.428229] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned646builder # [ 0.428251] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned647builder # [ 0.428273] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned648builder # [ 0.428300] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]649builder # [ 0.428310] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]650builder # [ 0.428315] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]651server # [ 0.470653] pci 0000:00:0a.0: enabling device (0000 -> 0002)652builder # [ 0.473521] pci 0000:00:0a.0: enabling device (0000 -> 0002)653server # [ 0.490829] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)654server # [ 0.492947] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)655builder # [ 0.499206] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)656server # [ 0.503962] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)657builder # [ 0.505455] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)658server # [ 0.510182] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)659builder # [ 0.507505] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)660server # [ 0.512127] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)661builder # [ 0.517851] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)662builder # [ 0.519824] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)663server # [ 0.522127] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)664server # [ 0.524157] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)665server # [ 0.535954] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)666builder # [ 0.529765] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)667builder # [ 0.532692] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)668builder # [ 0.535256] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)669server # [ 0.538936] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)670server # [ 0.540798] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)671server # [ 0.543063] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)672builder # [ 0.545354] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)673builder # [ 0.547339] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)674server # [ 0.564109] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled675builder # [ 0.557463] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)676server # [ 0.566629] msm_serial: driver initialized677server # [ 0.566772] SuperH (H)SCI(F) driver initialized678server # [ 0.566825] STM32 USART driver initialized679builder # [ 0.570430] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled680builder # [ 0.573021] msm_serial: driver initialized681builder # [ 0.573743] SuperH (H)SCI(F) driver initialized682builder # [ 0.573804] STM32 USART driver initialized683server # [ 0.598766] loop: module loaded684server # [ 0.598953] virtio_blk virtio5: 1/0/0 default/read/poll queues685server # [ 0.599655] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)686builder # [ 0.600722] loop: module loaded687builder # [ 0.600911] virtio_blk virtio5: 1/0/0 default/read/poll queues688server # [ 0.606172] megasas: 07.734.00.00-rc1689server # [ 0.606865] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]690server # [ 0.609107] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000691server # [ 0.609132] Intel/Sharp Extended Query Table at 0x0031692builder # [ 0.609776] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)693server # [ 0.618783] Using buffer write method694server # [ 0.618862] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]695server # [ 0.621565] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000696builder # [ 0.613871] megasas: 07.734.00.00-rc1697builder # [ 0.614546] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]698builder # [ 0.622826] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000699server # [ 0.629611] Intel/Sharp Extended Query Table at 0x0031700builder # [ 0.622852] Intel/Sharp Extended Query Table at 0x0031701server # [ 0.631156] Using buffer write method702builder # [ 0.624408] Using buffer write method703server # [ 0.631185] Concatenating MTD devices:704server # [ 0.631189] (0): "0.flash"705server # [ 0.631193] (1): "0.flash"706builder # [ 0.624481] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]707server # [ 0.631196] into device "0.flash"708builder # [ 0.646375] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000709builder # [ 0.646409] Intel/Sharp Extended Query Table at 0x0031710builder # [ 0.647998] Using buffer write method711builder # [ 0.648026] Concatenating MTD devices:712builder # [ 0.648030] (0): "0.flash"713builder # [ 0.648034] (1): "0.flash"714builder # [ 0.648038] into device "0.flash"715server # [ 0.870328] Freeing initrd memory: 27104K716server # [ 0.876229] tun: Universal TUN/TAP device driver, 1.6717builder # [ 0.877768] Freeing initrd memory: 27112K718server # [ 0.879967] thunder_xcv, ver 1.0719server # [ 0.880002] thunder_bgx, ver 1.0720server # [ 0.880024] nicpf, ver 1.0721server # [ 0.880573] e1000: Intel(R) PRO/1000 Network Driver722server # [ 0.880580] e1000: Copyright (c) 1999-2006 Intel Corporation.723server # [ 0.880614] e1000e: Intel(R) PRO/1000 Network Driver724server # [ 0.880621] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.725server # [ 0.880649] igb: Intel(R) Gigabit Ethernet Network Driver726server # [ 0.880655] igb: Copyright (c) 2007-2014 Intel Corporation.727builder # [ 0.883671] tun: Universal TUN/TAP device driver, 1.6728server # [ 0.880677] igbvf: Intel(R) Gigabit Virtual Function Network Driver729server # [ 0.880682] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.730server # [ 0.880809] sky2: driver version 1.30731server # [ 0.890235] ehci-pci 0000:00:0a.0: EHCI Host Controller732server # [ 0.890266] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1733builder # [ 0.887372] thunder_xcv, ver 1.0734server # [ 0.890435] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000735builder # [ 0.887415] thunder_bgx, ver 1.0736builder # [ 0.887437] nicpf, ver 1.0737builder # [ 0.887964] e1000: Intel(R) PRO/1000 Network Driver738server # [ 0.892917] usbcore: registered new interface driver usb-storage739builder # [ 0.887972] e1000: Copyright (c) 1999-2006 Intel Corporation.740server # [ 0.893002] usbcore: registered new interface driver usbserial_generic741builder # [ 0.888000] e1000e: Intel(R) PRO/1000 Network Driver742server # [ 0.893015] usbserial: USB Serial support registered for generic743builder # [ 0.888008] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.744builder # [ 0.888036] igb: Intel(R) Gigabit Ethernet Network Driver745server # [ 0.895889] hv_vmbus: registering driver hyperv_keyboard746builder # [ 0.888042] igb: Copyright (c) 2007-2014 Intel Corporation.747server # [ 0.897409] rtc-pl031 9010000.pl031: registered as rtc0748builder # [ 0.888063] igbvf: Intel(R) Gigabit Virtual Function Network Driver749builder # [ 0.888069] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.750server # [ 0.897437] rtc-pl031 9010000.pl031: setting system clock to 2026-08-27T08:41:32 UTC (1787820092)751builder # [ 0.888202] sky2: driver version 1.30752server # [ 0.899510] i2c_dev: i2c /dev entries driver753builder # [ 0.897153] usbcore: registered new interface driver usb-storage754builder # [ 0.897826] ehci-pci 0000:00:0a.0: EHCI Host Controller755builder # [ 0.897854] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1756builder # [ 0.898051] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000757server # [ 0.902746] sdhci: Secure Digital Host Controller Interface driver758builder # [ 0.901323] usbcore: registered new interface driver usbserial_generic759server # [ 0.902755] sdhci: Copyright(c) Pierre Ossman760builder # [ 0.901339] usbserial: USB Serial support registered for generic761server # [ 0.903017] Synopsys Designware Multimedia Card Interface Driver762builder # [ 0.901963] hv_vmbus: registering driver hyperv_keyboard763server # [ 0.903376] sdhci-pltfm: SDHCI platform and OF driver helper764builder # [ 0.904985] rtc-pl031 9010000.pl031: registered as rtc0765server # [ 0.904959] hid: raw HID events driver (C) Jiri Kosina766server # [ 0.905199] usbcore: registered new interface driver usbhid767builder # [ 0.905012] rtc-pl031 9010000.pl031: setting system clock to 2026-08-27T08:41:32 UTC (1787820092)768server # [ 0.905205] usbhid: USB HID core driver769server # [ 0.910185] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00770builder # [ 0.907149] i2c_dev: i2c /dev entries driver771server # [ 0.911326] hub 1-0:1.0: USB hub found772server # [ 0.911819] hub 1-0:1.0: 6 ports detected773server # [ 0.913385] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available774builder # [ 0.910433] sdhci: Secure Digital Host Controller Interface driver775server # [ 0.915965] drop_monitor: Initializing network drop monitor service776builder # [ 0.910448] sdhci: Copyright(c) Pierre Ossman777server # [ 0.916133] NET: Registered PF_INET6 protocol family778builder # [ 0.910708] Synopsys Designware Multimedia Card Interface Driver779server # [ 0.918054] Segment Routing with IPv6780builder # [ 0.911089] sdhci-pltfm: SDHCI platform and OF driver helper781server # [ 0.918084] In-situ OAM (IOAM) with IPv6782builder # [ 0.912685] hid: raw HID events driver (C) Jiri Kosina783server # [ 0.918114] NET: Registered PF_PACKET protocol family784builder # [ 0.912929] usbcore: registered new interface driver usbhid785builder # [ 0.912935] usbhid: USB HID core driver786server # [ 0.919737] 9pnet: Installing 9P2000 support787builder # [ 0.917864] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00788builder # [ 0.918181] hub 1-0:1.0: USB hub found789builder # [ 0.918200] hub 1-0:1.0: 6 ports detected790builder # [ 0.921111] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available791server # [ 0.922444] Key type dns_resolver registered792builder # [ 0.923734] drop_monitor: Initializing network drop monitor service793builder # [ 0.923896] NET: Registered PF_INET6 protocol family794builder # [ 0.925892] Segment Routing with IPv6795builder # [ 0.925909] In-situ OAM (IOAM) with IPv6796server # [ 0.928679] registered taskstats version 1797builder # [ 0.925935] NET: Registered PF_PACKET protocol family798server # [ 0.928834] Loading compiled-in X.509 certificates799builder # [ 0.927645] 9pnet: Installing 9P2000 support800builder # [ 0.930271] Key type dns_resolver registered801server # [ 0.937365] Demotion targets for Node 0: null802server # [ 0.937467] Key type .fscrypt registered803server # [ 0.937474] Key type fscrypt-provisioning registered804server # [ 0.937568] ima: No TPM chip found, activating TPM-bypass!805builder # [ 0.936382] registered taskstats version 1806server # [ 0.940060] ima: Allocated hash algorithm: sha1807builder # [ 0.936537] Loading compiled-in X.509 certificates808server # [ 0.940087] ima: No architecture policies found809server # [ 0.941766] input: gpio-keys as /devices/platform/gpio-keys/input/input0810builder # [ 0.945067] Demotion targets for Node 0: null811builder # [ 0.945876] Key type .fscrypt registered812builder # [ 0.945887] Key type fscrypt-provisioning registered813builder # [ 0.945982] ima: No TPM chip found, activating TPM-bypass!814builder # [ 0.946001] ima: Allocated hash algorithm: sha1815builder # [ 0.946021] ima: No architecture policies found816builder # [ 0.949562] input: gpio-keys as /devices/platform/gpio-keys/input/input0817server # [ 0.959196] clk: Disabling unused clocks818server # [ 0.959223] PM: genpd: Disabling unused power domains819server # [ 0.963477] Freeing unused kernel memory: 4736K820server # [ 0.963686] Run /init as init process821builder # [ 0.967219] clk: Disabling unused clocks822builder # [ 0.967247] PM: genpd: Disabling unused power domains823builder # [ 0.971531] Freeing unused kernel memory: 4736K824builder # [ 0.971707] Run /init as init process825server # [ 0.979709] systemd[1]: Successfully made /usr/ read-only.826builder # [ 0.987313] systemd[1]: Successfully made /usr/ read-only.827server # [ 1.157656] usb 1-1: new high-speed USB device number 2 using ehci-pci828builder # [ 1.165236] usb 1-1: new high-speed USB device number 2 using ehci-pci829server # [ 1.311811] 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/input1830builder # [ 1.317648] 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/input1831server # [ 1.318050] systemd[1]: systemd 261.1 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)832server # [ 1.318111] systemd[1]: Detected virtualization qemu.833server # [ 1.318185] systemd[1]: Detected architecture arm64.834server # [ 1.318209] systemd[1]: Running in initrd.835builder # [ 1.323830] systemd[1]: systemd 261.1 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)836server # [ 1.319086] systemd[1]: Initializing machine ID from random generator.837server # [ 1.319355] systemd[1]: Hostname set to <server>.838builder # [ 1.323891] systemd[1]: Detected virtualization qemu.839builder # [ 1.323972] systemd[1]: Detected architecture arm64.840builder # [ 1.323998] systemd[1]: Running in initrd.841builder # [ 1.343422] systemd[1]: Initializing machine ID from random generator.842builder # [ 1.346352] systemd[1]: Hostname set to <builder>.843server # [ 1.397834] 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.405425] 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.517625] usb 1-2: new high-speed USB device number 3 using ehci-pci846builder # [ 1.529239] usb 1-2: new high-speed USB device number 3 using ehci-pci847builder # [ 1.642278] systemd[1]: bpf-restrict-fs: LSM BPF program attached848server # [ 1.647845] systemd[1]: bpf-restrict-fs: LSM BPF program attached849server # [ 1.676055] 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/input2850server # [ 1.681511] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0851builder # [ 1.683167] 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/input2852builder # [ 1.685498] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0853builder # [ 1.714811] systemd[1]: Queued start job for default target Initrd Default Target.854builder # [ 1.723402] systemd[1]: Created slice Slice /system/modprobe.855builder # [ 1.724491] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.856builder # [ 1.725775] systemd[1]: Expecting device /dev/disk/by-label/nixos...857builder # [ 1.726760] systemd[1]: Reached target Path Units.858builder # [ 1.727440] systemd[1]: Reached target Slice Units.859builder # [ 1.728132] systemd[1]: Reached target Swaps.860builder # [ 1.728756] systemd[1]: Reached target Timer Units.861builder # [ 1.729727] systemd[1]: Listening on D-Bus System Message Bus Socket.862builder # [ 1.730769] systemd[1]: Listening on Journal Socket (/dev/log).863builder # [ 1.731751] systemd[1]: Listening on Journal Sockets.864builder # [ 1.732605] systemd[1]: Listening on udev Control Socket.865builder # [ 1.733624] systemd[1]: Listening on udev Kernel Socket.866builder # [ 1.733651] systemd[1]: Reached target Socket Units.867builder # [ 1.736575] systemd[1]: Starting Create List of Static Device Nodes...868builder # [ 1.744865] systemd[1]: Starting Load Kernel Module 9pnet_virtio...869builder # [ 1.746069] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs870builder # [ 1.758551] systemd[1]: Mounting Kernel Configuration File System...871server # [ 1.765461] systemd[1]: Queued start job for default target Initrd Default Target.872server # [ 1.777352] systemd[1]: Created slice Slice /system/modprobe.873server # [ 1.778793] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.874server # [ 1.780215] systemd[1]: Expecting device /dev/disk/by-label/nixos...875server # [ 1.781363] systemd[1]: Reached target Path Units.876server # [ 1.782323] systemd[1]: Reached target Slice Units.877server # [ 1.783317] systemd[1]: Reached target Swaps.878server # [ 1.784116] systemd[1]: Reached target Timer Units.879server # [ 1.785261] systemd[1]: Listening on D-Bus System Message Bus Socket.880builder # [ 1.781519] systemd[1]: Starting Journal Service...881server # [ 1.786676] systemd[1]: Listening on Journal Socket (/dev/log).882server # [ 1.786833] systemd[1]: Listening on Journal Sockets.883server # [ 1.786980] systemd[1]: Listening on udev Control Socket.884server # [ 1.787110] systemd[1]: Listening on udev Kernel Socket.885server # [ 1.787134] systemd[1]: Reached target Socket Units.886server # [ 1.793676] systemd[1]: Starting Create List of Static Device Nodes...887builder # [ 1.797405] systemd[1]: Starting Load Kernel Modules...888builder # [ 1.798321] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os889server # [ 1.801836] systemd[1]: Starting Load Kernel Module 9pnet_virtio...890server # [ 1.803114] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs891builder # [ 1.804506] systemd-journald[73]: Collecting audit messages is disabled.892server # [ 1.810235] systemd[1]: Mounting Kernel Configuration File System...893builder # [ 1.813451] systemd[1]: Starting Coldplug All udev Devices...894builder # [ 1.833524] systemd[1]: Finished Create List of Static Device Nodes.895builder # [ 1.834460] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.896builder # [ 1.834778] systemd[1]: Finished Load Kernel Module 9pnet_virtio.897builder # [ 1.835019] systemd[1]: Mounted Kernel Configuration File System.898server # [ 1.842001] systemd[1]: Starting Journal Service...899server # [ 1.848267] systemd[1]: Starting Load Kernel Modules...900server # [ 1.848467] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os901server # [ 1.857964] systemd[1]: Starting Coldplug All udev Devices...902builder # [ 1.853645] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...903server # [ 1.877795] systemd[1]: Finished Create List of Static Device Nodes.904server # [ 1.879039] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.905server # [ 1.879384] systemd[1]: Finished Load Kernel Module 9pnet_virtio.906server # [ 1.879643] systemd[1]: Mounted Kernel Configuration File System.907builder # [ 1.882058] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.908server # [ 1.894158] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...909builder # [ 1.897813] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.910builder # [ 1.900049] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev911builder # [ 1.902610] systemd[1]: Starting Create Static Device Nodes in /dev...912builder # [ 1.914516] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0913builder # [ 1.914755] [drm] features: -virgl +edid -resource_blob -host_visible914builder # [ 1.914766] [drm] features: -context_init915builder # [ 1.915463] [drm] number of scanouts: 1916builder # [ 1.915481] [drm] number of cap sets: 0917server # [ 1.921329] systemd-journald[73]: Collecting audit messages is disabled.918builder # [ 1.929601] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic919builder # [ 1.929624] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0920server # [ 1.944778] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.921server # [ 1.950310] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.922server # [ 1.953012] systemd[1]: Starting Create Static Device Nodes in /dev...923builder # [ 1.953751] systemd[1]: Finished Create Static Device Nodes in /dev.924builder # [ 1.954093] systemd[1]: Reached target Preparation for Local File Systems.925builder # [ 1.954122] systemd[1]: Reached target Local File Systems.926builder # [ 1.958279] systemd[1]: Starting Rule-based Manager for Device Events and Files...927server # [ 1.957434] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev928server # [ 1.963832] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0929server # [ 1.964071] [drm] features: -virgl +edid -resource_blob -host_visible930server # [ 1.964083] [drm] features: -context_init931server # [ 1.964822] [drm] number of scanouts: 1932server # [ 1.964844] [drm] number of cap sets: 0933builder # [ 1.969497] Console: switching to colour frame buffer device 160x50934builder # [ 1.994624] systemd[1]: Started Journal Service.935server # [ 1.998180] systemd[1]: Finished Create Static Device Nodes in /dev.936server # [ 1.998415] systemd[1]: Reached target Preparation for Local File Systems.937server # [ 1.998446] systemd[1]: Reached target Local File Systems.938builder # [ 1.997008] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device939server # [ 2.002708] systemd[1]: Starting Rule-based Manager for Device Events and Files...940builder # [ 1.987912] systemd-modules-load[75]: Inserted module 'dm_mod'941builder # [ 1.989342] systemd-modules-load[75]: Module 'virtio_balloon' is built in942builder # [ 1.990844] systemd-modules-load[75]: Module 'virtio_console' is built in943builder # [ 1.991930] systemd-modules-load[75]: Inserted module 'virtio_gpu'944server # [ 2.006944] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic945server # [ 2.006983] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0946builder # [ 2.009624] systemd-modules-load[75]: Module 'virtio_rng' is built in947builder # [ 2.010703] systemd[1]: Starting Create System Files and Directories...948builder # [ 2.011727] systemd[1]: Finished Load Kernel Modules.949server # [ 2.038067] Console: switching to colour frame buffer device 160x50950server # [ 2.044266] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device951builder # [ 2.045915] systemd[1]: Starting Apply Kernel Variables...952builder # [ 2.047623] systemd[1]: Finished Create System Files and Directories.953builder # [ 2.052812] systemd-udevd[79]: Using default interface naming scheme 'v261'.954server # [ 2.074335] systemd[1]: Finished Load Kernel Modules.955server # [ 2.081997] systemd[1]: Starting Apply Kernel Variables...956builder # [ 2.078564] systemd[1]: Finished Apply Kernel Variables.957builder # [ 2.092825] systemd[1]: Started Rule-based Manager for Device Events and Files.958server # [ 2.113525] systemd[1]: Started Journal Service.959server # [ 2.107432] systemd-modules-load[74]: Inserted module 'dm_mod'960server # [ 2.116467] systemd-modules-load[74]: Module 'virtio_balloon' is built in961server # [ 2.117651] systemd-modules-load[74]: Module 'virtio_console' is built in962server # [ 2.118725] systemd-modules-load[74]: Inserted module 'virtio_gpu'963server # [ 2.119740] systemd-modules-load[74]: Module 'virtio_rng' is built in964server # [ 2.125909] systemd-udevd[79]: Using default interface naming scheme 'v261'.965server # [ 2.136367] systemd[1]: Starting Create System Files and Directories...966server # [ 2.141549] systemd[1]: Finished Apply Kernel Variables.967builder # [ 2.139551] systemd[1]: Starting Virtual Console Setup...968server # [ 2.154766] systemd[1]: Finished Create System Files and Directories.969server # [ 2.170789] systemd[1]: Started Rule-based Manager for Device Events and Files.970builder # [ 2.193942] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.971builder # [ 2.195666] systemd[1]: Finished Virtual Console Setup.972server # [ 2.225259] systemd[1]: Starting Virtual Console Setup...973server # [ 2.272470] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.974server # [ 2.275751] systemd[1]: Finished Virtual Console Setup.975builder # [ 2.787543] systemd[1]: Finished Coldplug All udev Devices.976builder # [ 2.788615] systemd[1]: Reached target System Initialization.977builder # [ 2.789431] systemd[1]: Reached target Basic System.978server # [ 2.837849] systemd[1]: Finished Coldplug All udev Devices.979server # [ 2.844181] systemd[1]: Reached target System Initialization.980server # [ 2.845079] systemd[1]: Reached target Basic System.981builder # [ 2.916777] (udev-worker)[103]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.982builder # [ 2.921391] (udev-worker)[103]: Network interface NamePolicy= disabled on kernel command line.983builder # [ 2.960610] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.984server # [ 2.989350] (udev-worker)[102]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.985server # [ 3.000385] (udev-worker)[102]: Network interface NamePolicy= disabled on kernel command line.986server # [ 3.011375] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.987builder # [ 3.025944] systemd[1]: Found device /dev/disk/by-label/nixos.988builder # [ 3.028639] systemd[1]: Reached target Initrd Root Device.989builder # [ 3.032515] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...990server # [ 3.072754] systemd[1]: Found device /dev/disk/by-label/nixos.991server # [ 3.076115] systemd[1]: Reached target Initrd Root Device.992server # [ 3.080700] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...993builder # [ 3.084637] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks994builder # [ 3.090202] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.995builder # [ 3.096397] systemd[1]: Mounting /sysroot...996server # [ 3.130206] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks997server # [ 3.138173] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.998builder # [ 3.151811] EXT4-fs (vda): mounted filesystem ba9e0d27-5c71-4fdb-83e6-bb74f6c36cf3 r/w with ordered data mode. Quota mode: none.999server # [ 3.145303] systemd[1]: Mounting /sysroot...1000builder # [ 3.142847] systemd[1]: Mounted /sysroot.1001builder # [ 3.144463] systemd[1]: Reached target Initrd Root File System.1002builder # [ 3.147360] systemd[1]: Starting Mountpoints Configured in the Real Root...1003builder # [ 3.171875] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.1004server # [ 3.194247] EXT4-fs (vda): mounted filesystem cb199e25-24cb-40ac-91fe-ff508551eedf r/w with ordered data mode. Quota mode: none.1005builder # [ 3.178261] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...1006server # [ 3.181783] systemd[1]: Mounted /sysroot.1007builder # [ 3.179767] systemd[1]: Reloading...1008server # [ 3.182868] systemd[1]: Reached target Initrd Root File System.1009server # [ 3.186393] systemd[1]: Starting Mountpoints Configured in the Real Root...1010server # [ 3.211569] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.1011server # [ 3.217069] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...1012server # [ 3.222031] systemd[1]: Reloading...1013builder # [ 3.387538] systemd[1]: Reloading finished in 208 ms.1014builder # [ 3.423316] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...1015builder # [ 3.428248] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...1016server # [ 3.432143] systemd[1]: Reloading finished in 207 ms.1017builder # [ 3.431093] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1018builder # [ 3.440643] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1019builder # [ 3.454483] systemd[1]: Finished Mountpoints Configured in the Real Root.1020builder # [ 3.455555] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1021server # [ 3.461433] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...1022builder # [ 3.460712] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1023builder # [ 3.463284] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1024server # [ 3.467649] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...1025server # [ 3.472989] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1026server # [ 3.476650] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1027server # [ 3.480112] systemd[1]: Finished Mountpoints Configured in the Real Root.1028server # [ 3.481895] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1029server # [ 3.507187] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1030server # [ 3.508884] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1031builder # [ 3.813324] systemd[1]: Mounting /sysroot/nix/.ro-store...1032builder # [ 3.830713] systemd[1]: Mounting /sysroot/nix/.rw-store...1033builder # [ 3.836124] systemd[1]: Mounting /sysroot/run...1034builder # [ 3.861143] systemd[1]: Mounting /sysroot/tmp/shared...1035server # [ 3.863951] systemd[1]: Mounting /sysroot/nix/.ro-store...1036server # [ 3.869486] systemd[1]: Mounting /sysroot/nix/.rw-store...1037server # [ 3.879634] systemd[1]: Mounting /sysroot/run...1038builder # [ 3.878980] systemd[1]: Mounting /sysroot/tmp/xchg...1039server # [ 3.891406] systemd[1]: Mounting /sysroot/tmp/shared...1040builder # [ 3.899285] systemd[1]: Mounted /sysroot/nix/.rw-store.1041server # [ 3.906939] systemd[1]: Mounting /sysroot/tmp/xchg...1042builder # [ 3.927194] systemd[1]: Starting rw-sysroot-nix-store.service...1043builder # [ 3.939861] systemd[1]: Mounted /sysroot/nix/.ro-store.1044builder # [ 3.942823] systemd[1]: Mounted /sysroot/run.1045server # [ 3.950013] systemd[1]: Mounted /sysroot/nix/.ro-store.1046builder # [ 3.949561] systemd[1]: Mounted /sysroot/tmp/shared.1047builder # [ 3.958422] systemd[1]: Mounted /sysroot/tmp/xchg.1048server # [ 3.962649] systemd[1]: Mounted /sysroot/nix/.rw-store.1049server # [ 3.968405] systemd[1]: Mounted /sysroot/run.1050builder # [ 3.967492] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1051builder # [ 3.970825] systemd[1]: Finished rw-sysroot-nix-store.service.1052builder # [ 3.976864] systemd[1]: Mounting /sysroot/nix/store...1053server # [ 3.979980] systemd[1]: Mounted /sysroot/tmp/shared.1054server # [ 3.984889] systemd[1]: Mounted /sysroot/tmp/xchg.1055server # [ 3.992182] systemd[1]: Starting rw-sysroot-nix-store.service...1056server # [ 4.014995] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1057server # [ 4.016599] systemd[1]: Finished rw-sysroot-nix-store.service.1058server # [ 4.020407] systemd[1]: Mounting /sysroot/nix/store...1059builder # [ 4.020929] systemd[1]: Mounted /sysroot/nix/store.1060builder # [ 4.022787] systemd[1]: Reached target Initrd File Systems.1061builder # [ 4.025030] systemd[1]: Starting Find NixOS closure...1062builder # [ 4.035958] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1063server # [ 4.062390] systemd[1]: Mounted /sysroot/nix/store.1064server # [ 4.064074] systemd[1]: Reached target Initrd File Systems.1065server # [ 4.065552] systemd[1]: Starting Find NixOS closure...1066builder # [ 4.064070] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1067server # [ 4.076462] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1068builder # [ 4.082902] systemd[1]: Finished Find NixOS closure.1069builder # [ 4.083754] systemd[1]: Reached target Initrd Default Target.1070builder # [ 4.085977] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1071server # [ 4.100521] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1072builder # [ 4.113816] systemd[1]: Stopped target Initrd Default Target.1073builder # [ 4.116095] systemd[1]: Stopped target Basic System.1074builder # [ 4.117068] systemd[1]: Stopped target Initrd Root Device.1075builder # [ 4.118019] systemd[1]: Stopped target Path Units.1076builder # [ 4.120089] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1077server # [ 4.124133] systemd[1]: Finished Find NixOS closure.1078server # [ 4.125000] systemd[1]: Reached target Initrd Default Target.1079builder # [ 4.124926] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1080server # [ 4.129289] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1081builder # [ 4.127041] systemd[1]: Stopped target Slice Units.1082builder # [ 4.130045] systemd[1]: Stopped target Socket Units.1083builder # [ 4.131834] systemd[1]: Stopped target System Initialization.1084builder # [ 4.137123] systemd[1]: Stopped target Swaps.1085builder # [ 4.137847] systemd[1]: Stopped target Timer Units.1086builder # [ 4.138544] systemd[1]: dbus.socket: Deactivated successfully.1087builder # [ 4.139362] systemd[1]: Closed D-Bus System Message Bus Socket.1088builder # [ 4.144824] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1089builder # [ 4.153577] systemd[1]: Stopped Find NixOS closure.1090builder # [ 4.156327] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1091server # [ 4.160156] systemd[1]: Stopped target Initrd Default Target.1092builder # [ 4.160243] systemd[1]: Starting rw-sysroot-nix-store.service...1093server # [ 4.163362] systemd[1]: Stopped target Basic System.1094server # [ 4.167392] systemd[1]: Stopped target Initrd Root Device.1095builder # [ 4.165435] systemd[1]: systemd-sysctl.service: Deactivated successfully.1096server # [ 4.169386] systemd[1]: Stopped target Path Units.1097builder # [ 4.167018] systemd[1]: Stopped Apply Kernel Variables.1098server # [ 4.171036] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1099builder # [ 4.169574] systemd[1]: systemd-modules-load.service: Deactivated successfully.1100server # [ 4.173274] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1101builder # [ 4.173874] systemd[1]: Stopped Load Kernel Modules.1102server # [ 4.177049] systemd[1]: Stopped target Slice Units.1103builder # [ 4.175638] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1104server # [ 4.179539] systemd[1]: Stopped target Socket Units.1105builder # [ 4.178667] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1106server # [ 4.184405] systemd[1]: Stopped target System Initialization.1107server # [ 4.185256] systemd[1]: Stopped target Swaps.1108server # [ 4.185886] systemd[1]: Stopped target Timer Units.1109server # [ 4.186571] systemd[1]: dbus.socket: Deactivated successfully.1110server # [ 4.187416] systemd[1]: Closed D-Bus System Message Bus Socket.1111builder # [ 4.186765] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1112builder # [ 4.190199] systemd[1]: Stopped Create System Files and Directories.1113builder # [ 4.193929] systemd[1]: Stopped target Local File Systems.1114server # [ 4.199437] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1115builder # [ 4.197765] systemd[1]: Stopped target Preparation for Local File Systems.1116builder # [ 4.200509] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1117server # [ 4.204337] systemd[1]: Stopped Find NixOS closure.1118builder # [ 4.202339] systemd[1]: Stopped Coldplug All udev Devices.1119builder # [ 4.204507] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1120builder # [ 4.206840] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1121server # [ 4.209809] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1122builder # [ 4.209220] systemd[1]: Stopped Virtual Console Setup.1123builder # [ 4.210054] systemd[1]: initrd-cleanup.service: Deactivated successfully.1124builder # [ 4.210988] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1125builder # [ 4.211916] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1126server # [ 4.215554] systemd[1]: Starting rw-sysroot-nix-store.service...1127builder # [ 4.213165] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1128builder # [ 4.214028] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1129builder # [ 4.215020] systemd[1]: Finished rw-sysroot-nix-store.service.1130server # [ 4.219747] systemd[1]: systemd-sysctl.service: Deactivated successfully.1131builder # [ 4.218629] systemd[1]: systemd-udevd.service: Deactivated successfully.1132server # [ 4.222695] systemd[1]: Stopped Apply Kernel Variables.1133builder # [ 4.221135] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1134builder # [ 4.222208] systemd[1]: systemd-udevd.service: Consumed 1.123s CPU time over 2.277s wall clock time, 17.8M memory peak.1135builder # [ 4.223615] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1136server # [ 4.226495] systemd[1]: systemd-modules-load.service: Deactivated successfully.1137builder # [ 4.228566] systemd[1]: Closed udev Control Socket.1138server # [ 4.231533] systemd[1]: Stopped Load Kernel Modules.1139builder # [ 4.230025] systemd[1]: Starting Cleanup udev Database...1140builder # [ 4.230941] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1141server # [ 4.233933] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1142builder # [ 4.232083] systemd[1]: Stopped Create Static Device Nodes in /dev.1143builder # [ 4.232979] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1144builder # [ 4.234093] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1145builder # [ 4.235074] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1146server # [ 4.238247] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1147server # [ 4.241957] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1148builder # [ 4.239978] systemd[1]: Stopped Create List of Static Device Nodes.1149server # [ 4.243678] systemd[1]: Stopped Create System Files and Directories.1150server # [ 4.248295] systemd[1]: Stopped target Local File Systems.1151server # [ 4.249116] systemd[1]: Stopped target Preparation for Local File Systems.1152server # [ 4.250059] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1153server # [ 4.251095] systemd[1]: Stopped Coldplug All udev Devices.1154server # [ 4.251858] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1155server # [ 4.260308] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1156server # [ 4.262322] systemd[1]: Stopped Virtual Console Setup.1157server # [ 4.263177] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1158server # [ 4.265745] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1159server # [ 4.266594] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1160server # [ 4.267583] systemd[1]: Finished rw-sysroot-nix-store.service.1161builder # [ 4.269044] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1162server # [ 4.272282] systemd[1]: initrd-cleanup.service: Deactivated successfully.1163builder # [ 4.271370] systemd[1]: Finished Cleanup udev Database.1164builder # [ 4.272631] systemd[1]: Reached target Switch Root.1165server # [ 4.276148] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1166server # [ 4.277366] systemd[1]: systemd-udevd.service: Deactivated successfully.1167builder # [ 4.276131] systemd[1]: Starting NixOS Activation...1168server # [ 4.280189] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1169server # [ 4.281219] systemd[1]: systemd-udevd.service: Consumed 1.109s CPU time over 2.270s wall clock time, 18.1M memory peak.1170server # [ 4.284127] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1171server # [ 4.288223] systemd[1]: Closed udev Control Socket.1172server # [ 4.288943] systemd[1]: Starting Cleanup udev Database...1173server # [ 4.289720] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1174server # [ 4.290778] systemd[1]: Stopped Create Static Device Nodes in /dev.1175server # [ 4.296325] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1176server # [ 4.297496] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1177server # [ 4.298482] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1178server # [ 4.299454] systemd[1]: Stopped Create List of Static Device Nodes.1179server # [ 4.314332] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1180server # [ 4.317381] systemd[1]: Finished Cleanup udev Database.1181server # [ 4.320353] systemd[1]: Reached target Switch Root.1182server # [ 4.321129] systemd[1]: Starting NixOS Activation...1183builder # [ 4.456457] initrd-nixos-activation-start[172]: booting system configuration /nix/store/ghs2rkvqvf5isixsi658wqd4xnxccx1i-nixos-system-builder-test1184server # [ 4.479026] initrd-nixos-activation-start[172]: booting system configuration /nix/store/1vzs4qrnpfk7rzw0pfhrpk16i4r7mq7a-nixos-system-server-test1185builder # [ 4.527916] initrd-nixos-activation-start[172]: running activation script...1186server # [ 4.541978] initrd-nixos-activation-start[172]: running activation script...1187builder # [ 4.976706] initrd-nixos-activation-start[195]: setting up /etc...1188server # [ 4.979858] initrd-nixos-activation-start[195]: setting up /etc...1189builder # [ 5.244391] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1190server # [ 5.247366] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1191builder # [ 5.247223] systemd[1]: Finished NixOS Activation.1192server # [ 5.250249] systemd[1]: Finished NixOS Activation.1193server # [ 5.252124] systemd[1]: Starting Switch Root...1194builder # [ 5.252582] systemd[1]: Starting Switch Root...1195builder # [ 5.271604] systemd[1]: Switching root.1196server # [ 5.275725] systemd[1]: Switching root.1197server # [ 5.460775] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1198builder # [ 5.460500] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1199builder # [ 6.064479] systemd[1]: systemd 261.1 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)1200builder # [ 6.070345] systemd[1]: Detected virtualization qemu.1201builder # [ 6.072243] systemd[1]: Detected architecture arm64.1202builder # [ 6.076439] systemd[1]: Detected first boot.1203server # [ 6.070275] systemd[1]: systemd 261.1 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 # [ 6.082892] systemd[1]: Detected virtualization qemu.1205server # [ 6.086105] systemd[1]: Detected architecture arm64.1206server # [ 6.090307] systemd[1]: Detected first boot.1207builder # [ 6.085671] systemd[1]: Initializing machine ID from random generator.1208server # [ 6.098638] systemd[1]: Initializing machine ID from random generator.1209builder # [ 6.454730] systemd[1]: bpf-restrict-fs: LSM BPF program attached1210server # [ 6.479636] systemd[1]: bpf-restrict-fs: LSM BPF program attached1211builder # [ 6.680577] systemd[1]: Applying preset policy.1212server # [ 6.719450] systemd[1]: Applying preset policy.1213builder # [ 7.222949] systemd[1]: Populated /etc with preset unit settings.1214server # [ 7.278345] systemd[1]: Populated /etc with preset unit settings.1215builder # [ 7.791492] systemd[1]: initrd-switch-root.service: Deactivated successfully.1216builder # [ 7.792914] systemd[1]: Stopped initrd-switch-root.service.1217builder # [ 7.797878] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1218builder # [ 7.802771] systemd[1]: Created slice Slice /system/getty.1219builder # [ 7.805871] systemd[1]: Created slice User and Session Slice.1220builder # [ 7.807886] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1221builder # [ 7.810320] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1222builder # [ 7.812435] systemd[1]: Expecting device /dev/hvc0...1223builder # [ 7.814317] systemd[1]: Expecting device /dev/ttyAMA0...1224builder # [ 7.816107] systemd[1]: Reached target Local Encrypted Volumes.1225builder # [ 7.818105] systemd[1]: Stopped target initrd-fs.target.1226builder # [ 7.820611] systemd[1]: Stopped target initrd-root-fs.target.1227builder # [ 7.822843] systemd[1]: Stopped target initrd-switch-root.target.1228builder # [ 7.825008] systemd[1]: Reached target Virtual Machines and Containers.1229builder # [ 7.827287] systemd[1]: Reached target Path Units.1230builder # [ 7.829282] systemd[1]: Reached target Remote File Systems.1231builder # [ 7.831491] systemd[1]: Reached target Slice Units.1232builder # [ 7.833466] systemd[1]: Reached target Swaps.1233builder # [ 7.838015] systemd[1]: Listening on Query the User Interactively for a Password.1234builder # [ 7.843437] systemd[1]: Listening on Process Core Dump Socket.1235builder # [ 7.847974] systemd[1]: Listening on Credential Encryption/Decryption.1236builder # [ 7.852281] systemd[1]: Listening on Factory Reset Management.1237builder # [ 7.852782] systemd[1]: Listening on Hostname Service Socket.1238builder # [ 7.859915] systemd[1]: Starting Journal Log Access Socket...1239builder # [ 7.862628] systemd[1]: Listening on Journal Audit Socket.1240builder # [ 7.867170] systemd[1]: Listening on Console Output Muting Service Socket.1241server # [ 7.870324] systemd[1]: initrd-switch-root.service: Deactivated successfully.1242builder # [ 7.868852] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1243server # [ 7.872988] systemd[1]: Stopped initrd-switch-root.service.1244builder # [ 7.870543] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1245server # [ 7.877112] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1246builder # [ 7.873763] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1247server # [ 7.881366] systemd[1]: Created slice Slice /system/getty.1248server # [ 7.884548] systemd[1]: Created slice User and Session Slice.1249server # [ 7.886886] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1250server # [ 7.889314] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1251server # [ 7.891452] systemd[1]: Expecting device /dev/hvc0...1252builder # [ 7.886751] systemd[1]: Listening on Disk Repartitioning Service Socket.1253builder # [ 7.887254] systemd[1]: Listening on udev Control Socket.1254builder # [ 7.887781] systemd[1]: Listening on udev Varlink Socket.1255server # [ 7.893187] systemd[1]: Expecting device /dev/ttyAMA0...1256server # [ 7.895967] systemd[1]: Reached target Local Encrypted Volumes.1257builder # [ 7.893957] systemd[1]: Mounting Huge Pages File System...1258server # [ 7.898139] systemd[1]: Stopped target initrd-fs.target.1259server # [ 7.899253] systemd[1]: Stopped target initrd-root-fs.target.1260server # [ 7.900900] systemd[1]: Stopped target initrd-switch-root.target.1261builder # [ 7.900105] systemd[1]: Mounting POSIX Message Queue File System...1262server # [ 7.903415] systemd[1]: Reached target Virtual Machines and Containers.1263server # [ 7.905634] systemd[1]: Reached target Path Units.1264server # [ 7.907560] systemd[1]: Reached target Remote File Systems.1265server # [ 7.909567] systemd[1]: Reached target Slice Units.1266server # [ 7.911638] systemd[1]: Reached target Swaps.1267server # [ 7.916030] systemd[1]: Listening on Query the User Interactively for a Password.1268builder # [ 7.913963] systemd[1]: Mounting Kernel Debug File System...1269server # [ 7.921543] systemd[1]: Listening on Process Core Dump Socket.1270server # [ 7.926002] systemd[1]: Listening on Credential Encryption/Decryption.1271builder # [ 7.926571] systemd[1]: Mounting Kernel Trace File System...1272server # [ 7.930316] systemd[1]: Listening on Factory Reset Management.1273server # [ 7.932511] systemd[1]: Listening on Hostname Service Socket.1274server # [ 7.939069] systemd[1]: Starting Journal Log Access Socket...1275server # [ 7.941721] systemd[1]: Listening on Journal Audit Socket.1276server # [ 7.946489] systemd[1]: Listening on Console Output Muting Service Socket.1277builder # [ 7.943741] systemd[1]: Starting Create List of Static Device Nodes...1278server # [ 7.948490] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1279server # [ 7.950286] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1280server # [ 7.952374] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1281server # [ 7.966132] systemd[1]: Listening on Disk Repartitioning Service Socket.1282builder # [ 7.963606] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1283server # [ 7.967612] systemd[1]: Listening on udev Control Socket.1284server # [ 7.969251] systemd[1]: Listening on udev Varlink Socket.1285builder # [ 7.966719] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1286server # [ 7.974167] systemd[1]: Mounting Huge Pages File System...1287server # [ 7.979579] systemd[1]: Mounting POSIX Message Queue File System...1288builder # [ 7.978738] systemd[1]: Mounting Kernel Configuration File System...1289builder # [ 7.981087] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1290builder # [ 7.984134] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1291server # [ 7.989520] systemd[1]: Mounting Kernel Debug File System...1292server # [ 8.000300] systemd[1]: Mounting Kernel Trace File System...1293server # [ 8.016922] systemd[1]: Starting Create List of Static Device Nodes...1294builder # [ 8.017974] systemd[1]: Starting Load Kernel Module fuse...1295builder # [ 8.018454] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671296server # [ 8.031265] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1297server # [ 8.033517] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1298server # [ 8.042754] systemd[1]: Mounting Kernel Configuration File System...1299server # [ 8.045110] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1300server # [ 8.050635] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1301builder # [ 8.071675] systemd[1]: Starting Journal Service...1302server # [ 8.075304] systemd[1]: Starting Load Kernel Module fuse...1303server # [ 8.082567] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671304builder # [ 8.099786] systemd[1]: Starting Load Kernel Modules...1305builder # [ 8.124580] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1306builder # [ 8.141850] systemd[1]: Starting Remount Root and Kernel File Systems...1307builder # [ 8.144600] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1308server # [ 8.158531] systemd[1]: Starting Journal Service...1309builder # [ 8.158951] systemd[1]: Starting Coldplug All udev Devices...1310builder # [ 8.164660] systemd[1]: Listening on Journal Log Access Socket.1311server # [ 8.177475] systemd[1]: Starting Load Kernel Modules...1312builder # [ 8.173567] systemd[1]: Mounted Huge Pages File System.1313builder # [ 8.174158] systemd[1]: Mounted POSIX Message Queue File System.1314builder # [ 8.174692] systemd[1]: Mounted Kernel Debug File System.1315builder # [ 8.175151] systemd[1]: Mounted Kernel Trace File System.1316builder # [ 8.175669] systemd[1]: Mounted Kernel Configuration File System.1317server # [ 8.201737] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1318server # [ 8.208942] systemd[1]: Starting Remount Root and Kernel File Systems...1319builder # [ 8.206428] systemd[1]: Finished Create List of Static Device Nodes.1320server # [ 8.211403] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1321builder # [ 8.212919] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1322server # [ 8.227685] systemd[1]: Starting Coldplug All udev Devices...1323server # [ 8.233557] systemd[1]: Listening on Journal Log Access Socket.1324server # [ 8.239228] systemd[1]: Mounted Huge Pages File System.1325server # [ 8.241437] systemd[1]: Mounted POSIX Message Queue File System.1326server # [ 8.245875] systemd[1]: Mounted Kernel Debug File System.1327server # [ 8.248052] systemd[1]: Mounted Kernel Trace File System.1328server # [ 8.248673] systemd[1]: Mounted Kernel Configuration File System.1329builder # [ 8.301551] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1330server # [ 8.305036] systemd[1]: Finished Create List of Static Device Nodes.1331builder # [ 8.304491] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1332server # [ 8.315530] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1333builder # [ 8.312720] systemd[1]: Finished Load Kernel Modules.1334builder # [ 8.319065] systemd[1]: Starting Firewall...1335builder # [ 8.329334] systemd[1]: Starting Apply Kernel Variables...1336builder # [ 8.361317] EXT4-fs (vda): re-mounted ba9e0d27-5c71-4fdb-83e6-bb74f6c36cf3.1337builder # [ 8.377620] systemd-journald[267]: Collecting audit messages is enabled.1338server # [ 8.385019] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1339server # [ 8.388064] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1340builder # [ 8.385630] systemd[1]: Finished Remount Root and Kernel File Systems.1341builder # [ 8.386401] systemd[1]: Listening on Disk Image Download Service Socket.1342builder # [ 8.386786] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1343builder # [ 8.409831] systemd[1]: Starting Load/Save OS Random Seed...1344builder # [ 8.412196] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1345server # [ 8.417792] EXT4-fs (vda): re-mounted cb199e25-24cb-40ac-91fe-ff508551eedf.1346builder # [ 8.416434] systemd[1]: Started Journal Service.1347server # [ 8.428574] systemd[1]: Finished Remount Root and Kernel File Systems.1348server # [ 8.431333] systemd[1]: Listening on Disk Image Download Service Socket.1349builder # [ 8.411684] systemd[1]: Queued start job for default target Multi-User System.1350builder # [ 8.414914] systemd[1]: systemd-journald.service: Deactivated successfully.1351server # [ 8.433754] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1352server # [ 8.441814] systemd[1]: Starting Load/Save OS Random Seed...1353server # [ 8.443919] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1354builder # [ 8.428740] systemd-modules-load[268]: Module 'atkbd' is built in1355builder # [ 8.445944] systemd-modules-load[268]: Module 'loop' is built in1356builder # [ 8.470397] fuse: init (API version 7.45)1357builder # [ 8.458616] systemd-modules-load[268]: Module 'tun' is built in1358server # [ 8.488584] systemd-journald[267]: Collecting audit messages is enabled.1359server # [ 8.498788] fuse: init (API version 7.45)1360builder # [ 8.470740] systemd[1]: Starting Flush Journal to Persistent Storage...1361builder # [ 8.492916] systemd[1]: modprobe@fuse.service: Deactivated successfully.1362server # [ 8.513600] systemd[1]: Finished Load Kernel Modules.1363server # [ 8.520776] systemd[1]: Starting Firewall...1364builder # [ 8.503237] systemd[1]: Finished Load Kernel Module fuse.1365server # [ 8.512663] systemd[1]: Queued start job for default target Multi-User System.1366server # [ 8.516491] systemd[1]: systemd-journald.service: Deactivated successfully.1367server # [ 8.536089] systemd[1]: Starting Apply Kernel Variables...1368server # [ 8.539380] systemd[1]: Started Journal Service.1369server # [ 8.537485] systemd-modules-load[268]: Module 'atkbd' is built in1370server # [ 8.550571] systemd-modules-load[268]: Module 'loop' is built in1371builder # [ 8.549148] systemd[1]: Finished Load/Save OS Random Seed.1372builder # [ 8.555078] systemd[1]: Reached target First Boot Complete.1373server # [ 8.566310] systemd-modules-load[268]: Inserted module 'tls'1374server # [ 8.571442] systemd-modules-load[268]: Module 'tun' is built in1375builder # [ 8.571661] systemd-oomd[269]: No swap; memory pressure usage will be degraded1376server # [ 8.586460] systemd[1]: modprobe@fuse.service: Deactivated successfully.1377builder # [ 8.602756] systemd-journald[267]: Received client request to flush runtime journal.1378server # [ 8.607371] systemd[1]: Finished Load Kernel Module fuse.1379server # [ 8.616897] systemd[1]: Starting Flush Journal to Persistent Storage...1380server # [ 8.634293] systemd-oomd[269]: No swap; memory pressure usage will be degraded1381server # [ 8.646678] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1382server # [ 8.658097] systemd[1]: Finished Load/Save OS Random Seed.1383server # [ 8.665058] systemd[1]: Reached target First Boot Complete.1384builder # [ 8.671036] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1385server # [ 8.697521] systemd-journald[267]: Received client request to flush runtime journal.1386builder # [ 8.685967] systemd[1]: Finished Apply Kernel Variables.1387builder # [ 8.688478] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1388builder # [ 8.700454] systemd[1]: Starting Create Static Device Nodes in /dev...1389builder # [ 8.702238] systemd[1]: Finished Flush Journal to Persistent Storage.1390server # [ 8.782415] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1391builder # [ 8.779920] systemd[1]: Mounting FUSE Control File System...1392server # [ 8.789246] systemd[1]: Starting Create Static Device Nodes in /dev...1393server # [ 8.793693] systemd[1]: Finished Apply Kernel Variables.1394server # [ 8.799621] systemd[1]: Finished Flush Journal to Persistent Storage.1395builder # [ 8.809854] systemd[1]: Finished Create Static Device Nodes in /dev.1396builder # [ 8.816750] systemd[1]: Reached target Preparation for Local File Systems.1397builder # [ 8.826985] systemd[1]: Mounting /run/wrappers...1398builder # [ 8.836884] systemd[1]: Starting Rule-based Manager for Device Events and Files...1399builder # [ 8.841375] systemd[1]: Mounted FUSE Control File System.1400server # [ 8.861969] systemd[1]: Mounting FUSE Control File System...1401server # [ 8.916403] systemd[1]: Mounted FUSE Control File System.1402builder # [ 8.918815] systemd[1]: Mounted /run/wrappers.1403server # [ 8.922455] systemd[1]: Finished Create Static Device Nodes in /dev.1404server # [ 8.928867] systemd[1]: Reached target Preparation for Local File Systems.1405server # [ 8.932925] systemd[1]: Mounting /run/wrappers...1406builder # [ 8.932485] systemd[1]: Reached target Local File Systems.1407builder # [ 8.933746] systemd[1]: Listening on Boot Loader Control Service Socket.1408builder # [ 8.937005] systemd[1]: Starting register-nix-paths.service...1409server # [ 8.940233] systemd[1]: Starting Rule-based Manager for Device Events and Files...1410builder # [ 8.948275] systemd[1]: Starting Create SUID/SGID Wrappers...1411builder # [ 8.956452] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1412builder # [ 8.964896] systemd[1]: Starting Save Transient machine-id to Disk...1413builder # [ 8.976443] systemd[1]: Starting Create System Files and Directories...1414server # [ 9.011597] systemd[1]: Mounted /run/wrappers.1415server # [ 9.015046] systemd[1]: Reached target Local File Systems.1416server # [ 9.021788] systemd[1]: Listening on Boot Loader Control Service Socket.1417server # [ 9.032620] systemd[1]: Starting register-nix-paths.service...1418server # [ 9.044804] systemd[1]: Starting Create SUID/SGID Wrappers...1419server # [ 9.051020] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1420server # [ 9.065804] systemd[1]: Starting Save Transient machine-id to Disk...1421server # [ 9.078156] systemd[1]: Starting Create System Files and Directories...1422builder # [ 9.102860] systemd[1]: Finished Save Transient machine-id to Disk.1423server # [ 9.185561] systemd[1]: Finished Save Transient machine-id to Disk.1424builder # [ 9.285182] systemd[1]: Finished Create System Files and Directories.1425builder # [ 9.292775] systemd[1]: Starting Rebuild Journal Catalog...1426builder # [ 9.305124] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1427builder # [ 9.355185] systemd-udevd[309]: Using default interface naming scheme 'v261'.1428server # [ 9.386701] systemd[1]: Finished Create System Files and Directories.1429server # [ 9.396428] systemd[1]: Starting Rebuild Journal Catalog...1430server # [ 9.407028] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1431server # [ 9.425618] systemd-udevd[308]: Using default interface naming scheme 'v261'.1432builder # [ 9.431832] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1433server # [ 9.516872] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1434builder # [ 9.527575] systemd[1]: Finished Rebuild Journal Catalog.1435builder # [ 9.533123] systemd[1]: Starting Update is Completed...1436server # [ 9.608192] systemd[1]: Finished Rebuild Journal Catalog.1437server # [ 9.613361] systemd[1]: Starting Update is Completed...1438builder # [ 9.642565] systemd[1]: Finished Update is Completed.1439builder # [ 9.704312] systemd[1]: Started Rule-based Manager for Device Events and Files.1440server # [ 9.725399] systemd[1]: Finished Update is Completed.1441server # [ 9.761746] systemd[1]: Started Rule-based Manager for Device Events and Files.1442builder # [ 9.774546] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1443server # [ 9.853281] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1444builder # [ 10.222308] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1445builder # [ 10.227864] systemd[1]: Finished Create SUID/SGID Wrappers.1446server # [ 10.248155] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1447server # [ 10.251437] systemd[1]: Finished Create SUID/SGID Wrappers.1448builder # [ 10.442178] systemd[1]: Finished register-nix-paths.service.1449server # [ 10.529065] systemd[1]: Finished register-nix-paths.service.1450builder # [ 10.602288] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31451server # [ 10.619105] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31452builder # [ 10.709712] systemd[1]: Finished Firewall.1453builder # [ 10.960746] systemd[1]: Finished Coldplug All udev Devices.1454builder # [ 10.968889] systemd[1]: Reached target System Initialization.1455builder # [ 10.970060] systemd[1]: Started Discard unused filesystem blocks once a week.1456builder # [ 10.976720] systemd[1]: Started Daily Cleanup of Temporary Directories.1457builder # [ 10.977962] systemd[1]: Reached target Timer Units.1458builder # [ 10.979525] systemd[1]: Listening on D-Bus System Message Bus Socket.1459builder # [ 10.983330] systemd[1]: Starting niks3 auto-upload socket...1460builder # [ 10.992717] systemd[1]: Listening on Nix Daemon Socket.1461builder # [ 10.993527] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1462builder # [ 10.994801] systemd[1]: Listening on niks3 auto-upload socket.1463builder # [ 10.995827] systemd[1]: Reached target Socket Units.1464builder # [ 10.999132] systemd[1]: Starting D-Bus System Message Bus...1465server # [ 11.008418] systemd[1]: Finished Firewall.1466server # [ 11.052270] systemd[1]: Finished Coldplug All udev Devices.1467server # [ 11.053275] systemd[1]: Reached target System Initialization.1468server # [ 11.054114] systemd[1]: Started Discard unused filesystem blocks once a week.1469server # [ 11.055124] systemd[1]: Started niks3 garbage collection timer.1470server # [ 11.056152] systemd[1]: Started Daily Cleanup of Temporary Directories.1471server # [ 11.061313] systemd[1]: Reached target Timer Units.1472server # [ 11.062490] systemd[1]: Listening on D-Bus System Message Bus Socket.1473server # [ 11.063535] systemd[1]: Listening on niks3 server socket.1474server # [ 11.072117] systemd[1]: Listening on Nix Daemon Socket.1475server # [ 11.073592] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1476server # [ 11.079047] systemd[1]: Reached target Socket Units.1477server # [ 11.084251] systemd[1]: Reached target Basic System.1478server # [ 11.090530] systemd[1]: Starting Import lastlog data into lastlog2 database...1479server # [ 11.091860] systemd[1]: Starting Generate test mTLS certs...1480server # [ 11.098915] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1481server # [ 11.111519] systemd[1]: Starting Post-Boot Actions...1482server # [ 11.116230] systemd[1]: Started Reset console on configuration changes.1483server # [ 11.125017] systemd[1]: Starting resolvconf update...1484builder # [ 11.125507] dbus-broker-launch[486]: Looking up NSS user entry for 'systemd-timesync'...1485server # [ 11.137485] systemd[1]: Starting D-Bus System Message Bus...1486builder # [ 11.136425] dbus-broker-launch[486]: NSS returned no entry for 'systemd-timesync'1487builder # [ 11.138671] dbus-broker-launch[486]: Invalid user-name in /nix/store/b0qv2wg71ka74bk485shrkpl565n4ac7-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1488builder # [ 11.168114] systemd[1]: Started D-Bus System Message Bus.1489builder # [ 11.203170] dbus-broker-launch[486]: Ready1490builder # [ 11.204195] systemd[1]: Reached target Basic System.1491builder # [ 11.208104] systemd[1]: Starting Import lastlog data into lastlog2 database...1492builder # [ 11.215357] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1493builder # [ 11.226425] systemd[1]: Starting Post-Boot Actions...1494builder # [ 11.234256] systemd[1]: Started Reset console on configuration changes.1495builder # [ 11.248455] systemd[1]: Starting resolvconf update...1496server # [ 11.253636] systemd[1]: Finished Post-Boot Actions.1497server # [ 11.285920] systemd[1]: Started Name Service Cache Daemon (nsncd).1498server # [ 11.296336] nsncd[499]: Aug 27 08:41:42.901 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1499server # [ 11.304350] systemd[1]: Reached target Host and Network Name Lookups.1500server # [ 11.313984] systemd[1]: Reached target User and Group Name Lookups.1501server # [ 11.315992] systemd[1]: Starting User Login Management...1502server # [ 11.322741] systemd[1]: Finished Import lastlog data into lastlog2 database.1503builder # [ 11.337411] systemd[1]: Finished Post-Boot Actions.1504builder # [ 11.372145] systemd[1]: Started Name Service Cache Daemon (nsncd).1505builder # [ 11.378888] nsncd[489]: Aug 27 08:41:42.983 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1506server # [ 11.389798] niks3-test-certs-start[510]: -----1507builder # [ 11.389106] systemd[1]: Reached target Host and Network Name Lookups.1508builder # [ 11.394260] systemd[1]: Reached target User and Group Name Lookups.1509builder # [ 11.399702] systemd[1]: Starting User Login Management...1510builder # [ 11.411983] systemd[1]: Finished Import lastlog data into lastlog2 database.1511server # [ 11.416621] dbus-broker-launch[504]: Looking up NSS user entry for 'systemd-timesync'...1512server # [ 11.437186] dbus-broker-launch[504]: NSS returned no entry for 'systemd-timesync'1513server # [ 11.440295] dbus-broker-launch[504]: Invalid user-name in /nix/store/ydzylz3pn5lfnlczk7famd61bgjb16pb-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1514server # [ 11.456533] niks3-test-certs-start[540]: -----1515server # [ 11.492367] systemd[1]: Started D-Bus System Message Bus.1516server # [ 11.509748] systemd-logind[515]: New seat seat0.1517builder # [ 11.527793] systemd-logind[500]: New seat seat0.1518builder # [ 11.536973] systemd[1]: Started User Login Management.1519builder # [ 11.541902] systemd[1]: Starting linger-users.service...1520server # [ 11.553758] dbus-broker-launch[504]: Ready1521server # [ 11.557009] systemd[1]: Started User Login Management.1522server # [ 11.563533] systemd[1]: Starting linger-users.service...1523builder # [ 11.567109] systemd[1]: Stopped target Host and Network Name Lookups.1524server # [ 11.573422] systemd[1]: Stopped target Host and Network Name Lookups.1525builder # [ 11.576086] systemd[1]: Stopping Host and Network Name Lookups...1526builder # [ 11.585692] systemd[1]: Stopped target User and Group Name Lookups.1527server # [ 11.588817] systemd[1]: Stopping Host and Network Name Lookups...1528builder # [ 11.591216] systemd[1]: Stopping User and Group Name Lookups...1529server # [ 11.591303] systemd[1]: Stopped target User and Group Name Lookups.1530server # [ 11.597283] systemd[1]: Stopping User and Group Name Lookups...1531builder # [ 11.598352] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1532server # [ 11.601358] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1533builder # [ 11.602820] systemd[1]: nscd.service: Deactivated successfully.1534server # [ 11.607717] systemd[1]: nscd.service: Deactivated successfully.1535builder # [ 11.608455] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1536server # [ 11.616145] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1537builder # [ 11.616265] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1538server # [ 11.620122] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1539server # [ 11.633972] niks3-test-certs-start[553]: Certificate request self-signature ok1540server # [ 11.644195] niks3-test-certs-start[553]: subject=CN=server1541builder # [ 11.642747] systemd[1]: linger-users.service: Deactivated successfully.1542builder # [ 11.647065] systemd[1]: Finished linger-users.service.1543builder # [ 11.675816] systemd[1]: Starting Virtual Console Setup...1544server # [ 11.683515] niks3-test-certs-start[580]: -----1545server # [ 11.701298] nsncd[570]: Aug 27 08:41:43.320 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1546builder # [ 11.700760] nsncd[558]: Aug 27 08:41:43.309 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1547server # [ 11.709656] systemd[1]: Started Name Service Cache Daemon (nsncd).1548builder # [ 11.713153] systemd[1]: Started Name Service Cache Daemon (nsncd).1549server # [ 11.716302] systemd[1]: Reached target Host and Network Name Lookups.1550builder # [ 11.717010] systemd[1]: Reached target Host and Network Name Lookups.1551server # [ 11.720343] systemd[1]: Reached target User and Group Name Lookups.1552server # [ 11.721309] systemd[1]: linger-users.service: Deactivated successfully.1553builder # [ 11.722745] systemd[1]: Reached target User and Group Name Lookups.1554server # [ 11.723441] systemd[1]: Finished linger-users.service.1555builder # [ 11.727622] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1556builder # [ 11.730594] systemd[1]: Stopped Virtual Console Setup.1557builder # [ 11.733359] systemd[1]: Starting Virtual Console Setup...1558builder # [ 11.764404] systemd[1]: Finished resolvconf update.1559builder # [ 11.766109] systemd[1]: Reached target Preparation for Network.1560builder # [ 11.771970] systemd[1]: Starting DHCP Client...1561server # [ 11.776155] systemd[1]: Finished resolvconf update.1562server # [ 11.780427] systemd[1]: Reached target Preparation for Network.1563server # [ 11.781723] niks3-test-certs-start[595]: Certificate request self-signature ok1564builder # [ 11.780662] systemd[1]: Starting Extra networking commands....1565server # [ 11.784154] niks3-test-certs-start[595]: subject=CN=niks3 test client1566server # [ 11.794860] systemd[1]: Starting DHCP Client...1567server # [ 11.802601] systemd[1]: Starting Extra networking commands....1568builder # [ 11.807097] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1569server # [ 11.829709] systemd[1]: Finished Generate test mTLS certs.1570server # [ 12.026987] dhcpcd[608]: dhcpcd-10.3.2 starting1571server # [ 12.045486] dhcpcd[648]: dev: loaded udev1572builder # [ 12.050143] dhcpcd[594]: dhcpcd-10.3.2 starting1573server # [ 12.051648] dhcpcd[648]: no valid interfaces found1574server # [ 12.055148] dhcpcd[648]: libudev: received NULL device1575server # [ 12.057666] dhcpcd[648]: no valid interfaces found1576server # [ 12.060226] dhcpcd[648]: libudev: received NULL device1577builder # [ 12.059048] systemd-logind[500]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1578builder # [ 12.068966] dhcpcd[632]: dev: loaded udev1579builder # [ 12.076397] dhcpcd[632]: no valid interfaces found1580builder # [ 12.078737] dhcpcd[632]: no valid interfaces found1581builder # [ 12.083229] dhcpcd[632]: libudev: received NULL device1582builder # [ 12.086828] dhcpcd[632]: libudev: received NULL device1583server # [ 12.094971] systemd[1]: Finished Extra networking commands..1584server # [ 12.096357] systemd[1]: Reached target Network.1585server # [ 12.100729] systemd[1]: Started Mock OIDC server for testing.1586server # [ 12.107110] systemd[1]: Starting Nginx Web Server...1587server # [ 12.116378] systemd[1]: Starting PostgreSQL Server...1588builder # [ 12.120804] systemd[1]: Finished Extra networking commands..1589server # [ 12.124437] systemd[1]: Started RustFS S3-compatible object storage.1590builder # [ 12.124441] systemd[1]: Reached target Network.1591server # [ 12.131870] systemd[1]: Starting Setup RustFS bucket...1592builder # [ 12.127162] systemd[1]: Starting Permit User Sessions...1593server # [ 12.145358] systemd[1]: Starting Permit User Sessions...1594server # [ 12.176314] systemd[1]: Starting Virtual Console Setup...1595builder # [ 12.181850] systemd[1]: Finished Permit User Sessions.1596builder # [ 12.187931] systemd[1]: Started Getty on tty1.1597builder # [ 12.190602] systemd[1]: Reached target Login Prompts.1598server # [ 12.229883] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1599builder # [ 12.230826] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1600server # [ 12.243478] systemd[1]: Stopped Virtual Console Setup.1601server # [ 12.250176] systemd[1]: Starting Virtual Console Setup...1602builder # [ 12.270931] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1603builder # [ 12.276641] systemd[1]: Started backdoor.service.1604server # [ 12.283424] systemd[1]: Finished Permit User Sessions.1605server # [ 12.288878] systemd[1]: Started Getty on tty1.1606server # [ 12.296701] systemd[1]: Reached target Login Prompts.1607builder # [ 12.369386] 8021q: 802.1Q VLAN Support v1.81608builder # [ 12.396354] (udev-worker)[394]: Network interface NamePolicy= disabled on kernel command line.1609builder # connecting to host...1610server # [ 12.426265] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1611server # [ 12.444345] mock-oidc-server[661]: Mock OIDC Server running1612server # [ 12.452122] mock-oidc-server[661]: OIDC Address: 127.0.0.1:80801613builder # [ 12.463896] cfg80211: Loading compiled-in X.509 certificates for regulatory database1614server # [ 12.458924] mock-oidc-server[661]: Issue Address: 127.0.0.1:80811615server # [ 12.463388] mock-oidc-server[661]: Issuer: http://127.0.0.1:8080/oidc1616server # [ 12.469688] mock-oidc-server[661]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json1617server # [ 12.475394] mock-oidc-server[661]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration1618builder # [ 12.471077] systemd-vconsole-setup[583]: Configuration of first virtual console was skipped, ignoring remaining ones.1619builder # [ 12.482494] systemd[1]: Finished Virtual Console Setup.1620server # [ 12.484961] mock-oidc-server[661]: Issue tokens: http://127.0.0.1:8081/issue?sub=...1621builder # [ 12.507314] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1622builder # [ 12.507822] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1623builder # [ 12.512774] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21624builder # [ 12.513123] cfg80211: failed to load regulatory.db1625builder # [ 12.598245] 8021q: adding VLAN 0 to HW filter on device eth01626builder # [ 12.585379] dhcpcd[632]: eth0: waiting for carrier1627builder # [ 12.586909] dhcpcd[632]: eth0: carrier acquired1628builder # [ 12.601606] dhcpcd[632]: DUID 00:01:00:01:32:22:b4:c8:52:54:00:12:34:561629builder # [ 12.602632] dhcpcd[632]: eth0: IAID 00:12:34:561630builder # [ 12.603276] dhcpcd[632]: eth0: adding address fe80::5054:ff:fe12:34561631builder # [ 12.618674] (udev-worker)[667]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1632builder # [ 12.623577] (udev-worker)[667]: Network interface NamePolicy= disabled on kernel command line.1633builder # [ 12.764851] systemd[1]: Condition check resulted in Virtio network device being skipped.1634server # [ 12.774218] nginx-pre-start[687]: nginx: the configuration file /nix/store/8fchrc74qg5jnf5g879pg31m8h9620s2-nginx.conf syntax is ok1635builder # [ 12.771141] systemd[1]: Starting Address configuration of eth1...1636builder # [ 12.782062] dhcpcd[632]: eth0: soliciting a DHCP lease1637builder # [ 12.788911] systemd-logind[500]: Watching system buttons on /dev/input/event0 (gpio-keys)1638builder # [ 12.790184] dhcpcd[632]: eth0: offered 10.0.2.15 from 10.0.2.21639server # [ 12.782090] nginx-pre-start[687]: nginx: configuration file /nix/store/8fchrc74qg5jnf5g879pg31m8h9620s2-nginx.conf test is successful1640server # [ 12.796655] systemd[1]: Started Nginx Web Server.1641builder # [ 12.796259] dhcpcd[632]: eth0: probing address 10.0.2.15/241642server # [ 12.827651] postgresql-pre-start[690]: The files belonging to this database system will be owned by user "postgres".1643server # [ 12.834807] postgresql-pre-start[690]: This user must also own the server process.1644server # [ 12.844104] postgresql-pre-start[690]: The database cluster will be initialized with locale "en_US.UTF-8".1645server # [ 12.850235] postgresql-pre-start[690]: The default database encoding has accordingly been set to "UTF8".1646server # [ 12.856451] postgresql-pre-start[690]: The default text search configuration will be set to "english".1647server # [ 12.864216] postgresql-pre-start[690]: Data page checksums are enabled.1648server # [ 12.870366] postgresql-pre-start[690]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok1649builder # [ 12.888971] 8021q: adding VLAN 0 to HW filter on device eth11650server # [ 12.880301] postgresql-pre-start[690]: creating subdirectories ... ok1651server # [ 12.884618] postgresql-pre-start[690]: selecting dynamic shared memory implementation ... posix1652builder # [ 12.894756] network-addresses-eth1-start[682]: adding address 192.168.1.1/24... done1653builder # [ 12.925255] mousedev: PS/2 mouse device common for all mice1654builder # [ 12.912761] network-addresses-eth1-start[682]: adding address 2001:db8:1::1/64... done1655builder # [ 12.938384] systemd[1]: Finished Address configuration of eth1.1656builder # [ 13.042480] systemd-logind[500]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1657server # [ 13.124931] systemd-vconsole-setup[675]: Configuration of first virtual console was skipped, ignoring remaining ones.1658server # [ 13.134163] systemd[1]: Finished Virtual Console Setup.1659server # [ 13.137296] postgresql-pre-start[690]: selecting default "max_connections" ... 1001660server # [ 13.198475] systemd-logind[515]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1661server # [ 13.303372] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1662server # [ 13.318519] postgresql-pre-start[690]: selecting default "shared_buffers" ... 128MB1663server # [ 13.351398] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1664server # [ 13.356638] systemd[1]: Started backdoor.service.1665server # [ 13.461063] 8021q: 802.1Q VLAN Support v1.81666server # connecting to host...1667server # [ 13.575552] cfg80211: Loading compiled-in X.509 certificates for regulatory database1668server: Guest shell says: b'Spawning backdoor root shell...\n'1669server # [ 13.612008] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1670server # [ 13.612528] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1671server # [ 13.617120] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21672server: connected to guest root shell1673server # [ 13.617462] cfg80211: failed to load regulatory.db1674server: (connecting took 14.04 seconds)1675server: (finished: waiting for the VM to finish booting, in 14.04 seconds)1676server # [ 13.768383] (udev-worker)[700]: Network interface NamePolicy= disabled on kernel command line.1677server # [ 13.774055] (udev-worker)[698]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1678server # [ 13.783913] (udev-worker)[698]: Network interface NamePolicy= disabled on kernel command line.1679builder # [ 13.945378] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1680builder # [ 13.948458] systemd[1]: Stopped Virtual Console Setup.1681builder # [ 13.949286] systemd[1]: Stopping Virtual Console Setup...1682builder # [ 13.954371] systemd[1]: Starting Virtual Console Setup...1683builder # [ 13.976092] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1684builder # [ 13.981275] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1685builder # [ 13.988535] systemd[1]: Stopped Virtual Console Setup.1686builder # [ 13.992608] systemd[1]: Starting Virtual Console Setup...1687server # [ 14.046278] systemd[1]: Condition check resulted in Virtio network device being skipped.1688server # [ 14.053389] systemd[1]: Starting Address configuration of eth1...1689server # [ 14.055731] systemd-logind[515]: Watching system buttons on /dev/input/event0 (gpio-keys)1690server # [ 14.190364] 8021q: adding VLAN 0 to HW filter on device eth01691server # [ 14.177937] dhcpcd[648]: eth0: waiting for carrier1692server # [ 14.181104] dhcpcd[648]: eth0: carrier acquired1693server # [ 14.206614] 8021q: adding VLAN 0 to HW filter on device eth11694server # [ 14.201546] dhcpcd[648]: DUID 00:01:00:01:32:22:b4:c9:52:54:00:12:34:561695server # [ 14.204169] dhcpcd[648]: eth0: IAID 00:12:34:561696server # [ 14.205988] dhcpcd[648]: eth0: adding address fe80::5054:ff:fe12:34561697server # [ 14.217043] network-addresses-eth1-start[746]: adding address 192.168.1.2/24... done1698server # [ 14.232096] network-addresses-eth1-start[746]: adding address 2001:db8:1::2/64... done1699server # [ 14.253109] systemd[1]: Finished Address configuration of eth1.1700server # [ 14.311569] mousedev: PS/2 mouse device common for all mice1701builder # [ 14.299510] systemd-vconsole-setup[699]: Configuration of first virtual console was skipped, ignoring remaining ones.1702builder # [ 14.303643] systemd[1]: Finished Virtual Console Setup.1703server # [ 14.450356] systemd-logind[515]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1704server # [ 14.849842] dhcpcd[648]: eth0: soliciting a DHCP lease1705server # [ 14.856828] dhcpcd[648]: eth0: offered 10.0.2.15 from 10.0.2.21706server # [ 14.868316] dhcpcd[648]: eth0: probing address 10.0.2.15/241707builder # [ 15.204820] dhcpcd[632]: eth0: soliciting an IPv6 router1708builder # [ 15.208293] dhcpcd[632]: eth0: Router Advertisement from fe80::21709builder # [ 15.210785] dhcpcd[632]: eth0: adding address fec0::5054:ff:fe12:3456/641710builder # [ 15.215063] dhcpcd[632]: eth0: adding route to fec0::/641711builder # [ 15.217509] dhcpcd[632]: eth0: adding default route via fe80::21712server # [ 16.038534] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1713server # [ 16.042390] systemd[1]: Stopped Virtual Console Setup.1714server # [ 16.043203] systemd[1]: Stopping Virtual Console Setup...1715server # [ 16.050687] systemd[1]: Starting Virtual Console Setup...1716server # [ 16.067881] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1717server # [ 16.069664] systemd[1]: Stopped Virtual Console Setup.1718server # [ 16.075843] systemd[1]: Starting Virtual Console Setup...1719server # [ 16.082913] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1720server # [ 16.295749] dhcpcd[648]: eth0: soliciting an IPv6 router1721server # [ 16.297355] dhcpcd[648]: eth0: Router Advertisement from fe80::21722server # [ 16.298967] dhcpcd[648]: eth0: adding address fec0::5054:ff:fe12:3456/641723server # [ 16.300259] dhcpcd[648]: eth0: adding route to fec0::/641724server # [ 16.302112] dhcpcd[648]: eth0: adding default route via fe80::21725server # [ 16.408698] systemd-vconsole-setup[788]: Configuration of first virtual console was skipped, ignoring remaining ones.1726server # [ 16.413139] systemd[1]: Finished Virtual Console Setup.1727server # [ 16.458072] postgresql-pre-start[690]: selecting default time zone ... UTC1728server # [ 16.461748] postgresql-pre-start[690]: creating configuration files ... ok1729server # [ 16.733853] postgresql-pre-start[690]: running bootstrap script ... ok1730server # [ 17.356432] postgresql-pre-start[690]: performing post-bootstrap initialization ... ok1731server # [ 17.529198] postgresql-pre-start[690]: syncing data to disk ... ok1732server # [ 17.531299] postgresql-pre-start[690]: initdb: warning: enabling "trust" authentication for local connections1733server # [ 17.532906] postgresql-pre-start[690]: 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.1734server # [ 17.535144] postgresql-pre-start[690]: Success. You can now start the database server using:1735server # [ 17.536459] postgresql-pre-start[690]: pg_ctl -D /var/lib/postgresql/18 -l logfile start1736server # [ 17.654846] postgres[802]: [802] LOG: starting PostgreSQL 18.4 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit1737server # [ 17.658412] postgres[802]: [802] LOG: listening on IPv6 address "::1", port 54321738server # [ 17.659649] postgres[802]: [802] LOG: listening on IPv4 address "127.0.0.1", port 54321739server # [ 17.661590] postgres[802]: [802] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1740server # [ 17.675042] postgres[811]: [811] LOG: database system was shut down at 2026-08-27 08:41:48 GMT1741server # [ 17.679937] postgres[802]: [802] LOG: database system is ready to accept connections1742server # [ 17.684412] systemd[1]: Started PostgreSQL Server.1743server # [ 17.689490] systemd[1]: Starting PostgreSQL Setup Scripts...1744builder # [ 17.746588] dhcpcd[632]: eth0: leased 10.0.2.15 for 86400 seconds1745builder # [ 17.750278] dhcpcd[632]: eth0: adding route to 10.0.2.0/241746builder # [ 17.753513] dhcpcd[632]: eth0: adding default route via 10.0.2.21747server # [ 17.906815] postgresql-setup-start[822]: CREATE DATABASE1748builder # [ 17.909925] systemd[1]: Started DHCP Client.1749builder # [ 17.912320] systemd[1]: Reached target Multi-User System.1750builder # [ 17.913229] systemd[1]: Startup finished in 958ms (kernel) + 4.609s (initrd) + 12.343s (userspace) = 17.911s.1751server # [ 17.959723] postgresql-setup-start[827]: CREATE ROLE1752server # [ 17.982449] postgresql-setup-start[829]: ALTER DATABASE1753server # [ 17.989344] systemd[1]: Finished PostgreSQL Setup Scripts.1754server # [ 17.991018] systemd[1]: Reached target PostgreSQL.1755server: (finished: waiting for unit postgresql.service, in 18.46 seconds)1756server: waiting for unit rustfs.service1757server: (finished: waiting for unit rustfs.service, in 0.05 seconds)1758server: waiting for unit rustfs-setup.service1759server # [ 20.189169] dhcpcd[648]: eth0: leased 10.0.2.15 for 86400 seconds1760server # [ 20.193180] dhcpcd[648]: eth0: adding route to 10.0.2.0/241761server # [ 20.202099] dhcpcd[648]: eth0: adding default route via 10.0.2.21762server # [ 20.418201] systemd[1]: Started DHCP Client.1763server # [ 27.371582] rustfs-setup-start[939]: mb s3://niks3-test1764server # [ 27.381391] systemd[1]: Finished Setup RustFS bucket.1765server # [ 27.389980] systemd[1]: Starting niks3 server...1766server # [ 27.590308] postgres[953]: [953] ERROR: relation "goose_db_version" does not exist at character 361767server # [ 27.591575] postgres[953]: [953] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1768server # [ 27.624944] niks3-server[947]: 2026/08/27 08:41:59 OK 20241026095416_initial_model.sql (21.54ms)1769server # [ 27.637427] niks3-server[947]: 2026/08/27 08:41:59 OK 20251210153512_drop_unused_gin_index.sql (4.25ms)1770server # [ 27.640376] niks3-server[947]: 2026/08/27 08:41:59 OK 20251218171726_add_pins.sql (5.65ms)1771server # [ 27.641900] niks3-server[947]: 2026/08/27 08:41:59 OK 20260628120000_add_object_size_and_stats.sql (7.19ms)1772server # [ 27.643207] niks3-server[947]: 2026/08/27 08:41:59 goose: successfully migrated database to version: 202606281200001773server # [ 27.648291] niks3-server[947]: 2026/08/27 08:41:59 OK 1_commit_pending_closure.sql (6.42ms)1774server # [ 27.651147] niks3-server[947]: 2026/08/27 08:41:59 OK 2_object_stats_trigger.sql (2.7ms)1775server # [ 27.652649] niks3-server[947]: 2026/08/27 08:41:59 goose: up to current file version: 21776server # [ 27.667339] niks3-server[947]: 2026/08/27 08:41:59 INFO OIDC provider initialized name=test1777server # [ 27.669022] niks3-server[947]: 2026/08/27 08:41:59 INFO OIDC authentication enabled config=/nix/store/2kgc3niwwgjciamla3552lvnijk6vw7x-niks3-oidc.json1778server # [ 27.671411] niks3-server[947]: 2026/08/27 08:41:59 INFO Loaded signing key name=niks3-test-1 path=/nix/store/wrx1ximvisxmxja4g0mfh43p13mnhdz4-niks3-signing-key1779server # [ 27.709310] niks3-server[947]: 2026/08/27 08:41:59 INFO Created nix-cache-info in bucket bucket=niks3-test1780server # [ 27.714415] niks3-server[947]: 2026/08/27 08:41:59 INFO Using socket-activated listener address=0.0.0.0:57511781server # [ 27.717543] niks3-server[947]: 2026/08/27 08:41:59 INFO systemd watchdog enabled interval=15s1782server # [ 27.720886] systemd[1]: Started niks3 server.1783server # [ 27.721725] niks3-server[947]: 2026/08/27 08:41:59 INFO Starting HTTP server address=0.0.0.0:57511784server # [ 27.723737] systemd[1]: Reached target Multi-User System.1785server # [ 27.724803] systemd[1]: Startup finished in 950ms (kernel) + 4.619s (initrd) + 22.148s (userspace) = 27.719s.1786server: (finished: waiting for unit rustfs-setup.service, in 9.75 seconds)1787server: waiting for unit mock-oidc.service1788server: (finished: waiting for unit mock-oidc.service, in 0.05 seconds)1789server: waiting for unit niks3.service1790server: (finished: waiting for unit niks3.service, in 0.05 seconds)1791server: waiting for TCP port 5751 on localhost1792server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded!1793server: (finished: waiting for TCP port 5751 on localhost, in 0.06 seconds)1794server: waiting for TCP port 8080 on localhost1795server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded!1796server: (finished: waiting for TCP port 8080 on localhost, in 0.04 seconds)1797server: waiting for TCP port 9000 on localhost1798server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded!1799server: (finished: waiting for TCP port 9000 on localhost, in 0.04 seconds)1800server: must succeed: mkdir -p /tmp/test-config1801server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.03 seconds)1802server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token1803server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.02 seconds)1804server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push /nix/store/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.31805server # [ 28.346207] systemd[1]: Created slice Slice /system/nix-daemon.1806server # [ 28.351677] systemd[1]: Started Nix Daemon instance (PID 996/UID 0).1807server # [ 28.440661] nix-daemon[998]: remote pid 996 is unknown user (trusted)1808server # [ 28.464277] systemd[1]: nix-daemon@0-1-996_997-0.service: Deactivated successfully.1809server # [ 28.481970] niks3-server[947]: 2026/08/27 08:42:00 INFO Received uploads request method=POST path=/api/pending_closures1810server # time=2026-08-27T08:42:00.126Z level=INFO msg="Uploading 5 paths to server (0 already cached)"1811server # time=2026-08-27T08:42:00.128Z level=INFO msg="Uploading 84i6rp3qvbrm0vl88w5fm9h37yka5mzb-xgcc-15.3.0-libgcc (150.1KB)"1812server # time=2026-08-27T08:42:00.130Z level=INFO msg="Uploading 4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.3 (287.5KB)"1813server # time=2026-08-27T08:42:00.133Z level=INFO msg="Uploading cjcj20n6xa0hs5cd0adwx47g058c6z72-glibc-2.42-67 (44.4MB)"1814server # time=2026-08-27T08:42:00.136Z level=INFO msg="Uploading qlmazqi9rqlg4b1gbw5qj2v8pwd9bl39-libidn2-2.3.8 (366.1KB)"1815server # time=2026-08-27T08:42:00.138Z level=INFO msg="Uploading qr7qvicd9q4lnq6lznx223z5sakp9jrx-libunistring-1.4.2 (2.0MB)"1816server # [ 28.597463] niks3-server[947]: 2026/08/27 08:42:00 INFO Registered completed upload object_key=nar/0x48iv4crw96wayixvwmbhz9znvm9djawdf9j0wszdqdxpn92kw1.nar.zst1817server # [ 28.627642] niks3-server[947]: 2026/08/27 08:42:00 INFO Registered completed upload object_key=84i6rp3qvbrm0vl88w5fm9h37yka5mzb.ls1818server # [ 28.684218] niks3-server[947]: 2026/08/27 08:42:00 INFO Registered completed upload object_key=nar/07pprfga3cz413hy4lql6vphxcffdcbpn83nix1z1g7hfdnmlc0h.nar.zst1819server # [ 28.695627] niks3-server[947]: 2026/08/27 08:42:00 INFO Registered completed upload object_key=qr7qvicd9q4lnq6lznx223z5sakp9jrx.ls1820server # [ 28.788717] niks3-server[947]: 2026/08/27 08:42:00 INFO Registered completed upload object_key=nar/17ns7snp4gqzfpxnhjwzvy7wcwjsj1yzsjw7v6bmm0vfgw7dbxwx.nar.zst1821server # [ 28.802369] niks3-server[947]: 2026/08/27 08:42:00 INFO Registered completed upload object_key=qlmazqi9rqlg4b1gbw5qj2v8pwd9bl39.ls1822server # [ 28.905277] niks3-server[947]: 2026/08/27 08:42:00 INFO Registered completed upload object_key=nar/17gmcsz5kjdd73iypfpshr50ihfgmsx0hafwh48i98n2g20yqga7.nar.zst1823server # [ 28.919266] niks3-server[947]: 2026/08/27 08:42:00 INFO Registered completed upload object_key=4z8ys247h2242760a0pw6y9akab2n09l.ls1824server # [ 30.636468] niks3-server[947]: 2026/08/27 08:42:02 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1825server # [ 30.650884] niks3-server[947]: 2026/08/27 08:42:02 INFO Completed multipart upload object_key=nar/1fca2lwv39xkbrm6qpxbdmjg5ppqv8rbdha2rdqr33jm5f23a491.nar.zst upload_id=NzVlY2UyZGUtYzFhMS00MzdlLWJmOTYtOTNkMDUzODlkY2VmLmU0OWVjYWE1LTFkMDUtNDQ0NS05NjdkLTlmYWU3Yjg1MjJkYngxNzg3ODIwMTIwMTE2OTg5ODQw parts=11826server # [ 30.665174] niks3-server[947]: 2026/08/27 08:42:02 INFO Registered completed upload object_key=cjcj20n6xa0hs5cd0adwx47g058c6z72.ls1827server # [ 30.666830] niks3-server[947]: 2026/08/27 08:42:02 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1828server # [ 30.671622] niks3-server[947]: 2026/08/27 08:42:02 INFO Signed narinfos id=1 count=51829server # time=2026-08-27T08:42:02.293Z level=INFO msg="Uploading 5 narinfos"1830server # [ 30.688447] niks3-server[947]: 2026/08/27 08:42:02 INFO Registered completed upload object_key=4z8ys247h2242760a0pw6y9akab2n09l.narinfo1831server # [ 30.699134] niks3-server[947]: 2026/08/27 08:42:02 INFO Registered completed upload object_key=qlmazqi9rqlg4b1gbw5qj2v8pwd9bl39.narinfo1832server # [ 30.710342] niks3-server[947]: 2026/08/27 08:42:02 INFO Registered completed upload object_key=84i6rp3qvbrm0vl88w5fm9h37yka5mzb.narinfo1833server # [ 30.737865] niks3-server[947]: 2026/08/27 08:42:02 INFO Registered completed upload object_key=cjcj20n6xa0hs5cd0adwx47g058c6z72.narinfo1834server # [ 30.742220] niks3-server[947]: 2026/08/27 08:42:02 INFO Registered completed upload object_key=qr7qvicd9q4lnq6lznx223z5sakp9jrx.narinfo1835server # [ 30.743880] niks3-server[947]: 2026/08/27 08:42:02 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1836server # time=2026-08-27T08:42:02.368Z level=INFO msg="Upload complete. (2.474s)"1837server # [ 30.749363] niks3-server[947]: 2026/08/27 08:42:02 INFO Completed upload id=11838server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push /nix/store/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.3, in 2.64 seconds)1839server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token1840server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.02 seconds)1841server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push /nix/store/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.31842server # [ 30.954627] systemd[1]: Started Nix Daemon instance (PID 1028/UID 0).1843server # [ 31.047430] nix-daemon[1030]: remote pid 1028 is unknown user (trusted)1844server # [ 31.064840] systemd[1]: nix-daemon@1-2-1028_1029-0.service: Deactivated successfully.1845server # [ 31.074639] niks3-server[947]: 2026/08/27 08:42:02 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]1846server # time=2026-08-27T08:42:02.698Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"1847server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push /nix/store/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.3, in 0.30 seconds)1848server: waiting for unit nginx.service1849server: (finished: waiting for unit nginx.service, in 0.05 seconds)1850server: waiting for TCP port 443 on localhost1851server # Connection to localhost (::1) 443 port [tcp/https] succeeded!1852server: (finished: waiting for TCP port 443 on localhost, in 0.04 seconds)1853server: must succeed: /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.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/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.31854server # time=2026-08-27T08:42:02.874Z 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.pem1855server # [ 31.323914] systemd[1]: Started Nix Daemon instance (PID 1055/UID 0).1856server # [ 31.406291] nix-daemon[1057]: remote pid 1055 is unknown user (trusted)1857server # [ 31.425508] systemd[1]: nix-daemon@2-3-1055_1056-0.service: Deactivated successfully.1858server # [ 31.445959] niks3-server[947]: 2026/08/27 08:42:03 INFO Received uploads request method=POST path=/api/pending_closures1859server # time=2026-08-27T08:42:03.071Z level=INFO msg="Uploading 0 paths to server (5 already cached)"1860server # [ 31.453688] niks3-server[947]: 2026/08/27 08:42:03 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1861server # [ 31.456998] niks3-server[947]: 2026/08/27 08:42:03 INFO Completed upload id=21862server # time=2026-08-27T08:42:03.078Z level=INFO msg="Upload complete. (199ms)"1863server: (finished: must succeed: /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.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/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.3, in 0.29 seconds)1864server: must fail: /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.31865server # time=2026-08-27T08:42:03.103Z 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)"1866server: (finished: must fail: /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.3, in 0.02 seconds)1867server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.31868server # time=2026-08-27T08:42:03.190Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem1869server # [ 31.636155] systemd[1]: Started Nix Daemon instance (PID 1081/UID 0).1870server # [ 31.716781] nix-daemon[1083]: remote pid 1081 is unknown user (trusted)1871server # [ 31.733756] systemd[1]: nix-daemon@3-4-1081_1082-0.service: Deactivated successfully.1872server # [ 31.746720] niks3-server[947]: 2026/08/27 08:42:03 INFO Received uploads request method=POST path=/api/pending_closures1873server # time=2026-08-27T08:42:03.369Z level=INFO msg="Uploading 0 paths to server (5 already cached)"1874server # [ 31.751549] niks3-server[947]: 2026/08/27 08:42:03 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1875server # time=2026-08-27T08:42:03.375Z level=INFO msg="Upload complete. (183ms)"1876server # [ 31.756109] niks3-server[947]: 2026/08/27 08:42:03 INFO Completed upload id=31877server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.3, in 0.27 seconds)1878server: must succeed: cd /etc/niks3-test-certs && /nix/store/vwy8b146hgb0jvwwwx96jxpqrx1m85ba-openssl-3.6.3-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client'1879server # -----1880server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/vwy8b146hgb0jvwwwx96jxpqrx1m85ba-openssl-3.6.3-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)1881server: must succeed: cd /etc/niks3-test-certs && /nix/store/vwy8b146hgb0jvwwwx96jxpqrx1m85ba-openssl-3.6.3-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem1882server # Certificate request self-signature ok1883server # subject=CN=other client1884server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/vwy8b146hgb0jvwwwx96jxpqrx1m85ba-openssl-3.6.3-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem, in 0.05 seconds)1885server: must fail: /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.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/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.31886server # time=2026-08-27T08:42:03.539Z 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.pem1887server # [ 31.987543] systemd[1]: Started Nix Daemon instance (PID 1110/UID 0).1888server # [ 32.071917] nix-daemon[1112]: remote pid 1110 is unknown user (trusted)1889server # [ 32.089433] systemd[1]: nix-daemon@4-5-1110_1111-0.service: Deactivated successfully.1890server # [ 32.100974] niks3-server[947]: 2026/08/27 08:42:03 WARN mTLS auth: subject not in bound subjects subject="CN=other client"1891server # time=2026-08-27T08:42:03.723Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"1892server: (finished: must fail: /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.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/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.3, in 0.27 seconds)1893server: must succeed: mkdir -p /tmp/test-store1894server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.03 seconds)1895server: must succeed: 1896 export AWS_ACCESS_KEY_ID=rustfsadmin1897export AWS_SECRET_ACCESS_KEY=rustfsadmin1898 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.318991900server: (finished: must succeed: 1901 export AWS_ACCESS_KEY_ID=rustfsadmin1902export AWS_SECRET_ACCESS_KEY=rustfsadmin1903 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.31904, in 0.74 seconds)1905server: must succeed: 1906cat > /tmp/test-drv.nix << 'EOF'1907derivation {1908 name = "test-build-log";1909 system = builtins.currentSystem;1910 builder = "/bin/sh";1911 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];1912}1913EOF19141915server: (finished: must succeed: 1916cat > /tmp/test-drv.nix << 'EOF'1917derivation {1918 name = "test-build-log";1919 system = builtins.currentSystem;1920 builder = "/bin/sh";1921 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];1922}1923EOF1924, in 0.03 seconds)1925server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix1926server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring1927server # [ 33.004658] systemd[1]: Started Nix Daemon instance (PID 1154/UID 0).1928server # [ 33.086011] nix-daemon[1158]: remote pid 1154 is unknown user (trusted)1929server # this derivation will be built:1930server # /nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv1931server # building '/nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv'...1932server # test-build-log> test build log output1933server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.33 seconds)1934server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1935server # [ 33.242598] systemd[1]: nix-daemon@5-6-1154_1155-0.service: Deactivated successfully.1936server # [ 33.396295] systemd[1]: Started Nix Daemon instance (PID 1184/UID 0).1937server # [ 33.480396] nix-daemon[1186]: remote pid 1184 is unknown user (trusted)1938server # [ 33.496751] systemd[1]: nix-daemon@6-7-1184_1185-0.service: Deactivated successfully.1939server # [ 33.507433] niks3-server[947]: 2026/08/27 08:42:05 INFO Received uploads request method=POST path=/api/pending_closures1940server # time=2026-08-27T08:42:05.132Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1941server # time=2026-08-27T08:42:05.134Z level=INFO msg="Uploading z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log (128B)"1942server # [ 33.548219] niks3-server[947]: 2026/08/27 08:42:05 INFO Registered completed upload object_key=nar/00zns3gj9hwz2a4b0i07y7nmxybq59lh24bl3xsxblcl6333mjil.nar.zst1943server # [ 33.554384] niks3-server[947]: 2026/08/27 08:42:05 INFO Registered completed upload object_key=log/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv1944server # [ 33.564901] niks3-server[947]: 2026/08/27 08:42:05 INFO Registered completed upload object_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.ls1945server # [ 33.566585] niks3-server[947]: 2026/08/27 08:42:05 INFO Received sign narinfos request method=POST path=/api/pending_closures/4/sign1946server # time=2026-08-27T08:42:05.190Z level=INFO msg="Uploading 1 narinfos"1947server # [ 33.577888] niks3-server[947]: 2026/08/27 08:42:05 INFO Signed narinfos id=4 count=11948server # [ 33.586034] niks3-server[947]: 2026/08/27 08:42:05 INFO Registered completed upload object_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.narinfo1949server # [ 33.587747] niks3-server[947]: 2026/08/27 08:42:05 INFO Received complete upload request method=POST path=/api/pending_closures/4/complete1950server # time=2026-08-27T08:42:05.210Z level=INFO msg="Upload complete. (259ms)"1951server # [ 33.591751] niks3-server[947]: 2026/08/27 08:42:05 INFO Completed upload id=41952server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log, in 0.36 seconds)1953server: must succeed: 1954 export AWS_ACCESS_KEY_ID=rustfsadmin1955export AWS_SECRET_ACCESS_KEY=rustfsadmin1956 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log19571958server: (finished: must succeed: 1959 export AWS_ACCESS_KEY_ID=rustfsadmin1960export AWS_SECRET_ACCESS_KEY=rustfsadmin1961 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1962, in 0.23 seconds)1963server: must succeed: readlink /etc/niks3-test/symlink-wrapper1964server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.03 seconds)1965server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper1966server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.03 seconds)1967server: must succeed: test -L /nix/store/kbz6jfpcar5d9kjay3nvfyz02dphnbkq-symlink-wrapper1968server: (finished: must succeed: test -L /nix/store/kbz6jfpcar5d9kjay3nvfyz02dphnbkq-symlink-wrapper, in 0.02 seconds)1969server: must succeed: readlink /nix/store/kbz6jfpcar5d9kjay3nvfyz02dphnbkq-symlink-wrapper1970server: (finished: must succeed: readlink /nix/store/kbz6jfpcar5d9kjay3nvfyz02dphnbkq-symlink-wrapper, in 0.03 seconds)1971server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push /nix/store/kbz6jfpcar5d9kjay3nvfyz02dphnbkq-symlink-wrapper1972server # [ 34.129684] systemd[1]: Started Nix Daemon instance (PID 1228/UID 0).1973server # [ 34.215392] nix-daemon[1230]: remote pid 1228 is unknown user (trusted)1974server # [ 34.232930] systemd[1]: nix-daemon@7-8-1228_1229-0.service: Deactivated successfully.1975server # [ 34.242396] niks3-server[947]: 2026/08/27 08:42:05 INFO Received uploads request method=POST path=/api/pending_closures1976server # time=2026-08-27T08:42:05.878Z level=INFO msg="Uploading 2 paths to server (0 already cached)"1977server # time=2026-08-27T08:42:05.879Z level=INFO msg="Uploading kbz6jfpcar5d9kjay3nvfyz02dphnbkq-symlink-wrapper (192B)"1978server # time=2026-08-27T08:42:05.881Z level=INFO msg="Uploading 7rr22ycj7650jkmllrl1sg9m9rbbi8yr-base-package (536B)"1979server # [ 34.279848] niks3-server[947]: 2026/08/27 08:42:05 INFO Registered completed upload object_key=nar/0kw5v3blz7nmx4fjsmx22rz38amp68v56zivvfrwdfjd05410p0k.nar.zst1980server # [ 34.290760] niks3-server[947]: 2026/08/27 08:42:05 INFO Registered completed upload object_key=nar/0820av37plmfgrxw6pcbym6phk1sv14dilyrablrnkzb7z1fd5js.nar.zst1981server # [ 34.297837] niks3-server[947]: 2026/08/27 08:42:05 INFO Registered completed upload object_key=kbz6jfpcar5d9kjay3nvfyz02dphnbkq.ls1982server # [ 34.303988] niks3-server[947]: 2026/08/27 08:42:05 INFO Registered completed upload object_key=7rr22ycj7650jkmllrl1sg9m9rbbi8yr.ls1983server # time=2026-08-27T08:42:05.927Z level=INFO msg="Uploading 2 narinfos"1984server # [ 34.310370] niks3-server[947]: 2026/08/27 08:42:05 INFO Received sign narinfos request method=POST path=/api/pending_closures/5/sign1985server # [ 34.314224] niks3-server[947]: 2026/08/27 08:42:05 INFO Signed narinfos id=5 count=21986server # [ 34.323830] niks3-server[947]: 2026/08/27 08:42:05 INFO Registered completed upload object_key=7rr22ycj7650jkmllrl1sg9m9rbbi8yr.narinfo1987server # [ 34.327113] niks3-server[947]: 2026/08/27 08:42:05 INFO Registered completed upload object_key=kbz6jfpcar5d9kjay3nvfyz02dphnbkq.narinfo1988server # [ 34.332169] niks3-server[947]: 2026/08/27 08:42:05 INFO Received complete upload request method=POST path=/api/pending_closures/5/complete1989server # time=2026-08-27T08:42:05.955Z level=INFO msg="Upload complete. (279ms)"1990server # [ 34.338616] niks3-server[947]: 2026/08/27 08:42:05 INFO Completed upload id=51991server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push /nix/store/kbz6jfpcar5d9kjay3nvfyz02dphnbkq-symlink-wrapper, in 0.40 seconds)1992server: must succeed: 1993 export AWS_ACCESS_KEY_ID=rustfsadmin1994export AWS_SECRET_ACCESS_KEY=rustfsadmin1995 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/kbz6jfpcar5d9kjay3nvfyz02dphnbkq-symlink-wrapper19961997server: (finished: must succeed: 1998 export AWS_ACCESS_KEY_ID=rustfsadmin1999export AWS_SECRET_ACCESS_KEY=rustfsadmin2000 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/kbz6jfpcar5d9kjay3nvfyz02dphnbkq-symlink-wrapper2001, in 0.25 seconds)2002server: must succeed: 2003cat > /tmp/oidc-test.nix << 'EOF'2004derivation {2005 name = "oidc-test";2006 system = builtins.currentSystem;2007 builder = "/bin/sh";2008 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2009}2010EOF20112012server: (finished: must succeed: 2013cat > /tmp/oidc-test.nix << 'EOF'2014derivation {2015 name = "oidc-test";2016 system = builtins.currentSystem;2017 builder = "/bin/sh";2018 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2019}2020EOF2021, in 0.03 seconds)2022server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link2023server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2024server # [ 34.724308] systemd[1]: Started Nix Daemon instance (PID 1258/UID 0).2025server # [ 34.819366] nix-daemon[1262]: remote pid 1258 is unknown user (trusted)2026server # this derivation will be built:2027server # /nix/store/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv2028server # building '/nix/store/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv'...2029server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link, in 0.34 seconds)2030server: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://server:5751&repository_owner=myorg'2031server # [ 34.968610] systemd[1]: nix-daemon@8-9-1258_1259-0.service: Deactivated successfully.2032server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://server:5751&repository_owner=myorg', in 0.08 seconds)2033server: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODc4MjM3MjYsImlhdCI6MTc4NzgyMDEyNiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.RS9C9Wf6giKljs7iviQ_bozjsYB7uzWLeL6gqpJWvo8o43IlbRXYn5ZD1Rv0mhf6l2rJ5jNYbQLxRjUJ4dEtWdaugFbgXQVY4Gac5-tsVlJ0Kl5ykTUI2YZhomXia8KNyd9fdYEsXu9kjqmT2v_oleqX_CER5UZV5-6GGQ1Ln1bwDTPKZ5Xo5kqmIFw5OAgE261KEJBnbmJk0Iq6qQC-ijSnrFfnnsiFkJwSTm2ubRNdjkm1tdTR6OhKOmiHsPerIO18FDQqiySGB2vYcv0qeTMPVP5Q9BPUKmO-8IsGyHMU8-ULArRYWAM7BqyfDtlQyeWKDDqF8T9XTmg6zGqknA' /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test2034server # time=2026-08-27T08:42:06.682Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2035server # [ 35.200652] systemd[1]: Started Nix Daemon instance (PID 1291/UID 0).2036server # [ 35.283681] nix-daemon[1293]: remote pid 1291 is unknown user (trusted)2037server # [ 35.301208] systemd[1]: nix-daemon@9-10-1291_1292-0.service: Deactivated successfully.2038server # [ 35.314344] niks3-server[947]: 2026/08/27 08:42:06 INFO OIDC auth successful provider=test2039server # [ 35.315635] niks3-server[947]: 2026/08/27 08:42:06 INFO Received uploads request method=POST path=/api/pending_closures2040server # time=2026-08-27T08:42:06.941Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2041server # time=2026-08-27T08:42:06.942Z level=INFO msg="Uploading x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test (136B)"2042server # [ 35.338366] niks3-server[947]: 2026/08/27 08:42:06 INFO OIDC auth successful provider=test2043server # [ 35.345175] niks3-server[947]: 2026/08/27 08:42:06 INFO OIDC auth successful provider=test2044server # [ 35.348839] niks3-server[947]: 2026/08/27 08:42:06 INFO Registered completed upload object_key=nar/17h0qgwf5334wia961ibqy9hmpsyvfhrvj5ji9m031klp6sx4v0q.nar.zst2045server # [ 35.354084] niks3-server[947]: 2026/08/27 08:42:06 INFO Registered completed upload object_key=log/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv2046server # [ 35.359437] niks3-server[947]: 2026/08/27 08:42:06 INFO OIDC auth successful provider=test2047server # [ 35.363655] niks3-server[947]: 2026/08/27 08:42:06 INFO Registered completed upload object_key=x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj.ls2048server # time=2026-08-27T08:42:06.986Z level=INFO msg="Uploading 1 narinfos"2049server # [ 35.368951] niks3-server[947]: 2026/08/27 08:42:06 INFO OIDC auth successful provider=test2050server # [ 35.370112] niks3-server[947]: 2026/08/27 08:42:06 INFO Received sign narinfos request method=POST path=/api/pending_closures/6/sign2051server # [ 35.371676] niks3-server[947]: 2026/08/27 08:42:06 INFO Signed narinfos id=6 count=12052server # [ 35.376887] niks3-server[947]: 2026/08/27 08:42:06 INFO OIDC auth successful provider=test2053server # [ 35.380837] niks3-server[947]: 2026/08/27 08:42:07 INFO Registered completed upload object_key=x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj.narinfo2054server # [ 35.382562] niks3-server[947]: 2026/08/27 08:42:07 INFO OIDC auth successful provider=test2055server # [ 35.383652] niks3-server[947]: 2026/08/27 08:42:07 INFO Received complete upload request method=POST path=/api/pending_closures/6/complete2056server # time=2026-08-27T08:42:07.007Z level=INFO msg="Upload complete. (254ms)"2057server # [ 35.389844] niks3-server[947]: 2026/08/27 08:42:07 INFO Completed upload id=62058server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODc4MjM3MjYsImlhdCI6MTc4NzgyMDEyNiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.RS9C9Wf6giKljs7iviQ_bozjsYB7uzWLeL6gqpJWvo8o43IlbRXYn5ZD1Rv0mhf6l2rJ5jNYbQLxRjUJ4dEtWdaugFbgXQVY4Gac5-tsVlJ0Kl5ykTUI2YZhomXia8KNyd9fdYEsXu9kjqmT2v_oleqX_CER5UZV5-6GGQ1Ln1bwDTPKZ5Xo5kqmIFw5OAgE261KEJBnbmJk0Iq6qQC-ijSnrFfnnsiFkJwSTm2ubRNdjkm1tdTR6OhKOmiHsPerIO18FDQqiySGB2vYcv0qeTMPVP5Q9BPUKmO-8IsGyHMU8-ULArRYWAM7BqyfDtlQyeWKDDqF8T9XTmg6zGqknA' /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test, in 0.35 seconds)2059server: must succeed: 2060cat > /tmp/oidc-test2.nix << 'EOF'2061derivation {2062 name = "oidc-test2";2063 system = builtins.currentSystem;2064 builder = "/bin/sh";2065 args = [ "-c" "echo 'OIDC test 2' > $out" ];2066}2067EOF20682069server: (finished: must succeed: 2070cat > /tmp/oidc-test2.nix << 'EOF'2071derivation {2072 name = "oidc-test2";2073 system = builtins.currentSystem;2074 builder = "/bin/sh";2075 args = [ "-c" "echo 'OIDC test 2' > $out" ];2076}2077EOF2078, in 0.03 seconds)2079server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link2080server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2081server # [ 35.515186] systemd[1]: Started Nix Daemon instance (PID 1304/UID 0).2082server # [ 35.611637] nix-daemon[1308]: remote pid 1304 is unknown user (trusted)2083server # this derivation will be built:2084server # /nix/store/ldlfjwl576hkm4b5ag3b447nnn59cwiw-oidc-test2.drv2085server # building '/nix/store/ldlfjwl576hkm4b5ag3b447nnn59cwiw-oidc-test2.drv'...2086server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link, in 0.33 seconds)2087server: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:otherorg/repo:ref:refs/heads/main&aud=http://server:5751&repository_owner=otherorg'2088server # [ 35.754618] systemd[1]: nix-daemon@10-11-1304_1305-0.service: Deactivated successfully.2089server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:otherorg/repo:ref:refs/heads/main&aud=http://server:5751&repository_owner=otherorg', in 0.05 seconds)2090server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODc4MjM3MjcsImlhdCI6MTc4NzgyMDEyNywiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.QMwNFEfCsvsEGTTowQKNRWQQYsVJXKe23kLsoop1LGGLn_jvKoznjq4IMFHho38BzB8C7t4Lh2e8AImoXlz7EsGjFXeWmAMqQypvJUxqgSYhqqPg_kpAvZs2e641kG1zAYuqEZyQqZy3VQPYuo26dnM829-ztGmRSWLoSIiI5mObHMbE5k5zBRyIjTu5GDRVDsfajqIJm6IbvEQWyWzvXwByPdSJdS95Tfx72IzG1omQplyj9FQGfUStr0M4syHDUPmN13oJH7ghFJoZQyBFdwNs0Bql6EfOC-KnQgyn2ORNiOBg1D7by-WwyKFCVVxFyz5Fl8QcMViE8if9CNcQtg' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22091server # time=2026-08-27T08:42:07.450Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2092server # [ 35.969954] systemd[1]: Started Nix Daemon instance (PID 1337/UID 0).2093server # [ 36.052775] nix-daemon[1339]: remote pid 1337 is unknown user (trusted)2094server # [ 36.069808] systemd[1]: nix-daemon@11-12-1337_1338-0.service: Deactivated successfully.2095server # [ 36.078787] niks3-server[947]: 2026/08/27 08:42:07 WARN Authentication failed token_preview=eyJhbGciOi...8if9CNcQtg token_length=682 oidc_error="bound claims validation failed: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2096server # time=2026-08-27T08:42:07.702Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2097server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODc4MjM3MjcsImlhdCI6MTc4NzgyMDEyNywiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.QMwNFEfCsvsEGTTowQKNRWQQYsVJXKe23kLsoop1LGGLn_jvKoznjq4IMFHho38BzB8C7t4Lh2e8AImoXlz7EsGjFXeWmAMqQypvJUxqgSYhqqPg_kpAvZs2e641kG1zAYuqEZyQqZy3VQPYuo26dnM829-ztGmRSWLoSIiI5mObHMbE5k5zBRyIjTu5GDRVDsfajqIJm6IbvEQWyWzvXwByPdSJdS95Tfx72IzG1omQplyj9FQGfUStr0M4syHDUPmN13oJH7ghFJoZQyBFdwNs0Bql6EfOC-KnQgyn2ORNiOBg1D7by-WwyKFCVVxFyz5Fl8QcMViE8if9CNcQtg' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.28 seconds)2098server: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://wrong:5751&repository_owner=myorg'2099server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://wrong:5751&repository_owner=myorg', in 0.05 seconds)2100server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc4NzgyMzcyNywiaWF0IjoxNzg3ODIwMTI3LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.BS1AJRDJcYgZiZhfgjOvsvUmlh9WP5S31N6HzaS1hOrcQpkOXp9rQ13G3VA5m4jrapfjq2_14AOI2sgCtxKl4xE4E2pxQgV9W0EzLYTF6P9__cEW7bxMYi9e2g9efHz4_g1ascsJH720OMWOWjV3t_P9oxIyOe7lWJJxn7QHdgMIyNuku_vk-CAWZ8dKNYfaVCOjJBHrc5ErwhkFzYpPGM6fsp5S4urwkpVhfW_iyhjOzALgVXwM9OapVYztBfX_wGHRmrQAiazwClkHCSGgTKvcysjiRBW8IcBSs8KvVF2W7F0Rwye0OcGVVaN5_wRz6RGOp6gNAoBIBw6TNpKgOA' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22101server # time=2026-08-27T08:42:07.777Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2102server # [ 36.289675] systemd[1]: Started Nix Daemon instance (PID 1361/UID 0).2103server # [ 36.375413] nix-daemon[1363]: remote pid 1361 is unknown user (trusted)2104server # [ 36.393231] systemd[1]: nix-daemon@12-13-1361_1362-0.service: Deactivated successfully.2105server # [ 36.401546] niks3-server[947]: 2026/08/27 08:42:08 WARN Authentication failed token_preview=eyJhbGciOi...Bw6TNpKgOA token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2106server # time=2026-08-27T08:42:08.024Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2107server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc4NzgyMzcyNywiaWF0IjoxNzg3ODIwMTI3LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.BS1AJRDJcYgZiZhfgjOvsvUmlh9WP5S31N6HzaS1hOrcQpkOXp9rQ13G3VA5m4jrapfjq2_14AOI2sgCtxKl4xE4E2pxQgV9W0EzLYTF6P9__cEW7bxMYi9e2g9efHz4_g1ascsJH720OMWOWjV3t_P9oxIyOe7lWJJxn7QHdgMIyNuku_vk-CAWZ8dKNYfaVCOjJBHrc5ErwhkFzYpPGM6fsp5S4urwkpVhfW_iyhjOzALgVXwM9OapVYztBfX_wGHRmrQAiazwClkHCSGgTKvcysjiRBW8IcBSs8KvVF2W7F0Rwye0OcGVVaN5_wRz6RGOp6gNAoBIBw6TNpKgOA' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.28 seconds)2108server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22109server # time=2026-08-27T08:42:08.052Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2110server # [ 36.564752] systemd[1]: Started Nix Daemon instance (PID 1381/UID 0).2111server # [ 36.647479] nix-daemon[1383]: remote pid 1381 is unknown user (trusted)2112server # [ 36.665198] systemd[1]: nix-daemon@13-14-1381_1382-0.service: Deactivated successfully.2113server # [ 36.675165] niks3-server[947]: 2026/08/27 08:42:08 WARN Authentication failed token_preview=not-a-valid-jwt-token token_length=21 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2114server # time=2026-08-27T08:42:08.298Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2115server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.27 seconds)2116server: must succeed: 2117 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins create hello-pin /nix/store/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.321182119server # [ 36.775122] niks3-server[947]: 2026/08/27 08:42:08 INFO Received create pin request method=POST path=/api/pins/hello-pin2120server # time=2026-08-27T08:42:08.407Z level=INFO msg="Created pin" name=hello-pin store_path=/nix/store/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.32121server # [ 36.789315] niks3-server[947]: 2026/08/27 08:42:08 INFO Created/updated pin name=hello-pin store_path=/nix/store/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.3 narinfo_key=4z8ys247h2242760a0pw6y9akab2n09l.narinfo2122server: (finished: must succeed: 2123 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins create hello-pin /nix/store/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.32124, in 0.11 seconds)2125server: must succeed: 2126 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins list21272128server # [ 36.926087] niks3-server[947]: 2026/08/27 08:42:08 INFO Received list pins request method=GET path=/api/pins2129server: (finished: must succeed: 2130 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins list2131, in 0.14 seconds)2132server: must succeed: 2133 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins list --names-only21342135server # [ 37.032597] niks3-server[947]: 2026/08/27 08:42:08 INFO Received list pins request method=GET path=/api/pins2136server: (finished: must succeed: 2137 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins list --names-only2138, in 0.11 seconds)2139server: must succeed: 2140 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins list --json21412142server # [ 37.134794] niks3-server[947]: 2026/08/27 08:42:08 INFO Received list pins request method=GET path=/api/pins2143server: (finished: must succeed: 2144 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins list --json2145, in 0.10 seconds)2146server: must succeed: 2147 export S3_ENDPOINT_URL=http://localhost:90002148 export AWS_ACCESS_KEY_ID=rustfsadmin2149 export AWS_SECRET_ACCESS_KEY=rustfsadmin2150 /nix/store/q2hw52as72yn9rb580i0qjddq0qvgdn3-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin21512152server: (finished: must succeed: 2153 export S3_ENDPOINT_URL=http://localhost:90002154 export AWS_ACCESS_KEY_ID=rustfsadmin2155 export AWS_SECRET_ACCESS_KEY=rustfsadmin2156 /nix/store/q2hw52as72yn9rb580i0qjddq0qvgdn3-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin2157, in 0.04 seconds)2158server: must succeed: 2159 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push --pin ca-pin /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log21602161server # [ 37.324822] systemd[1]: Started Nix Daemon instance (PID 1458/UID 0).2162server # [ 37.410928] nix-daemon[1460]: remote pid 1458 is unknown user (trusted)2163server # [ 37.428746] systemd[1]: nix-daemon@14-15-1458_1459-0.service: Deactivated successfully.2164server # [ 37.437483] niks3-server[947]: 2026/08/27 08:42:09 INFO Received uploads request method=POST path=/api/pending_closures2165server # time=2026-08-27T08:42:09.061Z level=INFO msg="Uploading 0 paths to server (1 already cached)"2166server # [ 37.442508] niks3-server[947]: 2026/08/27 08:42:09 INFO Received complete upload request method=POST path=/api/pending_closures/7/complete2167server # time=2026-08-27T08:42:09.065Z level=INFO msg="Upload complete. (186ms)"2168server # [ 37.447056] niks3-server[947]: 2026/08/27 08:42:09 INFO Completed upload id=72169server # [ 37.449779] niks3-server[947]: 2026/08/27 08:42:09 INFO Received create pin request method=POST path=/api/pins/ca-pin2170server # time=2026-08-27T08:42:09.078Z level=INFO msg="Created pin" name=ca-pin store_path=/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2171server # [ 37.460083] niks3-server[947]: 2026/08/27 08:42:09 INFO Created/updated pin name=ca-pin store_path=/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log narinfo_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.narinfo2172server: (finished: must succeed: 2173 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 push --pin ca-pin /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2174, in 0.29 seconds)2175server: must succeed: 2176 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins list --names-only21772178server # [ 37.560582] niks3-server[947]: 2026/08/27 08:42:09 INFO Received list pins request method=GET path=/api/pins2179server: (finished: must succeed: 2180 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins list --names-only2181, in 0.10 seconds)2182server: must succeed: 2183 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins delete hello-pin21842185server # [ 37.658577] niks3-server[947]: 2026/08/27 08:42:09 INFO Received delete pin request method=DELETE path=/api/pins/hello-pin2186server # time=2026-08-27T08:42:09.286Z level=INFO msg="Deleted pin" name=hello-pin2187server # [ 37.667595] niks3-server[947]: 2026/08/27 08:42:09 INFO Deleted pin name=hello-pin2188server: (finished: must succeed: 2189 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins delete hello-pin2190, in 0.11 seconds)2191server: must succeed: 2192 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins list --names-only21932194server # [ 37.773863] niks3-server[947]: 2026/08/27 08:42:09 INFO Received list pins request method=GET path=/api/pins2195server: (finished: must succeed: 2196 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins list --names-only2197, in 0.11 seconds)2198server: must fail: 2199 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent22002201server # [ 37.872956] niks3-server[947]: 2026/08/27 08:42:09 INFO Received create pin request method=POST path=/api/pins/bad-pin2202server # time=2026-08-27T08:42:09.494Z level=ERROR msg="Fatal error" error="creating pin: server returned 404: closure not found: store path must be pushed before pinning\n"2203server # [ 37.876859] niks3-server[947]: 2026/08/27 08:42:09 ERROR Failed to get closure for pin narinfo_key=aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.narinfo error="no rows in result set"2204server: (finished: must fail: 2205 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/pg2x7zfxaz3sg9iccz9hsnx9mmlaa3b1-niks3-1.8.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent2206, in 0.10 seconds)2207server: must succeed: systemctl start niks3-gc.service2208server # [ 37.915981] systemd[1]: Starting niks3 garbage collection...2209server # [ 37.984777] niks3[1518]: time=2026-08-27T08:42:09.604Z level=INFO msg="Starting garbage collection" older-than=720h failed-uploads-older-than=6h force=false2210server # [ 37.989640] niks3-server[947]: 2026/08/27 08:42:09 INFO Starting cleanup of old closures method=DELETE path=/api/closures2211server # [ 37.993974] niks3[1518]: time=2026-08-27T08:42:09.612Z level=INFO msg="Garbage collection started"2212server # [ 37.997632] niks3-server[947]: 2026/08/27 08:42:09 INFO Aborted multipart uploads count=02213server # [ 38.007135] niks3-server[947]: 2026/08/27 08:42:09 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=2 objects-deleted-after-grace-period=0 objects-failed-to-delete=02214server # [ 38.015740] niks3-server[947]: 2026/08/27 08:42:09 INFO Vacuumed table table=pending_closures2215server # [ 38.022846] niks3-server[947]: 2026/08/27 08:42:09 INFO Vacuumed table table=pending_objects2216server # [ 38.029528] niks3-server[947]: 2026/08/27 08:42:09 INFO Vacuumed table table=multipart_uploads2217server # [ 38.033693] niks3-server[947]: 2026/08/27 08:42:09 INFO Vacuumed table table=closures2218server # [ 38.038691] niks3-server[947]: 2026/08/27 08:42:09 INFO Vacuumed table table=objects2219server # [ 39.995602] niks3[1518]: time=2026-08-27T08:42:11.614Z level=INFO msg="Garbage collection progress" phase="" failed_uploads_deleted=0 old_closures_deleted=0 objects_marked=2 objects_deleted=0 objects_failed=02220server # [ 40.005779] niks3[1518]: time=2026-08-27T08:42:11.614Z level=INFO msg="Garbage collection completed successfully" failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=2 objects-deleted-after-grace-period=0 objects-failed-to-delete=02221server # [ 40.021225] systemd[1]: niks3-gc.service: Deactivated successfully.2222server # [ 40.032601] systemd[1]: Finished niks3 garbage collection.2223server # [ 40.035186] systemd[1]: niks3-gc.service: Consumed 41ms CPU time over 2.109s wall clock time, 2.4M memory peak, 1.2K incoming IP traffic, 823B outgoing IP traffic.2224server: (finished: must succeed: systemctl start niks3-gc.service, in 2.17 seconds)2225builder: waiting for unit niks3-auto-upload.socket2226builder: waiting for the VM to finish booting2227builder: Guest shell says: b'Spawning backdoor root shell...\n'2228builder: connected to guest root shell2229builder: (connecting took 0.00 seconds)2230builder: (finished: waiting for the VM to finish booting, in 0.00 seconds)2231builder: (finished: waiting for unit niks3-auto-upload.socket, in 0.11 seconds)2232builder: must succeed: test -S /run/niks3/upload-to-cache.sock2233builder: (finished: must succeed: test -S /run/niks3/upload-to-cache.sock, in 0.02 seconds)2234builder: must succeed: grep post-build-hook /etc/nix/nix.conf2235builder: (finished: must succeed: grep post-build-hook /etc/nix/nix.conf, in 0.03 seconds)2236builder: must succeed: 2237cat > /tmp/test-drv.nix << 'EOF'2238derivation {2239 name = "post-build-hook-test";2240 system = builtins.currentSystem;2241 builder = "/bin/sh";2242 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2243}2244EOF22452246builder: (finished: must succeed: 2247cat > /tmp/test-drv.nix << 'EOF'2248derivation {2249 name = "post-build-hook-test";2250 system = builtins.currentSystem;2251 builder = "/bin/sh";2252 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2253}2254EOF2255, in 0.03 seconds)2256builder: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link2257builder # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2258builder # [ 40.373731] systemd[1]: Created slice Slice /system/nix-daemon.2259builder # [ 40.378795] systemd[1]: Started Nix Daemon instance (PID 784/UID 0).2260builder # [ 40.467281] nix-daemon[788]: remote pid 784 is unknown user (trusted)2261builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 499 ms (attempt 1/5)2262builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 1002 ms (attempt 2/5)2263builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 1893 ms (attempt 3/5)2264builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 3890 ms (attempt 4/5)2265builder # warning: Failed to setup the substituter at URI 'https://cache.nixos.org/': error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6)2266builder # this derivation will be built:2267builder # /nix/store/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv2268builder # building '/nix/store/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv'...2269builder # [ 48.081339] systemd[1]: Started niks3 auto-upload daemon.2270builder # [ 48.254095] niks3-hook[810]: time=2026-08-27T08:42:19.865Z level=INFO msg="niks3-hook serve starting" socket=/run/niks3/upload-to-cache.sock socket-activated=true db-path=/var/lib/niks3-hook/upload-queue.db batch-size=5 idle-exit-timeout=5s2271builder # [ 48.264852] niks3-hook[810]: time=2026-08-27T08:42:19.876Z level=INFO msg="Upload queue status" pending=12272builder # [ 48.266247] niks3-hook[810]: time=2026-08-27T08:42:19.876Z level=INFO msg="Uploading batch" count=12273builder: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link, in 8.05 seconds)2274builder: waiting for unit niks3-auto-upload.service2275builder # [ 48.293476] systemd[1]: nix-daemon@0-1-784_785-0.service: Deactivated successfully.2276builder # [ 48.296373] systemd[1]: nix-daemon@0-1-784_785-0.service: Consumed 196ms CPU time over 7.916s wall clock time, 17.6M memory peak, 288B outgoing IP traffic.2277builder: (finished: waiting for unit niks3-auto-upload.service, in 0.13 seconds)2278builder: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive2279builder # [ 48.429408] systemd[1]: Started Nix Daemon instance (PID 820/UID 0).2280builder # [ 48.530095] nix-daemon[830]: remote pid 820 is unknown user (trusted)2281builder # [ 48.548106] systemd[1]: nix-daemon@1-2-820_821-0.service: Deactivated successfully.2282server # [ 48.585019] niks3-server[947]: 2026/08/27 08:42:20 INFO Received uploads request method=POST path=/api/pending_closures2283builder # [ 48.605398] niks3-hook[810]: time=2026-08-27T08:42:20.217Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2284builder # [ 48.608381] niks3-hook[810]: time=2026-08-27T08:42:20.220Z level=INFO msg="Uploading 1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test (144B)"2285server # [ 48.660168] niks3-server[947]: 2026/08/27 08:42:20 INFO Registered completed upload object_key=log/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv2286server # [ 48.670174] niks3-server[947]: 2026/08/27 08:42:20 INFO Registered completed upload object_key=nar/1a0vfw93shmvlz6lac7d1lv82jxzfdswigh5s32hpvncf74i1i0y.nar.zst2287server # [ 48.686867] niks3-server[947]: 2026/08/27 08:42:20 INFO Registered completed upload object_key=1qlca7drab1x7rccc3h1pw1fl9c0j7yx.ls2288server # [ 48.692831] niks3-server[947]: 2026/08/27 08:42:20 INFO Received sign narinfos request method=POST path=/api/pending_closures/8/sign2289builder # [ 48.692151] niks3-hook[810]: time=2026-08-27T08:42:20.302Z level=INFO msg="Uploading 1 narinfos"2290server # [ 48.696583] niks3-server[947]: 2026/08/27 08:42:20 INFO Signed narinfos id=8 count=12291server # [ 48.710212] niks3-server[947]: 2026/08/27 08:42:20 INFO Registered completed upload object_key=1qlca7drab1x7rccc3h1pw1fl9c0j7yx.narinfo2292server # [ 48.719439] niks3-server[947]: 2026/08/27 08:42:20 INFO Received complete upload request method=POST path=/api/pending_closures/8/complete2293builder # [ 48.717487] niks3-hook[810]: time=2026-08-27T08:42:20.328Z level=INFO msg="Upload complete. (452ms)"2294server # [ 48.723420] niks3-server[947]: 2026/08/27 08:42:20 INFO Completed upload id=82295builder # [ 53.265931] niks3-hook[810]: time=2026-08-27T08:42:24.876Z level=INFO msg="Idle timeout reached and queue is empty, shutting down"2296builder # [ 53.272323] niks3-hook[810]: time=2026-08-27T08:42:24.883Z level=INFO msg="niks3-hook serve stopped"2297builder # [ 53.291315] systemd[1]: niks3-auto-upload.service: Deactivated successfully.2298builder # [ 53.299063] systemd[1]: niks3-auto-upload.service: Consumed 156ms CPU time over 5.209s wall clock time, 9.7M memory peak, 68K written to disk, 5.1K incoming IP traffic, 7.5K outgoing IP traffic.2299builder: (finished: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive, in 5.39 seconds)2300server: must succeed: 2301 export AWS_ACCESS_KEY_ID=rustfsadmin2302export AWS_SECRET_ACCESS_KEY=rustfsadmin2303 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/hook-test-store /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test23042305server: (finished: must succeed: 2306 export AWS_ACCESS_KEY_ID=rustfsadmin2307export AWS_SECRET_ACCESS_KEY=rustfsadmin2308 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/hook-test-store /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test2309, in 0.28 seconds)2310server: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test2311server: (finished: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test, in 0.09 seconds)2312(finished: run the VM test script, in 55.15 seconds)2313test script finished in 55.28s2314cleanup2315kill QemuMachine (pid 47)2316builder # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/41m77i1296n33p6liin8ynr6wh3h6b7m-python3-3.14.6/bin/python3.14)2317kill QemuMachine (pid 48)2318server # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/41m77i1296n33p6liin8ynr6wh3h6b7m-python3-3.14.6/bin/python3.14)2319(finished: cleanup, in 0.46 seconds)2320additionally exposed symbols:2321 builder, server,2322 vlan1,2323 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_ssh2324Hello store path: /nix/store/4z8ys247h2242760a0pw6y9akab2n09l-hello-2.12.32325Test output path: /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2326Symlink wrapper store path: /nix/store/kbz6jfpcar5d9kjay3nvfyz02dphnbkq-symlink-wrapper2327Symlink wrapper points to: /nix/store/7rr22ycj7650jkmllrl1sg9m9rbbi8yr-base-package/bin/test-program2328OIDC test store path: /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test2329Valid OIDC token obtained (length=677)2330OIDC push with valid token: SUCCESS2331Invalid OIDC token obtained (wrong org)2332OIDC push with wrong org: correctly rejected2333Wrong audience OIDC token obtained2334OIDC push with wrong audience: correctly rejected2335OIDC push with malformed token: correctly rejected2336All OIDC tests passed!2337All pin tests passed!2338Built store path: /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test2339Post-build-hook pipeline test passed!