vm-test-run-nixos-test-niks3
checks.aarch64-linux.nixos-test-niks3-lix
· build #242
· 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.0 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)12builder # Formatting '/build/vm-state-builder/tmp.KjcufZ40Sb', fmt=raw size=107374182413builder # mke2fs 1.47.4 (6-Mar-2025)14server # Disk image does not exist, creating the virtualisation disk image...15builder # Discarding device blocks: 0/262144 done16server # Formatting '/build/vm-state-server/tmp.nSs2dcg6Nn', fmt=raw size=107374182417builder # Creating filesystem with 262144 4k blocks and 65536 inodes18server # mke2fs 1.47.4 (6-Mar-2025)19builder # Filesystem UUID: f6cb108f-9094-42f6-84e8-bb0934fa233520server # Discarding device blocks: 0/262144 done21builder # Superblock backups stored on blocks:22server # Creating filesystem with 262144 4k blocks and 65536 inodes23builder # 32768, 98304, 163840, 22937624server # Filesystem UUID: 35348747-4d43-47ce-b24f-a52e7f4cc6c225builder # 26server # Superblock backups stored on blocks:27builder # Allocating group tables: 0/8 done28server # 32768, 98304, 163840, 22937629builder # Writing inode tables: 0/8 done30server # 31builder # Creating journal (8192 blocks): done32server # Allocating group tables: 0/8 done33builder # Writing superblocks and filesystem accounting information: 0/8 done34server # Writing inode tables: 0/8 done35builder # 36server # Creating journal (8192 blocks): done37builder # Virtualisation disk image created.38server # Writing superblocks and filesystem accounting information: 0/8 done39builder # Starting virtiofs daemons...40server # 41builder # [2026-09-21T21:30:30Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)42server # Virtualisation disk image created.43builder # [2026-09-21T21:30:30Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether44server # Starting virtiofs daemons...45builder # [2026-09-21T21:30:30Z INFO virtiofsd] Waiting for vhost-user socket connection...46server # [2026-09-21T21:30:30Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)47builder # [2026-09-21T21:30:30Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)48server # [2026-09-21T21:30:30Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether49builder # [2026-09-21T21:30:30Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether50server # [2026-09-21T21:30:30Z INFO virtiofsd] Waiting for vhost-user socket connection...51builder # [2026-09-21T21:30:30Z INFO virtiofsd] Waiting for vhost-user socket connection...52server # [2026-09-21T21:30:30Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)53builder # [2026-09-21T21:30:30Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)54server # [2026-09-21T21:30:30Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether55builder # [2026-09-21T21:30:30Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether56server # [2026-09-21T21:30:30Z INFO virtiofsd] Waiting for vhost-user socket connection...57builder # [2026-09-21T21:30:30Z INFO virtiofsd] Waiting for vhost-user socket connection...58server # [2026-09-21T21:30:30Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)59builder # [2026-09-21T21:30:30Z INFO virtiofsd] Client connected, servicing requests60server # [2026-09-21T21:30:30Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether61builder # [2026-09-21T21:30:30Z INFO virtiofsd] Client connected, servicing requests62server # [2026-09-21T21:30:30Z INFO virtiofsd] Waiting for vhost-user socket connection...63builder # [2026-09-21T21:30:30Z INFO virtiofsd] Client connected, servicing requests64server # [2026-09-21T21:30:30Z INFO virtiofsd] Client connected, servicing requests65server: QEMU running (pid 48)66server # [2026-09-21T21:30:30Z INFO virtiofsd] Client connected, servicing requests67server # [2026-09-21T21:30:30Z INFO virtiofsd] Client connected, servicing requests68(finished: start all VMs, in 0.48 seconds)69server: waiting for unit postgresql.service70server: waiting for the VM to finish booting71builder # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]72builder # [ 0.000000] Linux version 6.18.51 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 11 09:49:46 UTC 202673builder # [ 0.000000] KASLR enabled74builder # [ 0.000000] random: crng init done75builder # [ 0.000000] Machine model: linux,dummy-virt76builder # [ 0.000000] efi: UEFI not found.77builder # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT78builder # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]79builder # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]80builder # [ 0.000000] Zone ranges:81builder # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]82builder # [ 0.000000] DMA32 empty83builder # [ 0.000000] Normal empty84builder # [ 0.000000] Device empty85builder # [ 0.000000] Movable zone start for each node86builder # [ 0.000000] Early memory node ranges87builder # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]88builder # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]89builder # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000090builder # [ 0.000000] psci: probing for conduit method from DT.91builder # [ 0.000000] psci: PSCIv1.3 detected in firmware.92builder # [ 0.000000] psci: Using standard PSCI v0.2 function IDs93builder # [ 0.000000] psci: Trusted OS migration not required94builder # [ 0.000000] psci: SMC Calling Convention v1.195builder # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)96builder # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u31129697builder # [ 0.000000] Detected PIPT I-cache on CPU098builder # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)99builder # [ 0.000000] CPU features: detected: GICv3 CPU interface100builder # [ 0.000000] CPU features: detected: Spectre-v4101builder # [ 0.000000] CPU features: detected: Spectre-BHB102builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38103builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23104builder # [ 0.000000] alternatives: applying boot alternatives105builder # [ 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/rcnlmkx8r7zl26s9nq030kr7gi13c88c-nixos-system-builder-test/init regInfo=/nix/store/r23s4qhn6yjdryzhla1w5wx854fddidf-closure-info/registration console=ttyAMA0,115200n8 console=tty0106builder # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/r23s4qhn6yjdryzhla1w5wx854fddidf-closure-info/registration", will be passed to user space.107builder # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes108builder # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)109builder # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)110builder # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB111builder # [ 0.000000] software IO TLB: area num 1.112builder # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)113builder # [ 0.000000] Fallback order for Node 0: 0114builder # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144115builder # [ 0.000000] Policy zone: DMA116builder # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off117builder # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1118builder # [ 0.000000] allocated 2097152 bytes of page_ext119builder # [ 0.000000] ftrace: allocating 74894 entries in 294 pages120builder # [ 0.000000] ftrace: allocated 294 pages with 4 groups121builder # [ 0.000000] rcu: Hierarchical RCU implementation.122builder # [ 0.000000] rcu: RCU event tracing is enabled.123builder # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.124server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]125builder # [ 0.000000] Trampoline variant of Tasks RCU enabled.126builder # [ 0.000000] Rude variant of Tasks RCU enabled.127builder # [ 0.000000] Tracing variant of Tasks RCU enabled.128server # [ 0.000000] Linux version 6.18.51 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 11 09:49:46 UTC 2026129server # [ 0.000000] KASLR enabled130server # [ 0.000000] random: crng init done131builder # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.132server # [ 0.000000] Machine model: linux,dummy-virt133server # [ 0.000000] efi: UEFI not found.134builder # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1135server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT136builder # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.137server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]138builder # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.139server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]140server # [ 0.000000] Zone ranges:141builder # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.142server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]143server # [ 0.000000] DMA32 empty144builder # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0145server # [ 0.000000] Normal empty146builder # [ 0.000000] GICv3: 256 SPIs implemented147server # [ 0.000000] Device empty148builder # [ 0.000000] GICv3: 0 Extended SPIs implemented149server # [ 0.000000] Movable zone start for each node150builder # [ 0.000000] Root IRQ handler: gic_handle_irq151server # [ 0.000000] Early memory node ranges152builder # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI153server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]154builder # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0155server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]156builder # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000157server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000158builder # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]159server # [ 0.000000] psci: probing for conduit method from DT.160server # [ 0.000000] psci: PSCIv1.3 detected in firmware.161builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)162server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs163server # [ 0.000000] psci: Trusted OS migration not required164builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)165server # [ 0.000000] psci: SMC Calling Convention v1.1166builder # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000167server # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)168builder # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000169server # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u311296170builder # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.171server # [ 0.000000] Detected PIPT I-cache on CPU0172builder # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns173server # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)174builder # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).175server # [ 0.000000] CPU features: detected: GICv3 CPU interface176server # [ 0.000000] CPU features: detected: Spectre-v4177server # [ 0.000000] CPU features: detected: Spectre-BHB178builder # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns179server # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38180builder # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns181server # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23182builder # [ 0.000033] arm-pv: using stolen time PV183server # [ 0.000000] alternatives: applying boot alternatives184builder # [ 0.000472] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)185builder # [ 0.000630] Console: colour dummy device 80x25186builder # [ 0.000637] printk: legacy console [tty0] enabled187builder # [ 0.000832] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)188builder # [ 0.000839] pid_max: default: 32768 minimum: 301189server # [ 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/lv6fvz5f97s6arpm7s0261cr5fnbhyf7-nixos-system-server-test/init regInfo=/nix/store/gi14v0sxvmk68ar9a25zn1a112k2yj8j-closure-info/registration console=ttyAMA0,115200n8 console=tty0190builder # [ 0.000916] LSM: initializing lsm=capability,landlock,yama,bpf,ima191builder # [ 0.001054] landlock: Up and running.192builder # [ 0.001057] Yama: becoming mindful.193builder # [ 0.001562] LSM support for eBPF active194server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/gi14v0sxvmk68ar9a25zn1a112k2yj8j-closure-info/registration", will be passed to user space.195builder # [ 0.001705] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)196server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes197builder # [ 0.001725] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)198server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)199builder # [ 0.002834] cacheinfo: Unable to detect cache hierarchy for CPU 0200server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)201builder # [ 0.003588] rcu: Hierarchical SRCU implementation.202builder # [ 0.003592] rcu: Max phase no-delay instances is 1000.203server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB204server # [ 0.000000] software IO TLB: area num 1.205builder # [ 0.004830] fsl-mc MSI: its@8080000 domain created206builder # [ 0.004919] EFI services will not be available.207server # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)208builder # [ 0.004994] smp: Bringing up secondary CPUs ...209server # [ 0.000000] Fallback order for Node 0: 0210builder # [ 0.005002] smp: Brought up 1 node, 1 CPU211builder # [ 0.005005] SMP: Total of 1 processors activated.212server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144213server # [ 0.000000] Policy zone: DMA214builder # [ 0.005008] CPU: All CPU(s) started at EL1215builder # [ 0.005022] CPU features: detected: Branch Target Identification216server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off217builder # [ 0.005027] CPU features: detected: ARMv8.4 Translation Table Level218server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1219server # [ 0.000000] allocated 2097152 bytes of page_ext220builder # [ 0.005030] CPU features: detected: Instruction cache invalidation not required for I/D coherence221server # [ 0.000000] ftrace: allocating 74894 entries in 294 pages222server # [ 0.000000] ftrace: allocated 294 pages with 4 groups223builder # [ 0.005034] CPU features: detected: Data cache clean to the PoU not required for I/D coherence224server # [ 0.000000] rcu: Hierarchical RCU implementation.225builder # [ 0.005037] CPU features: detected: Common not Private translations226server # [ 0.000000] rcu: RCU event tracing is enabled.227builder # [ 0.005040] CPU features: detected: CRC32 instructions228server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.229builder # [ 0.005043] CPU features: detected: Data cache clean to Point of Deep Persistence230server # [ 0.000000] Trampoline variant of Tasks RCU enabled.231server # [ 0.000000] Rude variant of Tasks RCU enabled.232builder # [ 0.005047] CPU features: detected: Data cache clean to Point of Persistence233server # [ 0.000000] Tracing variant of Tasks RCU enabled.234builder # [ 0.005050] CPU features: detected: Data independent timing control (DIT)235server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.236builder # [ 0.005053] CPU features: detected: E0PD237builder # [ 0.005055] CPU features: detected: Enhanced Counter Virtualization238server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1239builder # [ 0.005058] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)240server # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.241builder # [ 0.005062] CPU features: detected: Enhanced Virtualization Traps242server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.243builder # [ 0.005065] CPU features: detected: Fine Grained Traps244builder # [ 0.005069] CPU features: detected: Generic authentication (architected QARMA5 algorithm)245server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.246builder # [ 0.005074] CPU features: detected: RCpc load-acquire (LDAPR)247server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0248server # [ 0.000000] GICv3: 256 SPIs implemented249builder # [ 0.005077] CPU features: detected: LSE atomic instructions250server # [ 0.000000] GICv3: 0 Extended SPIs implemented251builder # [ 0.005080] CPU features: detected: Privileged Access Never252server # [ 0.000000] Root IRQ handler: gic_handle_irq253builder # [ 0.005082] CPU features: detected: PMUv3254server # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI255builder # [ 0.005085] CPU features: detected: RAS Extension Support256server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0257builder # [ 0.005088] CPU features: detected: RASv1p1 Extension Support258server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000259builder # [ 0.005090] CPU features: detected: Random Number Generator260server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]261builder # [ 0.005093] CPU features: detected: Speculation barrier (SB)262server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)263builder # [ 0.005096] CPU features: detected: Stage-2 Force Write-Back264builder # [ 0.005099] CPU features: detected: TLB range maintenance instructions265server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)266builder # [ 0.005104] CPU features: detected: Speculative Store Bypassing Safe (SSBS)267server # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000268builder # [ 0.005142] alternatives: applying system-wide alternatives269server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000270builder # [ 0.008051] CPU features: detected: BBM Level 2 without TLB conflict abort271server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.272server # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns273server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).274server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns275server # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns276server # [ 0.000029] arm-pv: using stolen time PV277server # [ 0.000391] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)278server # [ 0.000571] Console: colour dummy device 80x25279server # [ 0.000579] printk: legacy console [tty0] enabled280server # [ 0.000800] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)281server # [ 0.000807] pid_max: default: 32768 minimum: 301282builder # [ 0.008218] Memory: 893500K/1048576K available (24448K kernel code, 7090K rwdata, 26360K rodata, 4736K init, 1107K bss, 113760K reserved, 32768K cma-reserved)283server # [ 0.000879] LSM: initializing lsm=capability,landlock,yama,bpf,ima284builder # [ 0.008559] devtmpfs: initialized285server # [ 0.001049] landlock: Up and running.286server # [ 0.001052] Yama: becoming mindful.287builder # [ 0.010273] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)288server # [ 0.001500] LSM support for eBPF active289builder # [ 0.010296] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).290server # [ 0.001629] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)291builder # [ 0.010478] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL292server # [ 0.001653] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)293builder # [ 0.010483] 0 pages in range for non-PLT usage294server # [ 0.002809] cacheinfo: Unable to detect cache hierarchy for CPU 0295builder # [ 0.010484] 508288 pages in range for PLT usage296server # [ 0.003517] rcu: Hierarchical SRCU implementation.297builder # [ 0.010591] pinctrl core: initialized pinctrl subsystem298server # [ 0.003521] rcu: Max phase no-delay instances is 1000.299builder # [ 0.011383] DMI not present or invalid.300server # [ 0.004779] fsl-mc MSI: its@8080000 domain created301builder # [ 0.014580] NET: Registered PF_NETLINK/PF_ROUTE protocol family302server # [ 0.004870] EFI services will not be available.303builder # [ 0.016891] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations304server # [ 0.004936] smp: Bringing up secondary CPUs ...305server # [ 0.004945] smp: Brought up 1 node, 1 CPU306builder # [ 0.017026] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations307server # [ 0.004948] SMP: Total of 1 processors activated.308server # [ 0.004951] CPU: All CPU(s) started at EL1309builder # [ 0.017183] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations310server # [ 0.004964] CPU features: detected: Branch Target Identification311builder # [ 0.017205] audit: initializing netlink subsys (disabled)312server # [ 0.004969] CPU features: detected: ARMv8.4 Translation Table Level313builder # [ 0.017715] thermal_sys: Registered thermal governor 'fair_share'314builder # [ 0.017717] thermal_sys: Registered thermal governor 'bang_bang'315server # [ 0.004972] CPU features: detected: Instruction cache invalidation not required for I/D coherence316builder # [ 0.017721] thermal_sys: Registered thermal governor 'step_wise'317server # [ 0.004976] CPU features: detected: Data cache clean to the PoU not required for I/D coherence318builder # [ 0.017724] thermal_sys: Registered thermal governor 'user_space'319server # [ 0.004979] CPU features: detected: Common not Private translations320builder # [ 0.017729] thermal_sys: Registered thermal governor 'power_allocator'321server # [ 0.004983] CPU features: detected: CRC32 instructions322builder # [ 0.017760] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1323server # [ 0.004986] CPU features: detected: Data cache clean to Point of Deep Persistence324builder # [ 0.017768] cpuidle: using governor ladder325builder # [ 0.017774] cpuidle: using governor menu326server # [ 0.004989] CPU features: detected: Data cache clean to Point of Persistence327builder # [ 0.017980] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.328server # [ 0.004992] CPU features: detected: Data independent timing control (DIT)329builder # [ 0.017996] ASID allocator initialised with 65536 entries330server # [ 0.004995] CPU features: detected: E0PD331builder # [ 0.019194] Serial: AMBA PL011 UART driver332server # [ 0.004998] CPU features: detected: Enhanced Counter Virtualization333builder # [ 0.024655] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1334server # [ 0.005001] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)335builder # [ 0.024806] printk: console [ttyAMA0] enabled336server # [ 0.005005] CPU features: detected: Enhanced Virtualization Traps337server # [ 0.005008] CPU features: detected: Fine Grained Traps338builder # [ 0.150003] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages339server # [ 0.005012] CPU features: detected: Generic authentication (architected QARMA5 algorithm)340builder # [ 0.150025] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page341server # [ 0.005017] CPU features: detected: RCpc load-acquire (LDAPR)342builder # [ 0.150030] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages343builder # [ 0.150035] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page344builder # [ 0.150039] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages345builder # [ 0.150043] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page346builder # [ 0.150048] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages347builder # [ 0.150052] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page348builder # [ 0.157582] fbcon: Taking over console349builder # [ 0.157597] ACPI: Interpreter disabled.350builder # [ 0.159602] iommu: Default domain type: Translated351server # [ 0.005020] CPU features: detected: LSE atomic instructions352builder # [ 0.159613] iommu: DMA domain TLB invalidation policy: strict mode353server # [ 0.005023] CPU features: detected: Privileged Access Never354builder # [ 0.161419] SCSI subsystem initialized355server # [ 0.005026] CPU features: detected: PMUv3356server # [ 0.005029] CPU features: detected: RAS Extension Support357server # [ 0.005031] CPU features: detected: RASv1p1 Extension Support358server # [ 0.005034] CPU features: detected: Random Number Generator359server # [ 0.005037] CPU features: detected: Speculation barrier (SB)360server # [ 0.005039] CPU features: detected: Stage-2 Force Write-Back361server # [ 0.005043] CPU features: detected: TLB range maintenance instructions362server # [ 0.005048] CPU features: detected: Speculative Store Bypassing Safe (SSBS)363server # [ 0.005085] alternatives: applying system-wide alternatives364server # [ 0.008084] CPU features: detected: BBM Level 2 without TLB conflict abort365builder # [ 0.168912] usbcore: registered new interface driver usbfs366server # [ 0.008237] Memory: 893512K/1048576K available (24448K kernel code, 7090K rwdata, 26360K rodata, 4736K init, 1107K bss, 113756K reserved, 32768K cma-reserved)367builder # [ 0.168942] usbcore: registered new interface driver hub368server # [ 0.008597] devtmpfs: initialized369builder # [ 0.168962] usbcore: registered new device driver usb370server # [ 0.010305] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)371builder # [ 0.169240] pps_core: LinuxPPS API ver. 1 registered372server # [ 0.010328] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).373builder # [ 0.169246] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>374builder # [ 0.169264] PTP clock support registered375server # [ 0.010516] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL376builder # [ 0.169321] EDAC MC: Ver: 3.0.0377server # [ 0.010521] 0 pages in range for non-PLT usage378server # [ 0.010522] 508288 pages in range for PLT usage379builder # [ 0.174029] scmi_core: SCMI protocol bus registered380server # [ 0.010637] pinctrl core: initialized pinctrl subsystem381server # [ 0.011382] DMI not present or invalid.382builder # [ 0.175000] FPGA manager framework383builder # [ 0.175997] vgaarb: loaded384server # [ 0.014498] NET: Registered PF_NETLINK/PF_ROUTE protocol family385server # [ 0.017037] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations386builder # [ 0.176645] clocksource: Switched to clocksource arch_sys_counter387server # [ 0.017201] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations388builder # [ 0.177244] VFS: Disk quotas dquot_6.6.0389server # [ 0.017362] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations390builder # [ 0.177272] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)391server # [ 0.017387] audit: initializing netlink subsys (disabled)392builder # [ 0.179739] netfs: FS-Cache loaded393builder # [ 0.179858] pnp: PnP ACPI: disabled394server # [ 0.017906] thermal_sys: Registered thermal governor 'fair_share'395server # [ 0.017908] thermal_sys: Registered thermal governor 'bang_bang'396server # [ 0.017912] thermal_sys: Registered thermal governor 'step_wise'397server # [ 0.017915] thermal_sys: Registered thermal governor 'user_space'398server # [ 0.017920] thermal_sys: Registered thermal governor 'power_allocator'399server # [ 0.017943] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1400server # [ 0.017952] cpuidle: using governor ladder401server # [ 0.017958] cpuidle: using governor menu402server # [ 0.018161] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.403server # [ 0.018177] ASID allocator initialised with 65536 entries404server # [ 0.019389] Serial: AMBA PL011 UART driver405server # [ 0.024497] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1406server # [ 0.024618] printk: console [ttyAMA0] enabled407builder # [ 0.185837] NET: Registered PF_INET protocol family408builder # [ 0.186051] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)409server # [ 0.149614] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages410server # [ 0.149634] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page411server # [ 0.149639] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages412server # [ 0.149643] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page413server # [ 0.149648] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages414server # [ 0.149652] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page415server # [ 0.149657] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages416server # [ 0.149661] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page417server # [ 0.157186] fbcon: Taking over console418server # [ 0.157200] ACPI: Interpreter disabled.419server # [ 0.165069] iommu: Default domain type: Translated420server # [ 0.165079] iommu: DMA domain TLB invalidation policy: strict mode421server # [ 0.166743] SCSI subsystem initialized422server # [ 0.167423] usbcore: registered new interface driver usbfs423server # [ 0.167454] usbcore: registered new interface driver hub424server # [ 0.167471] usbcore: registered new device driver usb425server # [ 0.167785] pps_core: LinuxPPS API ver. 1 registered426server # [ 0.167792] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>427server # [ 0.167801] PTP clock support registered428server # [ 0.167850] EDAC MC: Ver: 3.0.0429server # [ 0.172477] scmi_core: SCMI protocol bus registered430server # [ 0.173470] FPGA manager framework431server # [ 0.174405] vgaarb: loaded432server # [ 0.175032] clocksource: Switched to clocksource arch_sys_counter433server # [ 0.179519] VFS: Disk quotas dquot_6.6.0434server # [ 0.179566] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)435builder # [ 0.215000] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)436server # [ 0.181232] netfs: FS-Cache loaded437server # [ 0.181357] pnp: PnP ACPI: disabled438builder # [ 0.215047] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)439builder # [ 0.215074] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)440builder # [ 0.215120] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)441builder # [ 0.215197] TCP: Hash tables configured (established 8192 bind 8192)442builder # [ 0.215279] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)443builder # [ 0.215310] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)444builder # [ 0.215335] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)445server # [ 0.185277] NET: Registered PF_INET protocol family446builder # [ 0.215414] NET: Registered PF_UNIX/PF_LOCAL protocol family447server # [ 0.185439] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)448builder # [ 0.215440] NET: Registered PF_XDP protocol family449builder # [ 0.215461] PCI: CLS 0 bytes, default 64450builder # [ 0.215716] Trying to unpack rootfs image as initramfs...451builder # [ 0.230315] kvm [1]: HYP mode not available452server # [ 0.214420] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)453server # [ 0.214461] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)454server # [ 0.214485] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)455server # [ 0.214527] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)456server # [ 0.214604] TCP: Hash tables configured (established 8192 bind 8192)457server # [ 0.214686] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)458server # [ 0.214716] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)459server # [ 0.214769] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)460server # [ 0.214858] NET: Registered PF_UNIX/PF_LOCAL protocol family461server # [ 0.214885] NET: Registered PF_XDP protocol family462server # [ 0.214905] PCI: CLS 0 bytes, default 64463server # [ 0.215121] Trying to unpack rootfs image as initramfs...464server # [ 0.232785] kvm [1]: HYP mode not available465builder # [ 0.321193] Initialise system trusted keyrings466builder # [ 0.321932] workingset: timestamp_bits=42 max_order=18 bucket_order=0467builder # [ 0.323203] squashfs: version 4.0 (2009/01/31) Phillip Lougher468builder # [ 0.323974] 9p: Installing v9fs 9p2000 file system support469builder # [ 0.344575] Key type asymmetric registered470builder # [ 0.344602] Asymmetric key parser 'x509' registered471builder # [ 0.352724] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)472builder # [ 0.353793] io scheduler mq-deadline registered473builder # [ 0.353804] io scheduler kyber registered474builder # [ 0.364817] pl061_gpio 9030000.pl061: PL061 GPIO chip registered475server # [ 0.327578] Initialise system trusted keyrings476server # [ 0.328354] workingset: timestamp_bits=42 max_order=18 bucket_order=0477builder # [ 0.366261] ledtrig-cpu: registered to indicate activity on CPUs478builder # [ 0.366654] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:479server # [ 0.329642] squashfs: version 4.0 (2009/01/31) Phillip Lougher480builder # [ 0.366671] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000481server # [ 0.330426] 9p: Installing v9fs 9p2000 file system support482builder # [ 0.366684] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000483builder # [ 0.366693] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000484builder # [ 0.366714] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits485builder # [ 0.366741] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]486builder # [ 0.366834] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00487builder # [ 0.366843] pci_bus 0000:00: root bus resource [bus 00-ff]488builder # [ 0.366850] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]489builder # [ 0.366855] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]490builder # [ 0.366861] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]491builder # [ 0.366917] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint492builder # [ 0.367359] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint493builder # [ 0.367547] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]494builder # [ 0.367564] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]495builder # [ 0.367593] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]496builder # [ 0.367610] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]497builder # [ 0.368093] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint498builder # [ 0.368281] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]499builder # [ 0.368297] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]500builder # [ 0.368327] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]501builder # [ 0.387787] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint502builder # [ 0.387974] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]503builder # [ 0.387990] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]504builder # [ 0.388020] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]505builder # [ 0.388469] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint506builder # [ 0.392757] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]507builder # [ 0.392773] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]508builder # [ 0.392802] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]509builder # [ 0.392821] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]510server # [ 0.359153] Key type asymmetric registered511builder # [ 0.393271] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint512server # [ 0.359182] Asymmetric key parser 'x509' registered513builder # [ 0.393456] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]514server # [ 0.359254] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)515builder # [ 0.393485] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]516server # [ 0.361422] io scheduler mq-deadline registered517server # [ 0.361433] io scheduler kyber registered518builder # [ 0.393924] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint519builder # [ 0.394108] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]520builder # [ 0.394137] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]521builder # [ 0.394514] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint522builder # [ 0.394692] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]523builder # [ 0.394968] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint524builder # [ 0.395154] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]525builder # [ 0.395183] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]526builder # [ 0.395627] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint527builder # [ 0.395818] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]528builder # [ 0.395848] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]529builder # [ 0.396292] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint530server # [ 0.371180] pl061_gpio 9030000.pl061: PL061 GPIO chip registered531builder # [ 0.396476] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]532builder # [ 0.396506] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]533server # [ 0.372594] ledtrig-cpu: registered to indicate activity on CPUs534server # [ 0.372974] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:535server # [ 0.372991] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000536server # [ 0.373004] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000537server # [ 0.373012] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000538server # [ 0.373032] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits539server # [ 0.373056] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]540server # [ 0.373138] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00541server # [ 0.373148] pci_bus 0000:00: root bus resource [bus 00-ff]542server # [ 0.373154] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]543server # [ 0.373159] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]544server # [ 0.373164] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]545server # [ 0.373217] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint546builder # [ 0.421093] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint547builder # [ 0.421397] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]548server # [ 0.373652] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint549builder # [ 0.421417] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]550server # [ 0.373838] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]551server # [ 0.373855] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]552builder # [ 0.421447] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]553server # [ 0.373884] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]554builder # [ 0.421915] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint555server # [ 0.373900] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]556builder # [ 0.422100] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]557builder # [ 0.422116] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]558server # [ 0.374339] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint559builder # [ 0.422146] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]560server # [ 0.374517] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]561server # [ 0.374533] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]562builder # [ 0.422747] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned563server # [ 0.374562] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]564builder # [ 0.422759] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned565server # [ 0.375007] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint566builder # [ 0.422765] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned567builder # [ 0.422811] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned568builder # [ 0.422859] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned569builder # [ 0.422906] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned570builder # [ 0.422955] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned571server # [ 0.395641] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]572server # [ 0.395661] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]573builder # [ 0.423002] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned574server # [ 0.395691] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]575builder # [ 0.423051] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned576server # [ 0.396153] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint577builder # [ 0.423098] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned578server # [ 0.396337] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]579builder # [ 0.423145] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned580server # [ 0.396353] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]581server # [ 0.396383] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]582builder # [ 0.423193] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned583server # [ 0.396403] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]584builder # [ 0.423265] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned585server # [ 0.396857] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint586builder # [ 0.423311] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned587server # [ 0.397042] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]588builder # [ 0.423333] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned589server # [ 0.397073] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]590builder # [ 0.423355] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned591server # [ 0.397526] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint592builder # [ 0.423377] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned593server # [ 0.397714] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]594builder # [ 0.423399] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned595server # [ 0.397743] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]596builder # [ 0.423421] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned597builder # [ 0.423443] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned598server # [ 0.398134] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint599server # [ 0.398316] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]600builder # [ 0.423466] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned601server # [ 0.398559] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint602builder # [ 0.423491] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned603server # [ 0.398746] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]604builder # [ 0.423514] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned605server # [ 0.398776] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]606builder # [ 0.423536] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned607builder # [ 0.423558] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned608builder # [ 0.423580] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned609builder # [ 0.423601] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned610builder # [ 0.423623] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned611builder # [ 0.423644] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned612builder # [ 0.423666] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned613builder # [ 0.423688] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned614builder # [ 0.423714] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]615builder # [ 0.423724] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]616builder # [ 0.423728] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]617builder # [ 0.424562] pci 0000:00:07.0: enabling device (0000 -> 0002)618server # [ 0.419332] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint619server # [ 0.419541] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]620server # [ 0.419572] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]621server # [ 0.420040] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint622server # [ 0.420228] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]623server # [ 0.420258] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]624server # [ 0.420721] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint625server # [ 0.420983] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]626server # [ 0.421000] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]627server # [ 0.421030] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]628server # [ 0.421486] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint629server # [ 0.421670] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]630server # [ 0.421686] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]631server # [ 0.421716] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]632server # [ 0.422278] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned633server # [ 0.422289] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned634server # [ 0.422295] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned635server # [ 0.422340] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned636server # [ 0.422387] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned637server # [ 0.422435] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned638server # [ 0.422484] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned639server # [ 0.422531] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned640server # [ 0.422578] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned641server # [ 0.422625] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned642server # [ 0.422671] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned643server # [ 0.422718] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned644server # [ 0.422786] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned645server # [ 0.422833] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned646server # [ 0.422855] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned647server # [ 0.422878] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned648builder # [ 0.487008] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)649server # [ 0.422900] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned650server # [ 0.422922] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned651server # [ 0.422944] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned652server # [ 0.422966] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned653server # [ 0.422989] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned654server # [ 0.423015] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned655server # [ 0.459082] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned656server # [ 0.459133] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned657server # [ 0.459158] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned658server # [ 0.459180] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned659builder # [ 0.496882] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)660server # [ 0.459203] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned661builder # [ 0.500360] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)662server # [ 0.459225] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned663builder # [ 0.502380] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)664server # [ 0.459248] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned665builder # [ 0.504494] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)666server # [ 0.459270] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned667server # [ 0.459292] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned668server # [ 0.459321] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]669server # [ 0.459331] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]670server # [ 0.459336] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]671server # [ 0.460154] pci 0000:00:07.0: enabling device (0000 -> 0002)672builder # [ 0.514529] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)673builder # [ 0.516196] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)674builder # [ 0.526355] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)675builder # [ 0.528158] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)676server # [ 0.493002] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)677server # [ 0.495026] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)678builder # [ 0.539513] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)679builder # [ 0.542640] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)680server # [ 0.506668] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)681server # [ 0.509714] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)682builder # [ 0.555313] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled683builder # [ 0.557708] msm_serial: driver initialized684server # [ 0.515562] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)685builder # [ 0.557841] SuperH (H)SCI(F) driver initialized686server # [ 0.518451] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)687builder # [ 0.557895] STM32 USART driver initialized688server # [ 0.528268] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)689server # [ 0.530066] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)690server # [ 0.540573] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)691server # [ 0.542410] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)692builder # [ 0.590793] loop: module loaded693builder # [ 0.590949] virtio_blk virtio2: 1/0/0 default/read/poll queues694builder # [ 0.591932] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)695server # [ 0.554142] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)696builder # [ 0.597206] megasas: 07.734.00.00-rc1697builder # [ 0.597861] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]698server # [ 0.568250] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled699server # [ 0.570743] msm_serial: driver initialized700server # [ 0.570930] SuperH (H)SCI(F) driver initialized701server # [ 0.570992] STM32 USART driver initialized702builder # [ 0.609291] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000703builder # [ 0.609321] Intel/Sharp Extended Query Table at 0x0031704builder # [ 0.611620] Using buffer write method705builder # [ 0.611682] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]706builder # [ 0.616546] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000707builder # [ 0.616571] Intel/Sharp Extended Query Table at 0x0031708builder # [ 0.625685] Using buffer write method709builder # [ 0.625719] Concatenating MTD devices:710builder # [ 0.625723] (0): "0.flash"711builder # [ 0.625728] (1): "0.flash"712builder # [ 0.625731] into device "0.flash"713server # [ 0.601873] loop: module loaded714server # [ 0.602056] virtio_blk virtio2: 1/0/0 default/read/poll queues715server # [ 0.602818] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)716server # [ 0.607587] megasas: 07.734.00.00-rc1717server # [ 0.608497] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]718server # [ 0.610628] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000719server # [ 0.610650] Intel/Sharp Extended Query Table at 0x0031720server # [ 0.619980] Using buffer write method721server # [ 0.620052] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]722server # [ 0.622884] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000723server # [ 0.622906] Intel/Sharp Extended Query Table at 0x0031724server # [ 0.631892] Using buffer write method725server # [ 0.631929] Concatenating MTD devices:726server # [ 0.631933] (0): "0.flash"727server # [ 0.631937] (1): "0.flash"728server # [ 0.631941] into device "0.flash"729builder # [ 0.848756] Freeing initrd memory: 26900K730builder # [ 0.854539] tun: Universal TUN/TAP device driver, 1.6731builder # [ 0.858175] thunder_xcv, ver 1.0732builder # [ 0.858214] thunder_bgx, ver 1.0733builder # [ 0.858235] nicpf, ver 1.0734builder # [ 0.858792] e1000: Intel(R) PRO/1000 Network Driver735builder # [ 0.858799] e1000: Copyright (c) 1999-2006 Intel Corporation.736builder # [ 0.858822] e1000e: Intel(R) PRO/1000 Network Driver737builder # [ 0.858830] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.738builder # [ 0.858860] igb: Intel(R) Gigabit Ethernet Network Driver739builder # [ 0.858866] igb: Copyright (c) 2007-2014 Intel Corporation.740builder # [ 0.858891] igbvf: Intel(R) Gigabit Virtual Function Network Driver741builder # [ 0.858897] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.742builder # [ 0.859044] sky2: driver version 1.30743builder # [ 0.860625] usbcore: registered new interface driver usb-storage744builder # [ 0.861316] ehci-pci 0000:00:07.0: EHCI Host Controller745builder # [ 0.861345] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1746builder # [ 0.861617] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000747builder # [ 0.871865] usbcore: registered new interface driver usbserial_generic748builder # [ 0.871884] usbserial: USB Serial support registered for generic749builder # [ 0.872531] hv_vmbus: registering driver hyperv_keyboard750builder # [ 0.872695] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00751builder # [ 0.872982] hub 1-0:1.0: USB hub found752builder # [ 0.873002] hub 1-0:1.0: 6 ports detected753builder # [ 0.877924] rtc-pl031 9010000.pl031: registered as rtc0754builder # [ 0.877951] rtc-pl031 9010000.pl031: setting system clock to 2026-09-21T21:30:32 UTC (1790026232)755builder # [ 0.878254] i2c_dev: i2c /dev entries driver756builder # [ 0.883647] sdhci: Secure Digital Host Controller Interface driver757builder # [ 0.883660] sdhci: Copyright(c) Pierre Ossman758builder # [ 0.883936] Synopsys Designware Multimedia Card Interface Driver759builder # [ 0.884304] sdhci-pltfm: SDHCI platform and OF driver helper760builder # [ 0.888517] hid: raw HID events driver (C) Jiri Kosina761builder # [ 0.889435] usbcore: registered new interface driver usbhid762builder # [ 0.889443] usbhid: USB HID core driver763builder # [ 0.891690] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available764builder # [ 0.894324] drop_monitor: Initializing network drop monitor service765builder # [ 0.894462] NET: Registered PF_INET6 protocol family766builder # [ 0.896404] Segment Routing with IPv6767builder # [ 0.896427] In-situ OAM (IOAM) with IPv6768builder # [ 0.896459] NET: Registered PF_PACKET protocol family769builder # [ 0.898313] 9pnet: Installing 9P2000 support770builder # [ 0.898359] Key type dns_resolver registered771server # [ 0.861448] Freeing initrd memory: 26896K772server # [ 0.867528] tun: Universal TUN/TAP device driver, 1.6773builder # [ 0.904924] registered taskstats version 1774builder # [ 0.905079] Loading compiled-in X.509 certificates775builder # [ 0.913447] Demotion targets for Node 0: null776builder # [ 0.913551] Key type .fscrypt registered777builder # [ 0.913560] Key type fscrypt-provisioning registered778builder # [ 0.913650] ima: No TPM chip found, activating TPM-bypass!779server # [ 0.871232] thunder_xcv, ver 1.0780builder # [ 0.913669] ima: Allocated hash algorithm: sha1781server # [ 0.871268] thunder_bgx, ver 1.0782server # [ 0.871294] nicpf, ver 1.0783builder # [ 0.913691] ima: No architecture policies found784server # [ 0.871878] e1000: Intel(R) PRO/1000 Network Driver785server # [ 0.871885] e1000: Copyright (c) 1999-2006 Intel Corporation.786builder # [ 0.917686] input: gpio-keys as /devices/platform/gpio-keys/input/input0787server # [ 0.871910] e1000e: Intel(R) PRO/1000 Network Driver788server # [ 0.871917] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.789server # [ 0.871950] igb: Intel(R) Gigabit Ethernet Network Driver790server # [ 0.871956] igb: Copyright (c) 2007-2014 Intel Corporation.791server # [ 0.871982] igbvf: Intel(R) Gigabit Virtual Function Network Driver792server # [ 0.871988] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.793server # [ 0.872118] sky2: driver version 1.30794server # [ 0.873655] usbcore: registered new interface driver usb-storage795server # [ 0.873702] usbcore: registered new interface driver usbserial_generic796server # [ 0.873716] usbserial: USB Serial support registered for generic797server # [ 0.874289] hv_vmbus: registering driver hyperv_keyboard798server # [ 0.875581] ehci-pci 0000:00:07.0: EHCI Host Controller799server # [ 0.875610] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1800server # [ 0.875864] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000801server # [ 0.888422] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00802server # [ 0.888717] hub 1-0:1.0: USB hub found803server # [ 0.888737] hub 1-0:1.0: 6 ports detected804server # [ 0.890965] rtc-pl031 9010000.pl031: registered as rtc0805server # [ 0.890993] rtc-pl031 9010000.pl031: setting system clock to 2026-09-21T21:30:32 UTC (1790026232)806server # [ 0.893105] i2c_dev: i2c /dev entries driver807builder # [ 0.935052] clk: Disabling unused clocks808builder # [ 0.935076] PM: genpd: Disabling unused power domains809server # [ 0.896709] sdhci: Secure Digital Host Controller Interface driver810builder # [ 0.939239] Freeing unused kernel memory: 4736K811server # [ 0.896721] sdhci: Copyright(c) Pierre Ossman812builder # [ 0.939432] Run /init as init process813server # [ 0.896978] Synopsys Designware Multimedia Card Interface Driver814server # [ 0.897339] sdhci-pltfm: SDHCI platform and OF driver helper815server # [ 0.898811] hid: raw HID events driver (C) Jiri Kosina816server # [ 0.902431] usbcore: registered new interface driver usbhid817server # [ 0.902442] usbhid: USB HID core driver818server # [ 0.904820] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available819server # [ 0.906344] drop_monitor: Initializing network drop monitor service820server # [ 0.906508] NET: Registered PF_INET6 protocol family821server # [ 0.909601] Segment Routing with IPv6822server # [ 0.909621] In-situ OAM (IOAM) with IPv6823server # [ 0.909652] NET: Registered PF_PACKET protocol family824builder # [ 0.954794] systemd[1]: Successfully made /usr/ read-only.825server # [ 0.911504] 9pnet: Installing 9P2000 support826server # [ 0.911549] Key type dns_resolver registered827server # [ 0.917994] registered taskstats version 1828server # [ 0.918156] Loading compiled-in X.509 certificates829server # [ 0.926582] Demotion targets for Node 0: null830server # [ 0.926681] Key type .fscrypt registered831server # [ 0.926693] Key type fscrypt-provisioning registered832server # [ 0.926791] ima: No TPM chip found, activating TPM-bypass!833server # [ 0.926811] ima: Allocated hash algorithm: sha1834server # [ 0.926831] ima: No architecture policies found835server # [ 0.930966] input: gpio-keys as /devices/platform/gpio-keys/input/input0836server # [ 0.949028] clk: Disabling unused clocks837server # [ 0.949051] PM: genpd: Disabling unused power domains838server # [ 0.953340] Freeing unused kernel memory: 4736K839server # [ 0.953544] Run /init as init process840server # [ 0.969234] systemd[1]: Successfully made /usr/ read-only.841builder # [ 1.120723] usb 1-1: new high-speed USB device number 2 using ehci-pci842server # [ 1.135117] usb 1-1: new high-speed USB device number 2 using ehci-pci843builder # [ 1.273199] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1844builder # [ 1.289604] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)845builder # [ 1.301731] systemd[1]: Detected virtualization qemu.846builder # [ 1.303710] systemd[1]: Detected architecture arm64.847builder # [ 1.305688] systemd[1]: Running in initrd.848builder # [ 1.308260] systemd[1]: Initializing machine ID from random generator.849builder # [ 1.311235] systemd[1]: Hostname set to <builder>.850server # [ 1.287529] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1851server # [ 1.304110] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)852server # [ 1.316324] systemd[1]: Detected virtualization qemu.853server # [ 1.318362] systemd[1]: Detected architecture arm64.854server # [ 1.320412] systemd[1]: Running in initrd.855builder # [ 1.360888] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0856server # [ 1.321348] systemd[1]: Initializing machine ID from random generator.857server # [ 1.321729] systemd[1]: Hostname set to <server>.858server # [ 1.379291] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0859builder # [ 1.484743] usb 1-2: new high-speed USB device number 3 using ehci-pci860server # [ 1.499081] usb 1-2: new high-speed USB device number 3 using ehci-pci861builder # [ 1.616189] systemd[1]: bpf-restrict-fs: LSM BPF program attached862builder # [ 1.641511] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2863builder # [ 1.647100] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0864server # [ 1.646681] systemd[1]: bpf-restrict-fs: LSM BPF program attached865server # [ 1.659725] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2866server # [ 1.665480] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0867builder # [ 1.732315] systemd[1]: Queued start job for default target Initrd Default Target.868builder # [ 1.743059] systemd[1]: Created slice Slice /system/modprobe.869builder # [ 1.744310] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.870builder # [ 1.745760] systemd[1]: Expecting device /dev/disk/by-label/nixos...871builder # [ 1.746867] systemd[1]: Reached target Path Units.872builder # [ 1.747714] systemd[1]: Reached target Slice Units.873builder # [ 1.748744] systemd[1]: Reached target Swaps.874builder # [ 1.749543] systemd[1]: Reached target Timer Units.875builder # [ 1.750611] systemd[1]: Listening on D-Bus System Message Bus Socket.876builder # [ 1.751915] systemd[1]: Listening on Journal Socket (/dev/log).877builder # [ 1.753149] systemd[1]: Listening on Journal Sockets.878builder # [ 1.753294] systemd[1]: Listening on udev Control Socket.879builder # [ 1.753413] systemd[1]: Listening on udev Kernel Socket.880builder # [ 1.753435] systemd[1]: Reached target Socket Units.881builder # [ 1.758801] systemd[1]: Starting Create List of Static Device Nodes...882builder # [ 1.760041] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs883builder # [ 1.768819] systemd[1]: Mounting Kernel Configuration File System...884builder # [ 1.776933] systemd[1]: Starting Journal Service...885server # [ 1.757246] systemd[1]: Queued start job for default target Initrd Default Target.886server # [ 1.765366] systemd[1]: Created slice Slice /system/modprobe.887server # [ 1.766522] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.888server # [ 1.767886] systemd[1]: Expecting device /dev/disk/by-label/nixos...889server # [ 1.768892] systemd[1]: Reached target Path Units.890server # [ 1.769665] systemd[1]: Reached target Slice Units.891server # [ 1.770450] systemd[1]: Reached target Swaps.892server # [ 1.771187] systemd[1]: Reached target Timer Units.893builder # [ 1.808843] systemd[1]: Starting Load Kernel Modules...894server # [ 1.772152] systemd[1]: Listening on D-Bus System Message Bus Socket.895builder # [ 1.808958] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os896server # [ 1.773360] systemd[1]: Listening on Journal Socket (/dev/log).897server # [ 1.774451] systemd[1]: Listening on Journal Sockets.898server # [ 1.775429] systemd[1]: Listening on udev Control Socket.899server # [ 1.776419] systemd[1]: Listening on udev Kernel Socket.900server # [ 1.777285] systemd[1]: Reached target Socket Units.901server # [ 1.779678] systemd[1]: Starting Create List of Static Device Nodes...902server # [ 1.780792] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs903builder # [ 1.825786] systemd[1]: Starting Coldplug All udev Devices...904server # [ 1.788189] systemd[1]: Mounting Kernel Configuration File System...905builder # [ 1.840940] systemd-journald[72]: Collecting audit messages is disabled.906builder # [ 1.845607] systemd[1]: Finished Create List of Static Device Nodes.907server # [ 1.806443] systemd[1]: Starting Journal Service...908builder # [ 1.846259] systemd[1]: Mounted Kernel Configuration File System.909server # [ 1.819196] systemd[1]: Starting Load Kernel Modules...910builder # [ 1.857057] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...911server # [ 1.820182] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os912server # [ 1.827335] systemd[1]: Starting Coldplug All udev Devices...913builder # [ 1.867906] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.914server # [ 1.847709] systemd[1]: Finished Create List of Static Device Nodes.915server # [ 1.848493] systemd[1]: Mounted Kernel Configuration File System.916builder # [ 1.884400] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev917builder # [ 1.890420] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0918builder # [ 1.890654] [drm] features: -virgl +edid -resource_blob -host_visible919builder # [ 1.890663] [drm] features: -context_init920builder # [ 1.891342] [drm] number of scanouts: 1921builder # [ 1.891360] [drm] number of cap sets: 0922builder # [ 1.897230] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.923server # [ 1.859478] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...924builder # [ 1.905010] systemd[1]: Starting Create Static Device Nodes in /dev...925builder # [ 1.916969] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic926builder # [ 1.916982] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0927server # [ 1.890469] systemd-journald[72]: Collecting audit messages is disabled.928builder # [ 1.945194] systemd[1]: Finished Create Static Device Nodes in /dev.929server # [ 1.902855] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.930builder # [ 1.945496] systemd[1]: Reached target Preparation for Local File Systems.931server # [ 1.904395] systemd[1]: Starting Create Static Device Nodes in /dev...932builder # [ 1.945523] systemd[1]: Reached target Local File Systems.933builder # [ 1.949505] systemd[1]: Starting Rule-based Manager for Device Events and Files...934server # [ 1.912519] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.935builder # [ 1.953937] Console: switching to colour frame buffer device 160x50936server # [ 1.923176] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev937builder # [ 1.973318] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device938server # [ 1.930187] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0939server # [ 1.930420] [drm] features: -virgl +edid -resource_blob -host_visible940server # [ 1.930429] [drm] features: -context_init941builder # [ 1.989143] systemd[1]: Finished Load Kernel Modules.942builder # [ 1.991977] systemd[1]: Starting Apply Kernel Variables...943server # [ 1.951712] systemd[1]: Finished Create Static Device Nodes in /dev.944server # [ 1.952038] systemd[1]: Reached target Preparation for Local File Systems.945server # [ 1.952067] systemd[1]: Reached target Local File Systems.946server # [ 1.955923] systemd[1]: Starting Rule-based Manager for Device Events and Files...947server # [ 1.963282] [drm] number of scanouts: 1948server # [ 1.963311] [drm] number of cap sets: 0949builder # [ 2.011079] systemd[1]: Started Journal Service.950server # [ 1.967382] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic951server # [ 1.967395] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0952builder # [ 2.006605] systemd-modules-load[73]: Inserted module 'dm_mod'953builder # [ 2.007820] systemd-modules-load[73]: Module 'virtio_balloon' is built in954server # [ 1.991374] Console: switching to colour frame buffer device 160x50955builder # [ 2.028397] systemd-modules-load[73]: Module 'virtio_console' is built in956builder # [ 2.029469] systemd-modules-load[73]: Inserted module 'virtio_gpu'957builder # [ 2.030420] systemd-modules-load[73]: Module 'virtio_rng' is built in958builder # [ 2.031391] systemd[1]: Starting Create System Files and Directories...959builder # [ 2.044197] systemd[1]: Finished Apply Kernel Variables.960server # [ 2.019669] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device961builder # [ 2.046618] systemd[1]: Finished Create System Files and Directories.962server # [ 2.028435] systemd[1]: Finished Load Kernel Modules.963builder # [ 2.056699] systemd-udevd[79]: Using default interface naming scheme 'v261'.964server # [ 2.043432] systemd[1]: Starting Apply Kernel Variables...965builder # [ 2.081349] systemd[1]: Started Rule-based Manager for Device Events and Files.966server # [ 2.066801] systemd[1]: Started Journal Service.967server # [ 2.069738] systemd-modules-load[73]: Inserted module 'dm_mod'968server # [ 2.070995] systemd-modules-load[73]: Module 'virtio_balloon' is built in969server # [ 2.072315] systemd-modules-load[73]: Module 'virtio_console' is built in970server # [ 2.081452] systemd-modules-load[73]: Inserted module 'virtio_gpu'971server # [ 2.083686] systemd-modules-load[73]: Module 'virtio_rng' is built in972server # [ 2.092723] systemd-udevd[79]: Using default interface naming scheme 'v261'.973server # [ 2.093858] systemd[1]: Starting Create System Files and Directories...974server # [ 2.094933] systemd[1]: Finished Apply Kernel Variables.975builder # [ 2.136095] systemd[1]: Starting Virtual Console Setup...976server # [ 2.105440] systemd[1]: Finished Create System Files and Directories.977server # [ 2.121921] systemd[1]: Started Rule-based Manager for Device Events and Files.978builder # [ 2.184470] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.979builder # [ 2.187831] systemd[1]: Finished Virtual Console Setup.980server # [ 2.176425] systemd[1]: Starting Virtual Console Setup...981server # [ 2.232476] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.982server # [ 2.235907] systemd[1]: Finished Virtual Console Setup.983builder # [ 2.769454] systemd[1]: Finished Coldplug All udev Devices.984builder # [ 2.772151] systemd[1]: Reached target System Initialization.985builder # [ 2.776116] systemd[1]: Reached target Basic System.986server # [ 2.814111] systemd[1]: Finished Coldplug All udev Devices.987server # [ 2.815078] systemd[1]: Reached target System Initialization.988server # [ 2.816119] systemd[1]: Reached target Basic System.989builder # [ 2.907830] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line.990builder # [ 2.942647] (udev-worker)[97]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.991builder # [ 2.948259] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.992server # [ 2.985891] (udev-worker)[96]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.993server # [ 2.988244] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.994server # [ 2.991195] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.995builder # [ 3.034710] systemd[1]: Found device /dev/disk/by-label/nixos.996builder # [ 3.037237] systemd[1]: Reached target Initrd Root Device.997builder # [ 3.040708] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...998builder # [ 3.085744] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks999builder # [ 3.091261] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1000server # [ 3.056367] systemd[1]: Found device /dev/disk/by-label/nixos.1001builder # [ 3.095581] systemd[1]: Mounting /sysroot...1002server # [ 3.059314] systemd[1]: Reached target Initrd Root Device.1003server # [ 3.062927] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1004builder # [ 3.147680] EXT4-fs (vda): mounted filesystem f6cb108f-9094-42f6-84e8-bb0934fa2335 r/w with ordered data mode. Quota mode: none.1005builder # [ 3.135703] systemd[1]: Mounted /sysroot.1006builder # [ 3.139446] systemd[1]: Reached target Initrd Root File System.1007builder # [ 3.146412] systemd[1]: Starting Mountpoints Configured in the Real Root...1008server # [ 3.115866] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks1009server # [ 3.124479] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1010server # [ 3.127743] systemd[1]: Mounting /sysroot...1011builder # [ 3.167832] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.1012builder # [ 3.174944] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...1013builder # [ 3.178356] systemd[1]: Reloading...1014server # [ 3.177612] EXT4-fs (vda): mounted filesystem 35348747-4d43-47ce-b24f-a52e7f4cc6c2 r/w with ordered data mode. Quota mode: none.1015server # [ 3.168103] systemd[1]: Mounted /sysroot.1016server # [ 3.169194] systemd[1]: Reached target Initrd Root File System.1017server # [ 3.172272] systemd[1]: Starting Mountpoints Configured in the Real Root...1018server # [ 3.200248] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.1019server # [ 3.205438] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...1020server # [ 3.210798] systemd[1]: Reloading...1021builder # [ 3.380203] systemd[1]: Reloading finished in 206 ms.1022builder # [ 3.412087] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...1023builder # [ 3.416548] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...1024builder # [ 3.423701] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1025builder # [ 3.426743] systemd[1]: Finished Mountpoints Configured in the Real Root.1026builder # [ 3.428678] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1027server # [ 3.417907] systemd[1]: Reloading finished in 209 ms.1028server # [ 3.440923] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...1029server # [ 3.445390] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...1030server # [ 3.452191] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1031server # [ 3.454839] systemd[1]: Finished Mountpoints Configured in the Real Root.1032server # [ 3.457040] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1033builder # [ 3.815408] systemd[1]: Mounting /sysroot/nix/.ro-store...1034builder # [ 3.831290] systemd[1]: Mounting /sysroot/nix/.rw-store...1035builder # [ 3.834745] systemd[1]: Mounting /sysroot/run...1036builder # [ 3.850667] systemd[1]: Mounting /sysroot/tmp/shared...1037server # [ 3.831200] systemd[1]: Mounting /sysroot/nix/.ro-store...1038server # [ 3.847392] systemd[1]: Mounting /sysroot/nix/.rw-store...1039server # [ 3.851767] systemd[1]: Mounting /sysroot/run...1040server # [ 3.862475] systemd[1]: Mounting /sysroot/tmp/shared...1041builder # [ 3.925500] fuse: init (API version 7.45)1042builder # [ 3.912106] systemd[1]: Mounting /sysroot/tmp/xchg...1043builder # [ 3.914365] systemd[1]: Mounted /sysroot/nix/.rw-store.1044builder # [ 3.942130] virtiofs virtio6: discovered new tag: nix-store1045builder # [ 3.942916] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1046server # [ 3.892350] systemd[1]: Mounting /sysroot/tmp/xchg...1047builder # [ 3.958314] virtiofs virtio7: discovered new tag: shared1048builder # [ 3.959065] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1049builder # [ 3.967899] virtiofs virtio8: discovered new tag: xchg1050builder # [ 3.968636] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1051builder # [ 3.958543] systemd[1]: Starting rw-sysroot-nix-store.service...1052builder # [ 3.959615] systemd[1]: Mounted /sysroot/run.1053server # [ 3.939106] systemd[1]: Mounted /sysroot/nix/.rw-store.1054builder # [ 3.983372] systemd[1]: Mounted /sysroot/nix/.ro-store.1055builder # [ 3.985851] systemd[1]: Mounted /sysroot/tmp/shared.1056server # [ 3.965922] fuse: init (API version 7.45)1057builder # [ 4.005884] systemd[1]: Mounted /sysroot/tmp/xchg.1058server # [ 3.981975] virtiofs virtio6: discovered new tag: nix-store1059server # [ 3.982763] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1060builder # [ 4.013933] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1061server # [ 3.975921] systemd[1]: Mounted /sysroot/run.1062builder # [ 4.015013] systemd[1]: Finished rw-sysroot-nix-store.service.1063server # [ 3.998056] virtiofs virtio7: discovered new tag: shared1064server # [ 3.998856] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1065server # [ 4.007517] virtiofs virtio8: discovered new tag: xchg1066server # [ 4.008265] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1067server # [ 4.013166] systemd[1]: Starting rw-sysroot-nix-store.service...1068server # [ 4.023283] systemd[1]: Mounted /sysroot/nix/.ro-store.1069server # [ 4.026653] systemd[1]: Mounted /sysroot/tmp/shared.1070server # [ 4.029544] systemd[1]: Mounted /sysroot/tmp/xchg.1071server # [ 4.030842] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1072server # [ 4.034289] systemd[1]: Finished rw-sysroot-nix-store.service.1073builder # [ 4.281482] (udev-worker)[95]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory1074builder # [ 4.286530] (udev-worker)[95]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1075builder # [ 4.321185] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1076builder # [ 4.325248] systemd[1]: Stopped Virtual Console Setup.1077builder # [ 4.327753] systemd[1]: Stopping Virtual Console Setup...1078builder # [ 4.332228] systemd[1]: Starting Virtual Console Setup...1079builder # [ 4.337527] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1080builder # [ 4.339276] systemd[1]: Stopped Virtual Console Setup.1081builder # [ 4.345282] systemd[1]: Starting Virtual Console Setup...1082builder # [ 4.373442] systemd-vconsole-setup[152]: Configuration of first virtual console was skipped, ignoring remaining ones.1083builder # [ 4.376742] systemd[1]: Finished Virtual Console Setup.1084server # [ 4.343167] (udev-worker)[93]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory1085server # [ 4.349609] (udev-worker)[93]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1086server # [ 4.381588] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1087server # [ 4.384633] systemd[1]: Stopped Virtual Console Setup.1088server # [ 4.386002] systemd[1]: Stopping Virtual Console Setup...1089server # [ 4.388358] systemd[1]: Starting Virtual Console Setup...1090server # [ 4.404807] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1091server # [ 4.406305] systemd[1]: Stopped Virtual Console Setup.1092server # [ 4.408635] systemd[1]: Starting Virtual Console Setup...1093server # [ 4.431129] systemd-vconsole-setup[152]: Configuration of first virtual console was skipped, ignoring remaining ones.1094server # [ 4.434490] systemd[1]: Finished Virtual Console Setup.1095builder # [ 4.816359] systemd[1]: Mounting /sysroot/nix/store...1096server # [ 4.831762] systemd[1]: Mounting /sysroot/nix/store...1097builder # [ 4.882301] systemd[1]: Mounted /sysroot/nix/store.1098builder # [ 4.885160] systemd[1]: Reached target Initrd File Systems.1099builder # [ 4.889786] systemd[1]: Starting Find NixOS closure...1100builder # [ 4.897596] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1101server # [ 4.899534] systemd[1]: Mounted /sysroot/nix/store.1102server # [ 4.902568] systemd[1]: Reached target Initrd File Systems.1103builder # [ 4.942326] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1104server # [ 4.907420] systemd[1]: Starting Find NixOS closure...1105builder # [ 4.946545] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1106server # [ 4.916376] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1107builder # [ 4.961410] systemd[1]: Finished Find NixOS closure.1108builder # [ 4.964267] systemd[1]: Reached target Initrd Default Target.1109builder # [ 4.966213] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1110builder # [ 4.995973] systemd[1]: Stopped target Initrd Default Target.1111builder # [ 4.998648] systemd[1]: Stopped target Basic System.1112builder # [ 5.000122] systemd[1]: Stopped target Initrd Root Device.1113server # [ 4.962020] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1114builder # [ 5.001554] systemd[1]: Stopped target Path Units.1115builder # [ 5.004297] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1116server # [ 4.967241] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1117builder # [ 5.008292] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1118builder # [ 5.010161] systemd[1]: Stopped target Slice Units.1119builder # [ 5.012730] systemd[1]: Stopped target Socket Units.1120builder # [ 5.016191] systemd[1]: Stopped target System Initialization.1121builder # [ 5.017427] systemd[1]: Stopped target Swaps.1122builder # [ 5.019368] systemd[1]: Stopped target Timer Units.1123server # [ 4.981770] systemd[1]: Finished Find NixOS closure.1124builder # [ 5.022572] systemd[1]: dbus.socket: Deactivated successfully.1125server # [ 4.984240] systemd[1]: Reached target Initrd Default Target.1126server # [ 4.986517] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1127builder # [ 5.028348] systemd[1]: Closed D-Bus System Message Bus Socket.1128builder # [ 5.032207] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1129builder # [ 5.035075] systemd[1]: Stopped Find NixOS closure.1130builder # [ 5.040224] systemd[1]: Starting rw-sysroot-nix-store.service...1131builder # [ 5.043791] systemd[1]: systemd-sysctl.service: Deactivated successfully.1132builder # [ 5.046085] systemd[1]: Stopped Apply Kernel Variables.1133builder # [ 5.047360] systemd[1]: systemd-modules-load.service: Deactivated successfully.1134builder # [ 5.054616] systemd[1]: Stopped Load Kernel Modules.1135server # [ 5.016851] systemd[1]: Stopped target Initrd Default Target.1136builder # [ 5.055475] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1137server # [ 5.018836] systemd[1]: Stopped target Basic System.1138server # [ 5.023967] systemd[1]: Stopped target Initrd Root Device.1139builder # [ 5.063320] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1140server # [ 5.025598] systemd[1]: Stopped target Path Units.1141server # [ 5.026624] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1142builder # [ 5.065618] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1143server # [ 5.028160] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1144builder # [ 5.068271] systemd[1]: Stopped Create System Files and Directories.1145builder # [ 5.069183] systemd[1]: Stopped target Local File Systems.1146server # [ 5.031350] systemd[1]: Stopped target Slice Units.1147builder # [ 5.070784] systemd[1]: Stopped target Preparation for Local File Systems.1148server # [ 5.034010] systemd[1]: Stopped target Socket Units.1149builder # [ 5.072870] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1150server # [ 5.036855] systemd[1]: Stopped target System Initialization.1151builder # [ 5.076426] systemd[1]: Stopped Coldplug All udev Devices.1152builder # [ 5.077255] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1153server # [ 5.041580] systemd[1]: Stopped target Swaps.1154builder # [ 5.080100] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1155builder # [ 5.081240] systemd[1]: Stopped Virtual Console Setup.1156builder # [ 5.081979] systemd[1]: initrd-cleanup.service: Deactivated successfully.1157builder # [ 5.082892] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1158server # [ 5.044697] systemd[1]: Stopped target Timer Units.1159builder # [ 5.083826] systemd[1]: systemd-udevd.service: Deactivated successfully.1160server # [ 5.046370] systemd[1]: dbus.socket: Deactivated successfully.1161builder # [ 5.084967] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1162builder # [ 5.085970] systemd[1]: systemd-udevd.service: Consumed 1.372s CPU time over 3.129s wall clock time, 21.8M memory peak.1163builder # [ 5.087361] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1164server # [ 5.052261] systemd[1]: Closed D-Bus System Message Bus Socket.1165builder # [ 5.092221] systemd[1]: Finished rw-sysroot-nix-store.service.1166builder # [ 5.093076] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1167builder # [ 5.094645] systemd[1]: Closed udev Control Socket.1168server # [ 5.056203] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1169server # [ 5.057425] systemd[1]: Stopped Find NixOS closure.1170builder # [ 5.096143] systemd[1]: Starting Cleanup udev Database...1171server # [ 5.060190] systemd[1]: Starting rw-sysroot-nix-store.service...1172builder # [ 5.100244] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1173builder # [ 5.101343] systemd[1]: Stopped Create Static Device Nodes in /dev.1174builder # [ 5.102208] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1175server # [ 5.063862] systemd[1]: systemd-sysctl.service: Deactivated successfully.1176builder # [ 5.104181] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1177server # [ 5.066635] systemd[1]: Stopped Apply Kernel Variables.1178server # [ 5.069027] systemd[1]: systemd-modules-load.service: Deactivated successfully.1179builder # [ 5.108107] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1180server # [ 5.070532] systemd[1]: Stopped Load Kernel Modules.1181builder # [ 5.109087] systemd[1]: Stopped Create List of Static Device Nodes.1182server # [ 5.072399] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1183server # [ 5.073567] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1184server # [ 5.074627] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1185server # [ 5.075627] systemd[1]: Stopped Create System Files and Directories.1186server # [ 5.076717] systemd[1]: Stopped target Local File Systems.1187server # [ 5.077496] systemd[1]: Stopped target Preparation for Local File Systems.1188server # [ 5.078439] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1189server # [ 5.079425] systemd[1]: Stopped Coldplug All udev Devices.1190server # [ 5.088259] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1191server # [ 5.093285] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1192server # [ 5.094539] systemd[1]: Stopped Virtual Console Setup.1193server # [ 5.096343] systemd[1]: systemd-udevd.service: Deactivated successfully.1194server # [ 5.098390] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1195builder # [ 5.137410] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1196server # [ 5.100180] systemd[1]: systemd-udevd.service: Consumed 1.380s CPU time over 3.127s wall clock time, 21.7M memory peak.1197builder # [ 5.140599] systemd[1]: Finished Cleanup udev Database.1198builder # [ 5.141410] systemd[1]: Reached target Switch Root.1199server # [ 5.103320] systemd[1]: initrd-cleanup.service: Deactivated successfully.1200server # [ 5.104542] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1201builder # [ 5.144166] systemd[1]: Starting NixOS Activation...1202server # [ 5.105471] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1203server # [ 5.106460] systemd[1]: Closed udev Control Socket.1204server # [ 5.107155] systemd[1]: Starting Cleanup udev Database...1205server # [ 5.107914] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1206server # [ 5.109179] systemd[1]: Stopped Create Static Device Nodes in /dev.1207server # [ 5.110049] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1208server # [ 5.111158] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1209server # [ 5.120456] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1210server # [ 5.121480] systemd[1]: Stopped Create List of Static Device Nodes.1211server # [ 5.122632] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1212server # [ 5.128168] systemd[1]: Finished rw-sysroot-nix-store.service.1213server # [ 5.154275] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1214server # [ 5.156970] systemd[1]: Finished Cleanup udev Database.1215server # [ 5.158577] systemd[1]: Reached target Switch Root.1216server # [ 5.160291] systemd[1]: Starting NixOS Activation...1217builder # [ 5.223323] initrd-nixos-activation-start[175]: booting system configuration /nix/store/rcnlmkx8r7zl26s9nq030kr7gi13c88c-nixos-system-builder-test1218builder # [ 5.255094] initrd-nixos-activation-start[175]: running activation script...1219server # [ 5.244233] initrd-nixos-activation-start[176]: booting system configuration /nix/store/lv6fvz5f97s6arpm7s0261cr5fnbhyf7-nixos-system-server-test1220server # [ 5.277902] initrd-nixos-activation-start[176]: running activation script...1221builder # [ 5.491731] initrd-nixos-activation-start[198]: setting up /etc...1222server # [ 5.503191] initrd-nixos-activation-start[199]: setting up /etc...1223builder # [ 5.610930] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1224builder # [ 5.613755] systemd[1]: Finished NixOS Activation.1225builder # [ 5.614880] systemd[1]: Starting Switch Root...1226builder # [ 5.631451] systemd[1]: Switching root.1227server # [ 5.625888] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1228server # [ 5.628228] systemd[1]: Finished NixOS Activation.1229server # [ 5.630030] systemd[1]: Starting Switch Root...1230server # [ 5.651790] systemd[1]: Switching root.1231builder # [ 5.825765] systemd-journald[72]: Received SIGTERM from PID 1 (systemd).1232server # [ 5.832801] systemd-journald[72]: Received SIGTERM from PID 1 (systemd).1233server # [ 6.363850] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1234builder # [ 6.405051] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1235server # [ 6.376554] systemd[1]: Detected virtualization qemu.1236builder # [ 6.417683] systemd[1]: Detected virtualization qemu.1237server # [ 6.379695] systemd[1]: Detected architecture arm64.1238builder # [ 6.420694] systemd[1]: Detected architecture arm64.1239server # [ 6.383781] systemd[1]: Detected first boot.1240builder # [ 6.424337] systemd[1]: Detected first boot.1241server # [ 6.389659] systemd[1]: Initializing machine ID from random generator.1242builder # [ 6.430033] systemd[1]: Initializing machine ID from random generator.1243builder # [ 6.749148] systemd[1]: bpf-restrict-fs: LSM BPF program attached1244server # [ 6.712902] systemd[1]: bpf-restrict-fs: LSM BPF program attached1245builder # [ 6.937965] systemd[1]: Applying preset policy.1246server # [ 6.902425] systemd[1]: Applying preset policy.1247builder # [ 7.186598] systemd[1]: Populated /etc with preset unit settings.1248server # [ 7.149571] systemd[1]: Populated /etc with preset unit settings.1249builder # [ 7.410513] systemd[1]: initrd-switch-root.service: Deactivated successfully.1250builder # [ 7.411875] systemd[1]: Stopped initrd-switch-root.service.1251builder # [ 7.416269] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1252builder # [ 7.421041] systemd[1]: Created slice Slice /system/getty.1253builder # [ 7.423124] systemd[1]: Created slice User and Session Slice.1254builder # [ 7.425432] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1255server # [ 7.388575] systemd[1]: initrd-switch-root.service: Deactivated successfully.1256builder # [ 7.427860] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1257builder # [ 7.430253] systemd[1]: Expecting device /dev/hvc0...1258server # [ 7.389965] systemd[1]: Stopped initrd-switch-root.service.1259builder # [ 7.432136] systemd[1]: Expecting device /dev/ttyAMA0...1260builder # [ 7.434111] systemd[1]: Reached target Local Encrypted Volumes.1261server # [ 7.394531] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1262builder # [ 7.436072] systemd[1]: Stopped target initrd-fs.target.1263builder # [ 7.438091] systemd[1]: Stopped target initrd-root-fs.target.1264server # [ 7.399438] systemd[1]: Created slice Slice /system/getty.1265builder # [ 7.439182] systemd[1]: Stopped target initrd-switch-root.target.1266builder # [ 7.440910] systemd[1]: Reached target Virtual Machines and Containers.1267server # [ 7.401548] systemd[1]: Created slice User and Session Slice.1268builder # [ 7.443337] systemd[1]: Reached target Path Units.1269builder # [ 7.444315] systemd[1]: Reached target Remote File Systems.1270server # [ 7.403774] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1271builder # [ 7.445928] systemd[1]: Reached target Slice Units.1272server # [ 7.406120] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1273builder # [ 7.448179] systemd[1]: Reached target Swaps.1274server # [ 7.408594] systemd[1]: Expecting device /dev/hvc0...1275builder # [ 7.450620] systemd[1]: Listening on Query the User Interactively for a Password.1276server # [ 7.410474] systemd[1]: Expecting device /dev/ttyAMA0...1277server # [ 7.412490] systemd[1]: Reached target Local Encrypted Volumes.1278builder # [ 7.453598] systemd[1]: Listening on Process Core Dump Socket.1279server # [ 7.414464] systemd[1]: Stopped target initrd-fs.target.1280builder # [ 7.455813] systemd[1]: Listening on Credential Encryption/Decryption.1281server # [ 7.416497] systemd[1]: Stopped target initrd-root-fs.target.1282builder # [ 7.458167] systemd[1]: Listening on Factory Reset Management.1283server # [ 7.418413] systemd[1]: Stopped target initrd-switch-root.target.1284builder # [ 7.459369] systemd[1]: Listening on Hostname Service Socket.1285server # [ 7.420498] systemd[1]: Reached target Virtual Machines and Containers.1286server # [ 7.422529] systemd[1]: Reached target Path Units.1287builder # [ 7.463577] systemd[1]: Starting Journal Log Access Socket...1288server # [ 7.424661] systemd[1]: Reached target Remote File Systems.1289builder # [ 7.465724] systemd[1]: Listening on Journal Audit Socket.1290server # [ 7.426561] systemd[1]: Reached target Slice Units.1291server # [ 7.428463] systemd[1]: Reached target Swaps.1292builder # [ 7.469272] systemd[1]: Listening on Console Output Muting Service Socket.1293builder # [ 7.470829] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1294server # [ 7.431742] systemd[1]: Listening on Query the User Interactively for a Password.1295builder # [ 7.472951] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1296server # [ 7.434670] systemd[1]: Listening on Process Core Dump Socket.1297builder # [ 7.474758] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1298server # [ 7.436972] systemd[1]: Listening on Credential Encryption/Decryption.1299server # [ 7.439349] systemd[1]: Listening on Factory Reset Management.1300builder # [ 7.480625] systemd[1]: Listening on Disk Repartitioning Service Socket.1301server # [ 7.440577] systemd[1]: Listening on Hostname Service Socket.1302builder # [ 7.481995] systemd[1]: Listening on udev Control Socket.1303builder # [ 7.483589] systemd[1]: Listening on udev Varlink Socket.1304server # [ 7.444788] systemd[1]: Starting Journal Log Access Socket...1305server # [ 7.446872] systemd[1]: Listening on Journal Audit Socket.1306builder # [ 7.488061] systemd[1]: Mounting Huge Pages File System...1307server # [ 7.449790] systemd[1]: Listening on Console Output Muting Service Socket.1308builder # [ 7.491640] systemd[1]: Mounting POSIX Message Queue File System...1309server # [ 7.451367] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1310server # [ 7.453456] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1311server # [ 7.455394] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1312server # [ 7.460968] systemd[1]: Listening on Disk Repartitioning Service Socket.1313server # [ 7.462334] systemd[1]: Listening on udev Control Socket.1314server # [ 7.464023] systemd[1]: Listening on udev Varlink Socket.1315builder # [ 7.504458] systemd[1]: Mounting Kernel Debug File System...1316server # [ 7.468592] systemd[1]: Mounting Huge Pages File System...1317builder # [ 7.513906] systemd[1]: Mounting Kernel Trace File System...1318server # [ 7.479207] systemd[1]: Mounting POSIX Message Queue File System...1319server # [ 7.486588] systemd[1]: Mounting Kernel Debug File System...1320builder # [ 7.529267] systemd[1]: Starting Create List of Static Device Nodes...1321builder # [ 7.531471] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1322server # [ 7.497316] systemd[1]: Mounting Kernel Trace File System...1323builder # [ 7.541267] systemd[1]: Mounting Kernel Configuration File System...1324builder # [ 7.541609] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1325builder # [ 7.541865] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1326builder # [ 7.542124] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1327server # [ 7.515307] systemd[1]: Starting Create List of Static Device Nodes...1328server # [ 7.515735] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1329server # [ 7.527441] systemd[1]: Mounting Kernel Configuration File System...1330server # [ 7.529131] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1331builder # [ 7.570100] systemd[1]: Mounting FUSE Control File System...1332builder # [ 7.570505] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671333server # [ 7.535384] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1334server # [ 7.537277] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1335server # [ 7.559457] systemd[1]: Mounting FUSE Control File System...1336builder # [ 7.603529] systemd[1]: Starting Journal Service...1337server # [ 7.562482] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671338builder # [ 7.622075] systemd[1]: Starting Load Kernel Modules...1339server # [ 7.583616] systemd[1]: Starting Journal Service...1340builder # [ 7.638222] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1341server # [ 7.606962] systemd[1]: Starting Load Kernel Modules...1342builder # [ 7.649674] systemd[1]: Starting Remount Root and Kernel File Systems...1343builder # [ 7.651870] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1344builder # [ 7.657408] systemd[1]: Starting Coldplug All udev Devices...1345server # [ 7.620660] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1346builder # [ 7.665207] systemd[1]: Listening on Journal Log Access Socket.1347server # [ 7.626826] systemd[1]: Starting Remount Root and Kernel File Systems...1348builder # [ 7.667372] systemd[1]: Mounted Huge Pages File System.1349server # [ 7.628736] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1350builder # [ 7.667848] systemd[1]: Mounted POSIX Message Queue File System.1351builder # [ 7.668229] systemd[1]: Mounted Kernel Debug File System.1352builder # [ 7.668593] systemd[1]: Mounted Kernel Trace File System.1353builder # [ 7.673428] systemd[1]: Mounted Kernel Configuration File System.1354builder # [ 7.676105] systemd[1]: Mounted FUSE Control File System.1355server # [ 7.639442] systemd[1]: Starting Coldplug All udev Devices...1356server # [ 7.645626] systemd[1]: Listening on Journal Log Access Socket.1357server # [ 7.649842] systemd[1]: Mounted Huge Pages File System.1358builder # [ 7.689055] systemd[1]: Finished Create List of Static Device Nodes.1359builder # [ 7.694904] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1360server # [ 7.655301] systemd[1]: Mounted POSIX Message Queue File System.1361server # [ 7.655796] systemd[1]: Mounted Kernel Debug File System.1362server # [ 7.656178] systemd[1]: Mounted Kernel Trace File System.1363server # [ 7.656554] systemd[1]: Mounted Kernel Configuration File System.1364server # [ 7.656931] systemd[1]: Mounted FUSE Control File System.1365server # [ 7.675694] systemd[1]: Finished Create List of Static Device Nodes.1366server # [ 7.682127] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1367builder # [ 7.769732] systemd[1]: Finished Load Kernel Modules.1368builder # [ 7.776923] EXT4-fs (vda): re-mounted f6cb108f-9094-42f6-84e8-bb0934fa2335.1369builder # [ 7.781270] systemd[1]: Starting Firewall...1370builder # [ 7.785195] systemd-journald[268]: Collecting audit messages is enabled.1371builder # [ 7.794830] systemd[1]: Starting Apply Kernel Variables...1372server # [ 7.756046] systemd-journald[269]: Collecting audit messages is enabled.1373builder # [ 7.800820] systemd[1]: Finished Remount Root and Kernel File Systems.1374builder # [ 7.803222] systemd[1]: Listening on Disk Image Download Service Socket.1375builder # [ 7.805485] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1376server # [ 7.775162] EXT4-fs (vda): re-mounted 35348747-4d43-47ce-b24f-a52e7f4cc6c2.1377builder # [ 7.821300] systemd[1]: Starting Load/Save OS Random Seed...1378builder # [ 7.821648] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1379server # [ 7.788028] systemd[1]: Finished Remount Root and Kernel File Systems.1380builder # [ 7.831233] systemd[1]: Started Journal Service.1381server # [ 7.788650] systemd[1]: Listening on Disk Image Download Service Socket.1382builder # [ 7.816642] systemd[1]: Queued start job for default target Multi-User System.1383server # [ 7.788951] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1384builder # [ 7.822733] systemd[1]: systemd-journald.service: Deactivated successfully.1385server # [ 7.787792] systemd[1]: Queued start job for default target Multi-User System.1386server # [ 7.804654] systemd[1]: Starting Load/Save OS Random Seed...1387builder # [ 7.829532] systemd-modules-load[269]: Module 'atkbd' is built in1388server # [ 7.806896] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1389server # [ 7.809966] systemd[1]: Started Journal Service.1390builder # [ 7.844693] systemd-modules-load[269]: Module 'loop' is built in1391server # [ 7.808965] systemd[1]: systemd-journald.service: Deactivated successfully.1392builder # [ 7.852897] systemd-modules-load[269]: Module 'tun' is built in1393server # [ 7.820853] systemd-modules-load[270]: Module 'atkbd' is built in1394builder # [ 7.862277] systemd[1]: Starting Flush Journal to Persistent Storage...1395server # [ 7.828461] systemd-modules-load[270]: Module 'loop' is built in1396server # [ 7.840525] systemd-modules-load[270]: Inserted module 'tls'1397server # [ 7.846448] systemd-modules-load[270]: Module 'tun' is built in1398server # [ 7.859423] systemd[1]: Starting Flush Journal to Persistent Storage...1399server # [ 7.873004] systemd[1]: Finished Load Kernel Modules.1400server # [ 7.878694] systemd[1]: Starting Firewall...1401server # [ 7.886504] systemd[1]: Starting Apply Kernel Variables...1402builder # [ 7.934283] systemd[1]: Finished Load/Save OS Random Seed.1403builder # [ 7.935280] systemd[1]: Reached target First Boot Complete.1404builder # [ 7.945972] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1405builder # [ 7.960838] systemd-oomd[270]: No swap; memory pressure usage will be degraded1406builder # [ 7.962220] systemd[1]: Starting Create Static Device Nodes in /dev...1407server # [ 7.933250] systemd-oomd[271]: No swap; memory pressure usage will be degraded1408builder # [ 7.990234] systemd-journald[268]: Received client request to flush runtime journal.1409server # [ 7.966345] systemd-journald[269]: Received client request to flush runtime journal.1410builder # [ 8.029237] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1411builder # [ 8.030460] systemd[1]: Finished Apply Kernel Variables.1412builder # [ 8.036599] systemd[1]: Finished Flush Journal to Persistent Storage.1413server # [ 8.021057] systemd[1]: Finished Load/Save OS Random Seed.1414server # [ 8.022106] systemd[1]: Reached target First Boot Complete.1415server # [ 8.032987] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1416server # [ 8.034302] systemd[1]: Starting Create Static Device Nodes in /dev...1417server # [ 8.035231] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1418server # [ 8.040644] systemd[1]: Finished Flush Journal to Persistent Storage.1419server # [ 8.049846] systemd[1]: Finished Apply Kernel Variables.1420builder # [ 8.387567] systemd[1]: Finished Create Static Device Nodes in /dev.1421builder # [ 8.389021] systemd[1]: Reached target Preparation for Local File Systems.1422builder # [ 8.402670] systemd[1]: Starting Rule-based Manager for Device Events and Files...1423builder # [ 8.407578] systemd[1]: Mounting /run/wrappers...1424builder # [ 8.580766] systemd[1]: Mounted /run/wrappers.1425builder # [ 8.596634] systemd[1]: Reached target Local File Systems.1426builder # [ 8.604439] systemd[1]: Listening on Boot Loader Control Service Socket.1427builder # [ 8.616241] systemd[1]: Starting register-nix-paths.service...1428builder # [ 8.656324] systemd[1]: Starting Create SUID/SGID Wrappers...1429builder # [ 8.666850] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1430builder # [ 8.682150] systemd[1]: Starting Save Transient machine-id to Disk...1431builder # [ 8.690447] systemd[1]: Starting Create System Files and Directories...1432server # [ 8.784931] systemd[1]: Finished Create Static Device Nodes in /dev.1433server # [ 8.796880] systemd[1]: Reached target Preparation for Local File Systems.1434server # [ 8.842718] systemd[1]: Mounting /run/wrappers...1435server # [ 8.866108] systemd[1]: Starting Rule-based Manager for Device Events and Files...1436builder # [ 8.955502] systemd-udevd[304]: Using default interface naming scheme 'v261'.1437builder # [ 9.047047] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1438builder # [ 9.074500] systemd[1]: Finished Save Transient machine-id to Disk.1439server # [ 9.857621] systemd[1]: Mounted /run/wrappers.1440server # [ 9.871601] systemd[1]: Reached target Local File Systems.1441server # [ 9.910376] systemd[1]: Listening on Boot Loader Control Service Socket.1442server # [ 9.948237] systemd[1]: Starting register-nix-paths.service...1443server # [ 9.972899] systemd[1]: Starting Create SUID/SGID Wrappers...1444server # [ 9.990951] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1445server # [ 10.016148] systemd[1]: Starting Save Transient machine-id to Disk...1446server # [ 10.023244] systemd[1]: Starting Create System Files and Directories...1447builder # [ 10.124860] systemd[1]: Finished Create System Files and Directories.1448builder # [ 10.128143] systemd[1]: Starting Rebuild Journal Catalog...1449builder # [ 10.130526] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1450server # [ 10.133169] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1451server # [ 10.145548] systemd[1]: Finished Save Transient machine-id to Disk.1452server # [ 10.163897] systemd-udevd[310]: Using default interface naming scheme 'v261'.1453builder # [ 10.219273] systemd[1]: Started Rule-based Manager for Device Events and Files.1454builder # [ 10.274426] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1455server # [ 10.265753] systemd[1]: Finished Create System Files and Directories.1456server # [ 10.278042] systemd[1]: Starting Rebuild Journal Catalog...1457server # [ 10.284803] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1458builder # [ 10.374831] systemd[1]: Finished Rebuild Journal Catalog.1459builder # [ 10.386191] systemd[1]: Starting Update is Completed...1460server # [ 10.431163] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1461builder # [ 10.486231] systemd[1]: Finished Update is Completed.1462server # [ 10.456599] systemd[1]: Started Rule-based Manager for Device Events and Files.1463server # [ 10.533646] systemd[1]: Finished Rebuild Journal Catalog.1464server # [ 10.541315] systemd[1]: Starting Update is Completed...1465server # [ 10.678890] systemd[1]: Finished Update is Completed.1466builder # [ 10.961282] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1467builder # [ 10.963733] systemd[1]: Finished Create SUID/SGID Wrappers.1468builder # [ 10.992434] systemd[1]: Finished register-nix-paths.service.1469server # [ 11.237391] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1470server # [ 11.241320] systemd[1]: Finished Create SUID/SGID Wrappers.1471server # [ 11.408742] systemd[1]: Finished register-nix-paths.service.1472builder # [ 11.586497] systemd[1]: Finished Firewall.1473builder # [ 11.628637] systemd[1]: Finished Coldplug All udev Devices.1474builder # [ 11.632179] systemd[1]: Reached target System Initialization.1475builder # [ 11.633073] systemd[1]: Started Discard unused filesystem blocks once a week.1476builder # [ 11.634092] systemd[1]: Started Daily Cleanup of Temporary Directories.1477builder # [ 11.635033] systemd[1]: Reached target Timer Units.1478builder # [ 11.635760] systemd[1]: Listening on D-Bus System Message Bus Socket.1479builder # [ 11.645597] systemd[1]: Starting niks3 auto-upload socket...1480builder # [ 11.646470] systemd[1]: Listening on Nix Daemon Socket.1481builder # [ 11.647529] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1482builder # [ 11.657621] systemd[1]: Listening on niks3 auto-upload socket.1483builder # [ 11.660191] systemd[1]: Reached target Socket Units.1484builder # [ 11.663561] systemd[1]: Starting D-Bus System Message Bus...1485builder # [ 11.694148] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1486builder # [ 11.721254] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1487builder # [ 11.728604] dbus-broker-launch[482]: Looking up NSS user entry for 'systemd-timesync'...1488builder # [ 11.733303] dbus-broker-launch[482]: NSS returned no entry for 'systemd-timesync'1489builder # [ 11.736062] dbus-broker-launch[482]: Invalid user-name in /nix/store/71r8vf07jmganasdg7vszm3sscc846m9-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1490builder # [ 11.749717] systemd[1]: Started D-Bus System Message Bus.1491builder # [ 11.754773] systemd[1]: Reached target Basic System.1492builder # [ 11.762900] systemd[1]: Starting Import lastlog data into lastlog2 database...1493builder # [ 11.783229] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1494builder # [ 11.788196] systemd[1]: Starting Post-Boot Actions...1495builder # [ 11.795225] systemd[1]: Started Reset console on configuration changes.1496builder # [ 11.801477] systemd[1]: Starting resolvconf update...1497builder # [ 11.861550] dbus-broker-launch[482]: Ready1498server # [ 11.844299] systemd[1]: Finished Firewall.1499server # [ 11.872917] systemd[1]: Finished Coldplug All udev Devices.1500server # [ 11.876218] systemd[1]: Reached target System Initialization.1501server # [ 11.877119] systemd[1]: Started Discard unused filesystem blocks once a week.1502server # [ 11.878127] systemd[1]: Started niks3 garbage collection timer.1503builder # [ 11.917671] systemd[1]: Finished Post-Boot Actions.1504server # [ 11.880367] systemd[1]: Started Daily Cleanup of Temporary Directories.1505server # [ 11.884732] systemd[1]: Reached target Timer Units.1506server # [ 11.885887] systemd[1]: Listening on D-Bus System Message Bus Socket.1507server # [ 11.886805] systemd[1]: Listening on niks3 server socket.1508server # [ 11.908082] systemd[1]: Listening on Nix Daemon Socket.1509builder # [ 11.947759] nsncd[485]: Sep 21 21:30:43.578 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1510server # [ 11.914693] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1511server # [ 11.915965] systemd[1]: Reached target Socket Units.1512builder # [ 11.960815] systemd[1]: Started Name Service Cache Daemon (nsncd).1513builder # [ 11.966191] systemd[1]: Reached target Host and Network Name Lookups.1514builder # [ 11.971302] systemd[1]: Reached target User and Group Name Lookups.1515builder # [ 11.977505] systemd[1]: Starting User Login Management...1516server # [ 11.940593] systemd[1]: Reached target Basic System.1517server # [ 11.947048] systemd[1]: Starting Import lastlog data into lastlog2 database...1518builder # [ 11.992394] systemd[1]: Finished Import lastlog data into lastlog2 database.1519server # [ 11.957151] systemd[1]: Starting Generate test mTLS certs...1520server # [ 11.965056] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1521server # [ 11.969793] systemd[1]: Starting Post-Boot Actions...1522server # [ 11.976401] systemd[1]: Started Reset console on configuration changes.1523server # [ 11.986314] systemd[1]: Starting resolvconf update...1524server # [ 12.002814] systemd[1]: Starting D-Bus System Message Bus...1525server # [ 12.013237] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1526server # [ 12.033932] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1527builder # [ 12.104574] systemd-logind[505]: New seat seat0.1528builder # [ 12.112619] systemd[1]: Started User Login Management.1529builder # [ 12.120339] systemd[1]: Starting linger-users.service...1530builder # [ 12.130160] systemd[1]: Stopped target Host and Network Name Lookups.1531builder # [ 12.132901] systemd[1]: Stopping Host and Network Name Lookups...1532builder # [ 12.146394] systemd[1]: Stopped target User and Group Name Lookups.1533server # [ 12.112244] systemd[1]: Finished Post-Boot Actions.1534builder # [ 12.150601] systemd[1]: Stopping User and Group Name Lookups...1535builder # [ 12.157808] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1536builder # [ 12.162230] systemd[1]: nscd.service: Deactivated successfully.1537builder # [ 12.167861] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1538builder # [ 12.174251] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1539server # [ 12.161815] systemd[1]: Started Name Service Cache Daemon (nsncd).1540server # [ 12.169066] systemd[1]: Reached target Host and Network Name Lookups.1541server # [ 12.180629] nsncd[496]: Sep 21 21:30:43.787 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1542builder # [ 12.222719] systemd[1]: linger-users.service: Deactivated successfully.1543builder # [ 12.229502] systemd[1]: Finished linger-users.service.1544server # [ 12.194090] systemd[1]: Reached target User and Group Name Lookups.1545server # [ 12.199412] systemd[1]: Starting User Login Management...1546builder # [ 12.248750] systemd[1]: Started Name Service Cache Daemon (nsncd).1547builder # [ 12.251927] systemd[1]: Reached target Host and Network Name Lookups.1548server # [ 12.211891] systemd[1]: Finished Import lastlog data into lastlog2 database.1549builder # [ 12.257431] nsncd[560]: Sep 21 21:30:43.889 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1550builder # [ 12.262515] systemd[1]: Reached target User and Group Name Lookups.1551server # [ 12.236196] niks3-test-certs-start[508]: -----1552builder # [ 12.276823] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1553server # [ 12.241837] dbus-broker-launch[500]: Looking up NSS user entry for 'systemd-timesync'...1554builder # [ 12.291538] systemd[1]: Finished resolvconf update.1555server # [ 12.253067] dbus-broker-launch[500]: NSS returned no entry for 'systemd-timesync'1556builder # [ 12.293737] systemd[1]: Reached target Preparation for Network.1557server # [ 12.262048] dbus-broker-launch[500]: Invalid user-name in /nix/store/d52c35hr9f1wxv7acm9lxdwwlp8ahl5f-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1558builder # [ 12.302732] systemd[1]: Starting DHCP Client...1559builder # [ 12.309096] systemd[1]: Starting Extra networking commands....1560server # [ 12.279852] systemd[1]: Started D-Bus System Message Bus.1561server # [ 12.297827] niks3-test-certs-start[536]: -----1562builder # [ 12.355882] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1563builder # [ 12.360472] systemd[1]: Started backdoor.service.1564server # [ 12.332674] dbus-broker-launch[500]: Ready1565server # [ 12.416402] systemd-logind[510]: New seat seat0.1566server # [ 12.429671] systemd[1]: Stopped target Host and Network Name Lookups.1567builder # connecting to host...1568server # [ 12.446751] systemd[1]: Stopping Host and Network Name Lookups...1569server # [ 12.457094] niks3-test-certs-start[547]: Certificate request self-signature ok1570server # [ 12.468487] niks3-test-certs-start[547]: subject=CN=server1571server # [ 12.477433] systemd[1]: Stopped target User and Group Name Lookups.1572server # [ 12.483269] systemd[1]: Stopping User and Group Name Lookups...1573server # [ 12.489071] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1574server # [ 12.494872] systemd[1]: Started User Login Management.1575server # [ 12.502402] systemd[1]: Starting linger-users.service...1576server # [ 12.508981] systemd[1]: nscd.service: Deactivated successfully.1577server # [ 12.515513] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1578server # [ 12.524450] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1579builder # [ 12.572437] dhcpcd[596]: dhcpcd-10.3.2 starting1580builder # [ 12.585363] dhcpcd[638]: dev: loaded udev1581server # [ 12.557050] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1582server # [ 12.569156] niks3-test-certs-start[578]: -----1583builder # [ 12.632242] 8021q: 802.1Q VLAN Support v1.81584server # [ 12.578267] systemd[1]: linger-users.service: Deactivated successfully.1585server # [ 12.582223] systemd[1]: Finished linger-users.service.1586server # [ 12.587085] systemd[1]: Started Name Service Cache Daemon (nsncd).1587server # [ 12.594863] nsncd[567]: Sep 21 21:30:44.208 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1588builder # [ 12.635440] (udev-worker)[554]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1589builder # [ 12.641395] (udev-worker)[554]: Network interface NamePolicy= disabled on kernel command line.1590server # [ 12.606512] systemd[1]: Reached target Host and Network Name Lookups.1591builder # [ 12.645134] (udev-worker)[540]: Network interface NamePolicy= disabled on kernel command line.1592server # [ 12.609961] systemd[1]: Reached target User and Group Name Lookups.1593server # [ 12.639976] systemd[1]: Finished resolvconf update.1594builder # [ 12.679278] systemd[1]: Finished Extra networking commands..1595server # [ 12.643661] systemd[1]: Reached target Preparation for Network.1596builder # [ 12.692362] systemd[1]: Reached target Network.1597server # [ 12.656177] systemd[1]: Starting DHCP Client...1598builder # [ 12.699619] systemd[1]: Starting Permit User Sessions...1599server # [ 12.661448] systemd[1]: Starting Extra networking commands....1600server # [ 12.675796] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1601server # [ 12.685616] systemd[1]: Started backdoor.service.1602builder # [ 12.754755] cfg80211: Loading compiled-in X.509 certificates for regulatory database1603server # [ 12.718866] niks3-test-certs-start[594]: Certificate request self-signature ok1604server # [ 12.728122] niks3-test-certs-start[594]: subject=CN=niks3 test client1605builder # [ 12.791226] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1606builder # [ 12.791792] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1607builder # [ 12.794735] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21608builder # [ 12.795073] cfg80211: failed to load regulatory.db1609builder # [ 12.800760] systemd[1]: Finished Permit User Sessions.1610builder # [ 12.807470] systemd[1]: Started Getty on tty1.1611builder # [ 12.810752] systemd[1]: Reached target Login Prompts.1612server # [ 12.791537] systemd[1]: Finished Generate test mTLS certs.1613server # connecting to host...1614builder # [ 12.882949] 8021q: adding VLAN 0 to HW filter on device eth01615builder # [ 12.870555] dhcpcd[638]: eth0: waiting for carrier1616builder # [ 12.872821] dhcpcd[638]: libudev: received NULL device1617builder # [ 12.873605] dhcpcd[638]: libudev: received NULL device1618builder # [ 12.874447] dhcpcd[638]: eth0: carrier acquired1619builder # [ 12.886156] dhcpcd[638]: DUID 00:01:00:01:32:44:5e:84:52:54:00:12:34:561620builder # [ 12.887178] dhcpcd[638]: eth0: IAID 00:12:34:561621builder # [ 12.887794] dhcpcd[638]: eth0: adding address fe80::5054:ff:fe12:34561622server: Guest shell says: b'Spawning backdoor root shell...\n'1623server: connected to guest root shell1624builder # [ 12.946546] dhcpcd[638]: eth0: IAID 00:12:34:561625server: (connecting took 13.22 seconds)1626builder # [ 12.947300] systemd[1]: Condition check resulted in Virtio network device being skipped.1627server: (finished: waiting for the VM to finish booting, in 13.22 seconds)1628builder # [ 12.953370] systemd[1]: Starting Address configuration of eth1...1629server # [ 12.934830] (udev-worker)[365]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1630server # [ 12.947806] (udev-worker)[365]: Network interface NamePolicy= disabled on kernel command line.1631builder # [ 12.996114] systemd-logind[505]: Watching system buttons on /dev/input/event0 (gpio-keys)1632builder # [ 13.042037] 8021q: adding VLAN 0 to HW filter on device eth11633server # [ 12.993070] dhcpcd[609]: dhcpcd-10.3.2 starting1634builder # [ 13.041500] network-addresses-eth1-start[670]: adding address 192.168.1.1/24... done1635server # [ 13.010082] dhcpcd[654]: dev: loaded udev1636builder # [ 13.052657] network-addresses-eth1-start[670]: adding address 2001:db8:1::1/64... done1637server # [ 13.018797] (udev-worker)[379]: Network interface NamePolicy= disabled on kernel command line.1638builder # [ 13.066622] systemd[1]: Finished Address configuration of eth1.1639server # [ 13.055310] 8021q: 802.1Q VLAN Support v1.81640builder # [ 13.135536] mousedev: PS/2 mouse device common for all mice1641server # [ 13.148468] systemd[1]: Finished Extra networking commands..1642builder # [ 13.187193] systemd-logind[505]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1643server # [ 13.149469] systemd[1]: Reached target Network.1644server # [ 13.173785] cfg80211: Loading compiled-in X.509 certificates for regulatory database1645server # [ 13.164627] systemd[1]: Started Mock OIDC server for testing.1646server # [ 13.174366] systemd[1]: Starting Nginx Web Server...1647server # [ 13.184385] systemd[1]: Starting PostgreSQL Server...1648server # [ 13.185278] systemd[1]: Started RustFS S3-compatible object storage.1649server # [ 13.213651] systemd[1]: Starting Setup RustFS bucket...1650server # [ 13.240846] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1651server # [ 13.241386] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1652server # [ 13.244546] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21653server # [ 13.244897] cfg80211: failed to load regulatory.db1654server # [ 13.240719] systemd[1]: Starting Permit User Sessions...1655server # [ 13.379621] systemd[1]: Finished Permit User Sessions.1656server # [ 13.389789] systemd[1]: Started Getty on tty1.1657server # [ 13.390499] systemd[1]: Reached target Login Prompts.1658server # [ 13.518492] systemd[1]: Condition check resulted in Virtio network device being skipped.1659server # [ 13.553451] systemd[1]: Starting Address configuration of eth1...1660server # [ 13.735840] 8021q: adding VLAN 0 to HW filter on device eth01661server # [ 13.732648] dhcpcd[654]: eth0: waiting for carrier1662server # [ 13.753674] dhcpcd[654]: eth0: waiting for carrier1663server # [ 13.769795] dhcpcd[654]: eth0: carrier acquired1664server # [ 13.835527] dhcpcd[654]: DUID 00:01:00:01:32:44:5e:85:52:54:00:12:34:561665server # [ 13.845875] dhcpcd[654]: eth0: IAID 00:12:34:561666server # [ 13.846600] dhcpcd[654]: eth0: adding address fe80::5054:ff:fe12:34561667server # [ 13.964330] 8021q: adding VLAN 0 to HW filter on device eth11668server # [ 14.015764] network-addresses-eth1-start[701]: adding address 192.168.1.2/24... done1669server # [ 14.059954] network-addresses-eth1-start[701]: adding address 2001:db8:1::2/64... done1670builder # [ 14.125151] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31671server # [ 14.117873] systemd[1]: Finished Address configuration of eth1.1672server # [ 14.260323] nginx-pre-start[713]: nginx: the configuration file /nix/store/39wgd2lh1lil5fffm3156q3ci94xyljp-nginx.conf syntax is ok1673server # [ 14.261996] nginx-pre-start[713]: nginx: configuration file /nix/store/39wgd2lh1lil5fffm3156q3ci94xyljp-nginx.conf test is successful1674server # [ 14.292780] systemd[1]: Started Nginx Web Server.1675server # [ 14.338364] dhcpcd[654]: eth0: soliciting a DHCP lease1676server # [ 14.339654] postgresql-pre-start[722]: The files belonging to this database system will be owned by user "postgres".1677server # [ 14.365451] postgresql-pre-start[722]: This user must also own the server process.1678server # [ 14.366615] postgresql-pre-start[722]: The database cluster will be initialized with locale "en_US.UTF-8".1679server # [ 14.384650] postgresql-pre-start[722]: The default database encoding has accordingly been set to "UTF8".1680server # [ 14.385959] postgresql-pre-start[722]: The default text search configuration will be set to "english".1681server # [ 14.387140] postgresql-pre-start[722]: Data page checksums are enabled.1682builder # [ 14.443302] dhcpcd[638]: eth0: soliciting a DHCP lease1683builder # [ 14.448696] dhcpcd[638]: eth0: offered 10.0.2.15 from 10.0.2.21684server # [ 14.388010] postgresql-pre-start[722]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok1685server # [ 14.413493] postgresql-pre-start[722]: creating subdirectories ... ok1686server # [ 14.414430] postgresql-pre-start[722]: selecting dynamic shared memory implementation ... posix1687server # [ 14.415663] dhcpcd[654]: eth0: offered 10.0.2.15 from 10.0.2.21688builder # [ 14.456285] dhcpcd[638]: eth0: probing address 10.0.2.15/241689server # [ 14.437332] dhcpcd[654]: eth0: probing address 10.0.2.15/241690builder # [ 14.521599] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1691builder # [ 14.527888] systemd[1]: Starting Virtual Console Setup...1692builder # [ 14.553234] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1693builder # [ 14.556705] systemd[1]: Stopped Virtual Console Setup.1694builder # [ 14.563156] systemd[1]: Starting Virtual Console Setup...1695builder # [ 14.686682] systemd-logind[505]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1696builder # [ 14.768001] systemd-vconsole-setup[687]: Configuration of first virtual console was skipped, ignoring remaining ones.1697builder # [ 14.772388] systemd[1]: Finished Virtual Console Setup.1698server # [ 14.761728] postgresql-pre-start[722]: selecting default "max_connections" ... 1001699server # [ 14.959586] systemd-logind[510]: Watching system buttons on /dev/input/event0 (gpio-keys)1700server # [ 14.973620] mock-oidc-server[672]: Mock OIDC Server running1701server # [ 14.980316] mock-oidc-server[672]: OIDC Address: 127.0.0.1:80801702server # [ 14.983399] mock-oidc-server[672]: Issue Address: 127.0.0.1:80811703server # [ 14.985112] mock-oidc-server[672]: Issuer: http://127.0.0.1:8080/oidc1704server # [ 14.985980] mock-oidc-server[672]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json1705server # [ 14.996627] mock-oidc-server[672]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration1706server # [ 14.997968] mock-oidc-server[672]: Issue tokens: http://127.0.0.1:8081/issue?sub=...1707server # [ 15.025390] postgresql-pre-start[722]: selecting default "shared_buffers" ... 128MB1708builder # [ 15.171075] dhcpcd[638]: eth0: soliciting an IPv6 router1709builder # [ 15.176101] dhcpcd[638]: eth0: Router Advertisement from fe80::21710builder # [ 15.178849] dhcpcd[638]: eth0: adding address fec0::5054:ff:fe12:3456/641711builder # [ 15.181910] dhcpcd[638]: eth0: adding route to fec0::/641712builder # [ 15.184539] dhcpcd[638]: eth0: adding default route via fe80::21713server # [ 15.322208] mousedev: PS/2 mouse device common for all mice1714server # [ 15.794883] systemd-logind[510]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1715server # [ 16.315859] dhcpcd[654]: eth0: soliciting an IPv6 router1716server # [ 16.320761] dhcpcd[654]: eth0: Router Advertisement from fe80::21717server # [ 16.321724] dhcpcd[654]: eth0: adding address fec0::5054:ff:fe12:3456/641718server # [ 16.322697] dhcpcd[654]: eth0: adding route to fec0::/641719server # [ 16.323483] dhcpcd[654]: eth0: adding default route via fe80::21720server # [ 17.035687] postgresql-pre-start[722]: selecting default time zone ... UTC1721server # [ 17.041410] postgresql-pre-start[722]: creating configuration files ... ok1722server # [ 17.594624] postgresql-pre-start[722]: running bootstrap script ... ok1723server # [ 17.713951] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31724server # [ 18.565497] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1725server # [ 18.580996] systemd[1]: Starting Virtual Console Setup...1726server # [ 18.611566] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1727server # [ 18.613056] systemd[1]: Stopped Virtual Console Setup.1728server # [ 18.616965] systemd[1]: Starting Virtual Console Setup...1729server # [ 18.756478] systemd-logind[510]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1730server # [ 18.944365] systemd-vconsole-setup[806]: Configuration of first virtual console was skipped, ignoring remaining ones.1731server # [ 18.952927] systemd[1]: Finished Virtual Console Setup.1732server # [ 19.045081] dhcpcd[654]: eth0: leased 10.0.2.15 for 86400 seconds1733server # [ 19.048370] dhcpcd[654]: eth0: adding route to 10.0.2.0/241734server # [ 19.053072] dhcpcd[654]: eth0: adding default route via 10.0.2.21735server # [ 19.131350] postgresql-pre-start[722]: performing post-bootstrap initialization ... ok1736server # [ 19.226212] systemd[1]: Started DHCP Client.1737server # [ 19.315968] postgresql-pre-start[722]: syncing data to disk ... ok1738server # [ 19.316963] postgresql-pre-start[722]: initdb: warning: enabling "trust" authentication for local connections1739server # [ 19.318207] postgresql-pre-start[722]: 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.1740server # [ 19.320337] postgresql-pre-start[722]: Success. You can now start the database server using:1741server # [ 19.321574] postgresql-pre-start[722]: pg_ctl -D /var/lib/postgresql/18 -l logfile start1742server # [ 19.417930] postgres[871]: [871] LOG: starting PostgreSQL 18.6 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit1743server # [ 19.420904] postgres[871]: [871] LOG: listening on IPv6 address "::1", port 54321744server # [ 19.422088] postgres[871]: [871] LOG: listening on IPv4 address "127.0.0.1", port 54321745server # [ 19.425324] postgres[871]: [871] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1746server # [ 19.439077] postgres[880]: [880] LOG: database system was shut down at 2026-09-21 21:30:50 GMT1747server # [ 19.445131] postgres[871]: [871] LOG: database system is ready to accept connections1748server # [ 19.448848] systemd[1]: Started PostgreSQL Server.1749server # [ 19.453885] systemd[1]: Starting PostgreSQL Setup Scripts...1750server # [ 19.637605] postgresql-setup-start[891]: CREATE DATABASE1751server # [ 19.677347] postgresql-setup-start[896]: CREATE ROLE1752server # [ 19.694818] postgresql-setup-start[898]: ALTER DATABASE1753server # [ 19.701431] systemd[1]: Finished PostgreSQL Setup Scripts.1754builder # [ 19.739118] dhcpcd[638]: eth0: leased 10.0.2.15 for 86400 seconds1755server # [ 19.703115] systemd[1]: Reached target PostgreSQL.1756builder # [ 19.742445] dhcpcd[638]: eth0: adding route to 10.0.2.0/241757builder # [ 19.747508] dhcpcd[638]: eth0: adding default route via 10.0.2.21758builder # [ 19.886796] systemd[1]: Started DHCP Client.1759builder # [ 19.888617] systemd[1]: Reached target Multi-User System.1760builder # [ 19.889968] systemd[1]: Startup finished in 927ms (kernel) + 5.034s (initrd) + 13.927s (userspace) = 19.889s.1761server: (finished: waiting for unit postgresql.service, in 20.37 seconds)1762server: waiting for unit rustfs.service1763server: (finished: waiting for unit rustfs.service, in 0.04 seconds)1764server: waiting for unit rustfs-setup.service1765server # [ 34.548900] rustfs-setup-start[970]: mb s3://niks3-test1766server # [ 34.561231] systemd[1]: Finished Setup RustFS bucket.1767server # [ 34.577135] systemd[1]: Starting niks3 server...1768server # [ 34.696416] postgres[985]: [985] ERROR: relation "goose_db_version" does not exist at character 361769server # [ 34.702026] postgres[985]: [985] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1770server # [ 34.726185] niks3-server[980]: 2026/09/21 21:31:06 OK 20241026095416_initial_model.sql (14.52ms)1771server # [ 34.733794] niks3-server[980]: 2026/09/21 21:31:06 OK 20251210153512_drop_unused_gin_index.sql (3.77ms)1772server # [ 34.737343] niks3-server[980]: 2026/09/21 21:31:06 OK 20251218171726_add_pins.sql (7.61ms)1773server # [ 34.741980] niks3-server[980]: 2026/09/21 21:31:06 OK 20260628120000_add_object_size_and_stats.sql (4.6ms)1774server # [ 34.746911] niks3-server[980]: 2026/09/21 21:31:06 OK 20260905000000_add_claims.sql (4.89ms)1775server # [ 34.750787] niks3-server[980]: 2026/09/21 21:31:06 OK 20260920000000_drop_claims.sql (3.76ms)1776server # [ 34.752805] niks3-server[980]: 2026/09/21 21:31:06 goose: successfully migrated database to version: 202609200000001777server # [ 34.756242] niks3-server[980]: 2026/09/21 21:31:06 OK 1_commit_pending_closure.sql (5.43ms)1778server # [ 34.758832] niks3-server[980]: 2026/09/21 21:31:06 OK 2_object_stats_trigger.sql (2.46ms)1779server # [ 34.760854] niks3-server[980]: 2026/09/21 21:31:06 goose: up to current file version: 21780server # [ 34.766327] niks3-server[980]: 2026/09/21 21:31:06 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:8080/oidc1781server # [ 34.767948] niks3-server[980]: 2026/09/21 21:31:06 INFO OIDC authentication enabled config=/nix/store/z019gvf710g6d8396v9cvsyidwg4d4yk-niks3-oidc.json1782server # [ 34.770524] niks3-server[980]: 2026/09/21 21:31:06 INFO Loaded signing key name=niks3-test-1 path=/nix/store/6jgpl7z0sjpvlynzpcik37wwz06qi4np-niks3-signing-key1783server # [ 34.793587] niks3-server[980]: 2026/09/21 21:31:06 INFO Using socket-activated listener address=0.0.0.0:57511784server # [ 34.797317] systemd[1]: Started niks3 server.1785server # [ 34.798062] niks3-server[980]: 2026/09/21 21:31:06 INFO systemd watchdog enabled interval=15s1786server # [ 34.799275] niks3-server[980]: 2026/09/21 21:31:06 INFO Starting HTTP server address=0.0.0.0:57511787server # [ 34.800635] systemd[1]: Reached target Multi-User System.1788server # [ 34.801385] systemd[1]: Startup finished in 942ms (kernel) + 4.980s (initrd) + 28.872s (userspace) = 34.795s.1789server: (finished: waiting for unit rustfs-setup.service, in 15.05 seconds)1790server: waiting for unit mock-oidc.service1791server: (finished: waiting for unit mock-oidc.service, in 0.05 seconds)1792server: waiting for unit niks3.service1793server: (finished: waiting for unit niks3.service, in 0.04 seconds)1794server: waiting for TCP port 5751 on localhost1795server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded!1796server: (finished: waiting for TCP port 5751 on localhost, in 0.04 seconds)1797server: waiting for TCP port 8080 on localhost1798server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded!1799server: (finished: waiting for TCP port 8080 on localhost, in 0.02 seconds)1800server: waiting for TCP port 9000 on localhost1801server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded!1802server: (finished: waiting for TCP port 9000 on localhost, in 0.02 seconds)1803server: must succeed: mkdir -p /tmp/test-config1804server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.02 seconds)1805server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token1806server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.01 seconds)1807server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31808server # [ 35.497197] systemd[1]: Created slice Slice /system/nix-daemon.1809server # [ 35.501455] systemd[1]: Started Nix Daemon instance (PID 1029/UID 0).1810server # [ 35.563331] nix-daemon[1031]: remote pid 1029 is unknown user (trusted)1811server # [ 35.582053] systemd[1]: nix-daemon@0-1-1029_1030-0.service: Deactivated successfully.1812server # [ 35.587575] niks3-server[980]: 2026/09/21 21:31:07 INFO Received uploads request method=POST path=/api/pending_closures1813server # time=2026-09-21T21:31:07.230Z level=INFO msg="Uploading 5 paths to server (0 already cached)"1814server # time=2026-09-21T21:31:07.232Z level=INFO msg="Uploading h0hd048jzxs7fx9a6g75jwrrhbg50bp8-libunistring-1.4.2 (2.0MB)"1815server # time=2026-09-21T21:31:07.234Z level=INFO msg="Uploading waax852balprvsvdziyaf1h7r3ldvakg-xgcc-15.3.0-libgcc (150.1KB)"1816server # time=2026-09-21T21:31:07.237Z level=INFO msg="Uploading m54cs0m994hc3n9lax7sg98sgy729qsa-glibc-2.42-84 (44.4MB)"1817server # time=2026-09-21T21:31:07.239Z level=INFO msg="Uploading 0s40b0an0cz4vypbji92cb7qbr65icl4-libidn2-2.3.8 (366.1KB)"1818server # time=2026-09-21T21:31:07.242Z level=INFO msg="Uploading kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 (287.5KB)"1819server # [ 35.809024] niks3-server[980]: 2026/09/21 21:31:07 INFO Registered completed upload object_key=nar/0x48iv4crw96wayixvwmbhz9znvm9djawdf9j0wszdqdxpn92kw1.nar.zst1820server # [ 35.844368] niks3-server[980]: 2026/09/21 21:31:07 INFO Registered completed upload object_key=waax852balprvsvdziyaf1h7r3ldvakg.ls1821server # [ 35.883275] niks3-server[980]: 2026/09/21 21:31:07 INFO Registered completed upload object_key=nar/07lydviym1nzmxy9y72apc8lgzy9v6x110r4vxac3kk2xslg0gcg.nar.zst1822server # [ 35.894536] niks3-server[980]: 2026/09/21 21:31:07 INFO Registered completed upload object_key=nar/0rbj231dr19n59qcahf76cdwmqlqv239j85j94g8c89kf9kf5p04.nar.zst1823server # [ 35.904257] niks3-server[980]: 2026/09/21 21:31:07 INFO Registered completed upload object_key=h0hd048jzxs7fx9a6g75jwrrhbg50bp8.ls1824server # [ 35.922282] niks3-server[980]: 2026/09/21 21:31:07 INFO Registered completed upload object_key=0s40b0an0cz4vypbji92cb7qbr65icl4.ls1825server # [ 36.040130] niks3-server[980]: 2026/09/21 21:31:07 INFO Registered completed upload object_key=nar/16z5dldwcmmhv0ij0pz8h0z98mv285jvqw0ly64fsg0s9lbffamb.nar.zst1826server # [ 36.058009] niks3-server[980]: 2026/09/21 21:31:07 INFO Registered completed upload object_key=kwhxkl8yn5y8wqiq11jsybagw3fbc4iv.ls1827server # [ 36.713776] niks3-server[980]: 2026/09/21 21:31:08 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1828server # [ 36.727070] niks3-server[980]: 2026/09/21 21:31:08 INFO Completed multipart upload object_key=nar/1vzbqdhwmbhwll0g4bniam5qjj71a1ci2n6lc100n2yfpbpygw8c.nar.zst upload_id=YzJlNWIyOWEtMzkzNS00OTk0LTgwYzctM2FhNWE4ODZhZDNhLmVhYTRlZTEyLTk0NGYtNDM1ZS1hMzA3LWQ1MDIxZDk1MjBhNngxNzkwMDI2MjY3MjIyNzQ2NjAw parts=11829server # [ 36.738070] niks3-server[980]: 2026/09/21 21:31:08 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1830server # time=2026-09-21T21:31:08.367Z level=INFO msg="Uploading 5 narinfos"1831server # [ 36.746136] niks3-server[980]: 2026/09/21 21:31:08 INFO Signed narinfos id=1 count=51832server # [ 36.789532] niks3-server[980]: 2026/09/21 21:31:08 INFO Registered completed upload object_key=kwhxkl8yn5y8wqiq11jsybagw3fbc4iv.narinfo1833server # [ 36.796178] niks3-server[980]: 2026/09/21 21:31:08 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1834server # [ 36.804138] niks3-server[980]: 2026/09/21 21:31:08 INFO Registered completed upload object_key=waax852balprvsvdziyaf1h7r3ldvakg.narinfo1835server # [ 36.814405] niks3-server[980]: 2026/09/21 21:31:08 INFO Registered completed upload object_key=m54cs0m994hc3n9lax7sg98sgy729qsa.ls1836server # time=2026-09-21T21:31:08.445Z level=INFO msg="Upload complete. (1.376s)"1837server # [ 36.822774] niks3-server[980]: 2026/09/21 21:31:08 INFO Completed upload id=11838server # [ 36.824995] niks3-server[980]: 2026/09/21 21:31:08 INFO Registered completed upload object_key=h0hd048jzxs7fx9a6g75jwrrhbg50bp8.narinfo1839server # [ 36.838624] niks3-server[980]: 2026/09/21 21:31:08 INFO Registered completed upload object_key=m54cs0m994hc3n9lax7sg98sgy729qsa.narinfo1840server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 1.49 seconds)1841server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token1842server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.01 seconds)1843server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31844server # [ 36.914760] niks3-server[980]: 2026/09/21 21:31:08 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]1845server # [ 36.958937] systemd[1]: Started Nix Daemon instance (PID 1062/UID 0).1846server # [ 37.016331] nix-daemon[1064]: remote pid 1062 is unknown user (trusted)1847server # [ 37.030851] systemd[1]: nix-daemon@1-2-1062_1063-0.service: Deactivated successfully.1848server # [ 37.036359] niks3-server[980]: 2026/09/21 21:31:08 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]1849server # time=2026-09-21T21:31:08.664Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"1850server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.19 seconds)1851server: waiting for unit nginx.service1852server: (finished: waiting for unit nginx.service, in 0.03 seconds)1853server: waiting for TCP port 443 on localhost1854server # Connection to localhost (::1) 443 port [tcp/https] succeeded!1855server: (finished: waiting for TCP port 443 on localhost, in 0.02 seconds)1856server: must succeed: /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/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/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31857server # time=2026-09-21T21:31:08.775Z 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.pem1858server # time=2026-09-21T21:31:08.790Z level=INFO msg="All 1 paths already cached"1859server: (finished: must succeed: /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/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/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.07 seconds)1860server: must fail: /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31861server # time=2026-09-21T21:31:08.809Z 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)"1862server: (finished: must fail: /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.02 seconds)1863server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31864server # time=2026-09-21T21:31:08.867Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem1865server # time=2026-09-21T21:31:08.875Z level=INFO msg="All 1 paths already cached"1866server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.07 seconds)1867server: must succeed: cd /etc/niks3-test-certs && /nix/store/ai0jh6nymysikgd4liv2k8iv9kmipcva-openssl-3.6.4-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client'1868server # -----1869server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/ai0jh6nymysikgd4liv2k8iv9kmipcva-openssl-3.6.4-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client', in 0.02 seconds)1870server: must succeed: cd /etc/niks3-test-certs && /nix/store/ai0jh6nymysikgd4liv2k8iv9kmipcva-openssl-3.6.4-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem1871server # Certificate request self-signature ok1872server # subject=CN=other client1873server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/ai0jh6nymysikgd4liv2k8iv9kmipcva-openssl-3.6.4-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem, in 0.04 seconds)1874server: must fail: /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/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/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31875server # time=2026-09-21T21:31:08.995Z 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.pem1876server # [ 37.380164] niks3-server[980]: 2026/09/21 21:31:09 WARN mTLS auth: subject not in bound subjects subject="CN=other client"1877server # [ 37.422681] systemd[1]: Started Nix Daemon instance (PID 1129/UID 0).1878server # [ 37.478562] nix-daemon[1131]: remote pid 1129 is unknown user (trusted)1879server # [ 37.494019] systemd[1]: nix-daemon@2-3-1129_1130-0.service: Deactivated successfully.1880server # [ 37.500677] niks3-server[980]: 2026/09/21 21:31:09 WARN mTLS auth: subject not in bound subjects subject="CN=other client"1881server # time=2026-09-21T21:31:09.126Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"1882server: (finished: must fail: /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/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/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.19 seconds)1883server: must succeed: mkdir -p /tmp/test-store1884server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.02 seconds)1885server: must succeed: 1886 export AWS_ACCESS_KEY_ID=rustfsadmin1887export AWS_SECRET_ACCESS_KEY=rustfsadmin1888 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.318891890server: (finished: must succeed: 1891 export AWS_ACCESS_KEY_ID=rustfsadmin1892export AWS_SECRET_ACCESS_KEY=rustfsadmin1893 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31894, in 0.68 seconds)1895server: must succeed: 1896cat > /tmp/test-drv.nix << 'EOF'1897derivation {1898 name = "test-build-log";1899 system = builtins.currentSystem;1900 builder = "/bin/sh";1901 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];1902}1903EOF19041905server: (finished: 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}1913EOF1914, in 0.02 seconds)1915server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix1916server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring1917server # [ 38.278335] systemd[1]: Started Nix Daemon instance (PID 1173/UID 0).1918server # [ 38.334735] nix-daemon[1177]: remote pid 1173 is unknown user (trusted)1919server # this derivation will be built:1920server # /nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv1921server # building '/nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv'...1922server # test-build-log> test build log output1923server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.25 seconds)1924server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1925server # [ 38.531023] systemd[1]: nix-daemon@3-4-1173_1174-0.service: Deactivated successfully.1926server # [ 38.588367] systemd[1]: Started Nix Daemon instance (PID 1204/UID 0).1927server # [ 38.642597] nix-daemon[1206]: remote pid 1204 is unknown user (trusted)1928server # [ 38.656253] systemd[1]: nix-daemon@4-5-1204_1205-0.service: Deactivated successfully.1929server # [ 38.664483] niks3-server[980]: 2026/09/21 21:31:10 INFO Received uploads request method=POST path=/api/pending_closures1930server # time=2026-09-21T21:31:10.293Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1931server # time=2026-09-21T21:31:10.294Z level=INFO msg="Uploading z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log (128B)"1932server # [ 38.686886] niks3-server[980]: 2026/09/21 21:31:10 INFO Registered completed upload object_key=nar/00zns3gj9hwz2a4b0i07y7nmxybq59lh24bl3xsxblcl6333mjil.nar.zst1933server # [ 38.693983] niks3-server[980]: 2026/09/21 21:31:10 INFO Registered completed upload object_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.ls1934server # time=2026-09-21T21:31:10.322Z level=INFO msg="Uploading 1 narinfos"1935server # [ 38.700184] niks3-server[980]: 2026/09/21 21:31:10 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1936server # [ 38.703838] niks3-server[980]: 2026/09/21 21:31:10 INFO Signed narinfos id=2 count=11937server # [ 38.712129] niks3-server[980]: 2026/09/21 21:31:10 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1938server # [ 38.718952] niks3-server[980]: 2026/09/21 21:31:10 INFO Registered completed upload object_key=log/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv1939server # time=2026-09-21T21:31:10.345Z level=INFO msg="Upload complete. (178ms)"1940server # [ 38.722633] niks3-server[980]: 2026/09/21 21:31:10 INFO Completed upload id=21941server # [ 38.724683] niks3-server[980]: 2026/09/21 21:31:10 INFO Registered completed upload object_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.narinfo1942server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log, in 0.26 seconds)1943server: must succeed: 1944 export AWS_ACCESS_KEY_ID=rustfsadmin1945export AWS_SECRET_ACCESS_KEY=rustfsadmin1946 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log19471948server: (finished: must succeed: 1949 export AWS_ACCESS_KEY_ID=rustfsadmin1950export AWS_SECRET_ACCESS_KEY=rustfsadmin1951 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1952, in 0.16 seconds)1953subtest: push --stdin streams paths and reports each one1954server: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }'1955server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring1956server # [ 38.949456] systemd[1]: Started Nix Daemon instance (PID 1222/UID 0).1957server # [ 39.003315] nix-daemon[1226]: remote pid 1222 is unknown user (trusted)1958server # this derivation will be built:1959server # /nix/store/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv1960server # building '/nix/store/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv'...1961server: (finished: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }', in 0.21 seconds)1962server: must succeed: printf '%s\n\n%s\n' /nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log | NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --stdin1963server # [ 39.102743] systemd[1]: nix-daemon@5-6-1222_1223-0.service: Deactivated successfully.1964server # [ 39.208351] systemd[1]: Started Nix Daemon instance (PID 1255/UID 0).1965server # [ 39.261869] nix-daemon[1257]: remote pid 1255 is unknown user (trusted)1966server # [ 39.275377] systemd[1]: nix-daemon@6-7-1255_1256-0.service: Deactivated successfully.1967server # [ 39.279592] niks3-server[980]: 2026/09/21 21:31:10 INFO Received uploads request method=POST path=/api/pending_closures1968server # time=2026-09-21T21:31:10.911Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1969server # time=2026-09-21T21:31:10.912Z level=INFO msg="Uploading 7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test (120B)"1970server # [ 39.308646] niks3-server[980]: 2026/09/21 21:31:10 INFO Registered completed upload object_key=nar/1hj1r0g515rfxjx685mfn3dgr6akqbn4gf64snlv6bxaxph69lna.nar.zst1971server # [ 39.314313] niks3-server[980]: 2026/09/21 21:31:10 INFO Registered completed upload object_key=log/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv1972server # time=2026-09-21T21:31:10.942Z level=INFO msg="Uploading 1 narinfos"1973server # [ 39.320585] niks3-server[980]: 2026/09/21 21:31:10 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1974server # [ 39.324514] niks3-server[980]: 2026/09/21 21:31:10 INFO Signed narinfos id=3 count=11975server # [ 39.327501] niks3-server[980]: 2026/09/21 21:31:10 INFO Registered completed upload object_key=7j8147s8h4si9jmv6bxa86ql7z6j2m37.ls1976server # [ 39.332323] niks3-server[980]: 2026/09/21 21:31:10 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1977server # time=2026-09-21T21:31:10.960Z level=INFO msg="Upload complete. (175ms)"1978server # [ 39.336998] niks3-server[980]: 2026/09/21 21:31:10 INFO Completed upload id=31979server # [ 39.338540] niks3-server[980]: 2026/09/21 21:31:10 INFO Registered completed upload object_key=7j8147s8h4si9jmv6bxa86ql7z6j2m37.narinfo1980server: (finished: must succeed: printf '%s\n\n%s\n' /nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log | NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --stdin, in 0.25 seconds)1981server: must succeed: 1982 export AWS_ACCESS_KEY_ID=rustfsadmin1983export AWS_SECRET_ACCESS_KEY=rustfsadmin1984 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test1985 1986server: (finished: must succeed: 1987 export AWS_ACCESS_KEY_ID=rustfsadmin1988export AWS_SECRET_ACCESS_KEY=rustfsadmin1989 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test1990 , in 0.19 seconds)1991(finished: subtest: push --stdin streams paths and reports each one, in 0.64 seconds)1992server: must succeed: readlink /etc/niks3-test/symlink-wrapper1993server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.02 seconds)1994server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper1995server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.02 seconds)1996server: must succeed: test -L /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper1997server: (finished: must succeed: test -L /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper, in 0.01 seconds)1998server: must succeed: readlink /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper1999server: (finished: must succeed: readlink /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper, in 0.02 seconds)2000server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper2001server # [ 39.692604] systemd[1]: Started Nix Daemon instance (PID 1301/UID 0).2002server # [ 39.746081] nix-daemon[1303]: remote pid 1301 is unknown user (trusted)2003server # [ 39.760082] systemd[1]: nix-daemon@7-8-1301_1302-0.service: Deactivated successfully.2004server # [ 39.766608] niks3-server[980]: 2026/09/21 21:31:11 INFO Received uploads request method=POST path=/api/pending_closures2005server # time=2026-09-21T21:31:11.394Z level=INFO msg="Uploading 2 paths to server (0 already cached)"2006server # time=2026-09-21T21:31:11.396Z level=INFO msg="Uploading xafv64bljflg1v8hnf22lyk0w4gma3v5-base-package (536B)"2007server # time=2026-09-21T21:31:11.398Z level=INFO msg="Uploading 0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper (192B)"2008server # [ 39.798446] niks3-server[980]: 2026/09/21 21:31:11 INFO Registered completed upload object_key=nar/1ncc9lqll9lyam30m8vdy4yyaym0q7sps47afadhzvyqsw833fy8.nar.zst2009server # [ 39.804716] niks3-server[980]: 2026/09/21 21:31:11 INFO Registered completed upload object_key=nar/0820av37plmfgrxw6pcbym6phk1sv14dilyrablrnkzb7z1fd5js.nar.zst2010server # [ 39.811093] niks3-server[980]: 2026/09/21 21:31:11 INFO Received sign narinfos request method=POST path=/api/pending_closures/4/sign2011server # time=2026-09-21T21:31:11.438Z level=INFO msg="Uploading 2 narinfos"2012server # [ 39.816252] niks3-server[980]: 2026/09/21 21:31:11 INFO Registered completed upload object_key=0caxmbk1mdnhh6zbyh0sz4faqqy5f08r.ls2013server # [ 39.820771] niks3-server[980]: 2026/09/21 21:31:11 INFO Signed narinfos id=4 count=22014server # [ 39.821872] niks3-server[980]: 2026/09/21 21:31:11 INFO Registered completed upload object_key=xafv64bljflg1v8hnf22lyk0w4gma3v5.ls2015server # [ 39.829123] niks3-server[980]: 2026/09/21 21:31:11 INFO Registered completed upload object_key=0caxmbk1mdnhh6zbyh0sz4faqqy5f08r.narinfo2016server # [ 39.833803] niks3-server[980]: 2026/09/21 21:31:11 INFO Received complete upload request method=POST path=/api/pending_closures/4/complete2017server # time=2026-09-21T21:31:11.460Z level=INFO msg="Upload complete. (188ms)"2018server # [ 39.837146] niks3-server[980]: 2026/09/21 21:31:11 INFO Completed upload id=42019server # [ 39.840437] niks3-server[980]: 2026/09/21 21:31:11 INFO Registered completed upload object_key=xafv64bljflg1v8hnf22lyk0w4gma3v5.narinfo2020server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper, in 0.25 seconds)2021server: must succeed: 2022 export AWS_ACCESS_KEY_ID=rustfsadmin2023export AWS_SECRET_ACCESS_KEY=rustfsadmin2024 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper20252026server: (finished: must succeed: 2027 export AWS_ACCESS_KEY_ID=rustfsadmin2028export AWS_SECRET_ACCESS_KEY=rustfsadmin2029 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper2030, in 0.23 seconds)2031server: must succeed: 2032cat > /tmp/oidc-test.nix << 'EOF'2033derivation {2034 name = "oidc-test";2035 system = builtins.currentSystem;2036 builder = "/bin/sh";2037 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2038}2039EOF20402041server: (finished: must succeed: 2042cat > /tmp/oidc-test.nix << 'EOF'2043derivation {2044 name = "oidc-test";2045 system = builtins.currentSystem;2046 builder = "/bin/sh";2047 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2048}2049EOF2050, in 0.02 seconds)2051server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link2052server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2053server # [ 40.151876] systemd[1]: Started Nix Daemon instance (PID 1332/UID 0).2054server # [ 40.203358] nix-daemon[1336]: remote pid 1332 is unknown user (trusted)2055server # this derivation will be built:2056server # /nix/store/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv2057server # building '/nix/store/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv'...2058server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link, in 0.21 seconds)2059server: 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'2060server # [ 40.307215] systemd[1]: nix-daemon@8-9-1332_1333-0.service: Deactivated successfully.2061server: (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.04 seconds)2062server: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Im5qQVZRYkJTWnd4dE5mc2dYWk51ZThqU2NPaC1qdWQ2cEZweWhYeHBZSzgiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTAwMjk4NzEsImlhdCI6MTc5MDAyNjI3MSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.bj_a8M5Bs_ASXG6_SDYJQGZ623rxm3mL-_wZ-RvEtdFH54EyUPzsMdDMitgxnj5Thns_kmXXOLRZaKQeMomZAH60MXofFt8Yykrl7BAK5mRpvhIHsp9aaNGvJmi0emK4a0Tk1tUnLVyhMi_bFFHBy7t_tZsj1ziP7RrOG8orf3tWJjj3Gayxk5hKASB-kuxFrbtVlnX993JscW4ZZ_yILUyOl_tvtRtTGImJ89Sk0FdI01fhzCrdEcyUtWq5ZA4z9zwh2ZsEtNlFAERBZ6KSFg5w2Xu1987-9Qw5O4DPfjKkyVIMQXQ0_r0HYChN2HzON9FsHa9Zssgvv4Zd1NxJAw' /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test2063server # time=2026-09-21T21:31:11.980Z 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"2064server # [ 40.443499] systemd[1]: Started Nix Daemon instance (PID 1366/UID 0).2065server # [ 40.498039] nix-daemon[1368]: remote pid 1366 is unknown user (trusted)2066server # [ 40.512078] systemd[1]: nix-daemon@9-10-1366_1367-0.service: Deactivated successfully.2067server # [ 40.518073] niks3-server[980]: 2026/09/21 21:31:12 INFO Received uploads request method=POST path=/api/pending_closures2068server # time=2026-09-21T21:31:12.147Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2069server # time=2026-09-21T21:31:12.149Z level=INFO msg="Uploading x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test (136B)"2070server # [ 40.541820] niks3-server[980]: 2026/09/21 21:31:12 INFO Registered completed upload object_key=nar/17h0qgwf5334wia961ibqy9hmpsyvfhrvj5ji9m031klp6sx4v0q.nar.zst2071server # [ 40.548299] niks3-server[980]: 2026/09/21 21:31:12 INFO Registered completed upload object_key=log/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv2072server # time=2026-09-21T21:31:12.177Z level=INFO msg="Uploading 1 narinfos"2073server # [ 40.554764] niks3-server[980]: 2026/09/21 21:31:12 INFO Received sign narinfos request method=POST path=/api/pending_closures/5/sign2074server # [ 40.558060] niks3-server[980]: 2026/09/21 21:31:12 INFO Signed narinfos id=5 count=12075server # [ 40.563078] niks3-server[980]: 2026/09/21 21:31:12 INFO Registered completed upload object_key=x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj.ls2076server # time=2026-09-21T21:31:12.191Z level=INFO msg="Upload complete. (170ms)"2077server # [ 40.569575] niks3-server[980]: 2026/09/21 21:31:12 INFO Received complete upload request method=POST path=/api/pending_closures/5/complete2078server # [ 40.571145] niks3-server[980]: 2026/09/21 21:31:12 INFO Completed upload id=52079server # [ 40.574831] niks3-server[980]: 2026/09/21 21:31:12 INFO Registered completed upload object_key=x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj.narinfo2080server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Im5qQVZRYkJTWnd4dE5mc2dYWk51ZThqU2NPaC1qdWQ2cEZweWhYeHBZSzgiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTAwMjk4NzEsImlhdCI6MTc5MDAyNjI3MSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.bj_a8M5Bs_ASXG6_SDYJQGZ623rxm3mL-_wZ-RvEtdFH54EyUPzsMdDMitgxnj5Thns_kmXXOLRZaKQeMomZAH60MXofFt8Yykrl7BAK5mRpvhIHsp9aaNGvJmi0emK4a0Tk1tUnLVyhMi_bFFHBy7t_tZsj1ziP7RrOG8orf3tWJjj3Gayxk5hKASB-kuxFrbtVlnX993JscW4ZZ_yILUyOl_tvtRtTGImJ89Sk0FdI01fhzCrdEcyUtWq5ZA4z9zwh2ZsEtNlFAERBZ6KSFg5w2Xu1987-9Qw5O4DPfjKkyVIMQXQ0_r0HYChN2HzON9FsHa9Zssgvv4Zd1NxJAw' /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test, in 0.24 seconds)2081server: must succeed: 2082cat > /tmp/oidc-test2.nix << 'EOF'2083derivation {2084 name = "oidc-test2";2085 system = builtins.currentSystem;2086 builder = "/bin/sh";2087 args = [ "-c" "echo 'OIDC test 2' > $out" ];2088}2089EOF20902091server: (finished: must succeed: 2092cat > /tmp/oidc-test2.nix << 'EOF'2093derivation {2094 name = "oidc-test2";2095 system = builtins.currentSystem;2096 builder = "/bin/sh";2097 args = [ "-c" "echo 'OIDC test 2' > $out" ];2098}2099EOF2100, in 0.02 seconds)2101server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link2102server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2103server # [ 40.651002] systemd[1]: Started Nix Daemon instance (PID 1378/UID 0).2104server # [ 40.710826] nix-daemon[1382]: remote pid 1378 is unknown user (trusted)2105server # this derivation will be built:2106server # /nix/store/ldlfjwl576hkm4b5ag3b447nnn59cwiw-oidc-test2.drv2107server # building '/nix/store/ldlfjwl576hkm4b5ag3b447nnn59cwiw-oidc-test2.drv'...2108server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link, in 0.22 seconds)2109server: 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'2110server # [ 40.816498] systemd[1]: nix-daemon@10-11-1378_1379-0.service: Deactivated successfully.2111server: (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.04 seconds)2112server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Im5qQVZRYkJTWnd4dE5mc2dYWk51ZThqU2NPaC1qdWQ2cEZweWhYeHBZSzgiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTAwMjk4NzIsImlhdCI6MTc5MDAyNjI3MiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.VylwOzX3PdW4IHlNXmZ1MVES98e_OqYWRgOWTpYxleejG314N2ttEUup1ET0l56vW4XILKNNSPmOerpHm89jA8EnfzZPU47bTqgWWKLmt3ZU8_eGYB_Ov1TYmV3MzD_s7bvQVhdyt6N0VoitXTD3Kn_NMYySt1AL_gU7YWD5rj2PoOJ_M_RmexnrPOAp4yPKXtNm8Bn_UagJyMYb-D8kWgB-pekNwG8uPWbBgpoANBkVmkIoaaDbfHPuNDNw8J3_9hAk4kOsiwGacASey7DgDnyYemvtn6hmtvc7ojh0ZIr2Z2OdLTNJnldDQDSo7Iz80r4wNTBzvYw9ykOhlCn82g' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22113server # time=2026-09-21T21:31:12.489Z 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"2114server # [ 40.914642] niks3-server[980]: 2026/09/21 21:31:12 WARN Authentication failed token_preview=eyJhbGciOi...ykOhlCn82g token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2115server # [ 40.979263] systemd[1]: Started Nix Daemon instance (PID 1412/UID 0).2116server # [ 41.038086] nix-daemon[1414]: remote pid 1412 is unknown user (trusted)2117server # [ 41.051612] systemd[1]: nix-daemon@11-12-1412_1413-0.service: Deactivated successfully.2118server # [ 41.058329] niks3-server[980]: 2026/09/21 21:31:12 WARN Authentication failed token_preview=eyJhbGciOi...ykOhlCn82g token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2119server # time=2026-09-21T21:31:12.685Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2120server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Im5qQVZRYkJTWnd4dE5mc2dYWk51ZThqU2NPaC1qdWQ2cEZweWhYeHBZSzgiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTAwMjk4NzIsImlhdCI6MTc5MDAyNjI3MiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.VylwOzX3PdW4IHlNXmZ1MVES98e_OqYWRgOWTpYxleejG314N2ttEUup1ET0l56vW4XILKNNSPmOerpHm89jA8EnfzZPU47bTqgWWKLmt3ZU8_eGYB_Ov1TYmV3MzD_s7bvQVhdyt6N0VoitXTD3Kn_NMYySt1AL_gU7YWD5rj2PoOJ_M_RmexnrPOAp4yPKXtNm8Bn_UagJyMYb-D8kWgB-pekNwG8uPWbBgpoANBkVmkIoaaDbfHPuNDNw8J3_9hAk4kOsiwGacASey7DgDnyYemvtn6hmtvc7ojh0ZIr2Z2OdLTNJnldDQDSo7Iz80r4wNTBzvYw9ykOhlCn82g' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.22 seconds)2121server: 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'2122server: (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.03 seconds)2123server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Im5qQVZRYkJTWnd4dE5mc2dYWk51ZThqU2NPaC1qdWQ2cEZweWhYeHBZSzgiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MDAyOTg3MiwiaWF0IjoxNzkwMDI2MjcyLCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.3h3zsabdonQqKgnCJ95KNd53rQ0FloGLaCT2KCe-BPNbt7S0TTzZLzS618Gr-XyEu-b11sspMcC9aH0PLAMJ9D9mS-cpqwyfYuVprml8nfNAl7dFlwhfipXxNkL8jhjUIRt7jW992a9L1oZb68qNQjoZA-ampFQ_XpEHxg2uz-wOqUSzSkLm2Gp670DqSuk6n55YEKGwEkMlzcLJC8hmmYtOq9rTBNFqegUQFHnej6WQ4cd-xzldDXh9Y1YXXfxgKtQ0qMEXdsRAbgwibnNnB3WoMQ0uQeC_5N-7rYyf8S3UEsmgn3uBkJ_ZJwEmNhGoFMLcCpQ-ft8TQLOyiFqLjw' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22124server # time=2026-09-21T21:31:12.733Z 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"2125server # [ 41.152158] niks3-server[980]: 2026/09/21 21:31:12 WARN Authentication failed token_preview=eyJhbGciOi...QLOyiFqLjw token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2126server # [ 41.195739] systemd[1]: Started Nix Daemon instance (PID 1435/UID 0).2127server # [ 41.250759] nix-daemon[1437]: remote pid 1435 is unknown user (trusted)2128server # [ 41.264542] systemd[1]: nix-daemon@12-13-1435_1436-0.service: Deactivated successfully.2129server # [ 41.270556] niks3-server[980]: 2026/09/21 21:31:12 WARN Authentication failed token_preview=eyJhbGciOi...QLOyiFqLjw token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2130server # time=2026-09-21T21:31:12.897Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2131server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Im5qQVZRYkJTWnd4dE5mc2dYWk51ZThqU2NPaC1qdWQ2cEZweWhYeHBZSzgiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MDAyOTg3MiwiaWF0IjoxNzkwMDI2MjcyLCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.3h3zsabdonQqKgnCJ95KNd53rQ0FloGLaCT2KCe-BPNbt7S0TTzZLzS618Gr-XyEu-b11sspMcC9aH0PLAMJ9D9mS-cpqwyfYuVprml8nfNAl7dFlwhfipXxNkL8jhjUIRt7jW992a9L1oZb68qNQjoZA-ampFQ_XpEHxg2uz-wOqUSzSkLm2Gp670DqSuk6n55YEKGwEkMlzcLJC8hmmYtOq9rTBNFqegUQFHnej6WQ4cd-xzldDXh9Y1YXXfxgKtQ0qMEXdsRAbgwibnNnB3WoMQ0uQeC_5N-7rYyf8S3UEsmgn3uBkJ_ZJwEmNhGoFMLcCpQ-ft8TQLOyiFqLjw' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.18 seconds)2132server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22133server # time=2026-09-21T21:31:12.917Z 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"2134server # [ 41.336275] niks3-server[980]: 2026/09/21 21:31:12 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]2135server # [ 41.379358] systemd[1]: Started Nix Daemon instance (PID 1455/UID 0).2136server # [ 41.433095] nix-daemon[1457]: remote pid 1455 is unknown user (trusted)2137server # [ 41.446789] systemd[1]: nix-daemon@13-14-1455_1456-0.service: Deactivated successfully.2138server # [ 41.452144] niks3-server[980]: 2026/09/21 21:31:13 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]2139server # time=2026-09-21T21:31:13.079Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2140server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.18 seconds)2141server: must succeed: 2142 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins create hello-pin /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.321432144server # [ 41.516444] niks3-server[980]: 2026/09/21 21:31:13 INFO Received create pin request method=POST path=/api/pins/hello-pin2145server # time=2026-09-21T21:31:13.151Z level=INFO msg="Created pin" name=hello-pin store_path=/nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.32146server # [ 41.529114] niks3-server[980]: 2026/09/21 21:31:13 INFO Created/updated pin name=hello-pin store_path=/nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 narinfo_key=kwhxkl8yn5y8wqiq11jsybagw3fbc4iv.narinfo2147server: (finished: must succeed: 2148 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins create hello-pin /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.32149, in 0.07 seconds)2150server: must succeed: 2151 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins list21522153server # [ 41.594718] niks3-server[980]: 2026/09/21 21:31:13 INFO Received list pins request method=GET path=/api/pins2154server: (finished: must succeed: 2155 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins list2156, in 0.06 seconds)2157server: must succeed: 2158 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins list --names-only21592160server # [ 41.657290] niks3-server[980]: 2026/09/21 21:31:13 INFO Received list pins request method=GET path=/api/pins2161server: (finished: must succeed: 2162 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins list --names-only2163, in 0.06 seconds)2164server: must succeed: 2165 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins list --json21662167server # [ 41.718527] niks3-server[980]: 2026/09/21 21:31:13 INFO Received list pins request method=GET path=/api/pins2168server: (finished: must succeed: 2169 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins list --json2170, in 0.06 seconds)2171server: must succeed: 2172 export S3_ENDPOINT_URL=http://localhost:90002173 export AWS_ACCESS_KEY_ID=rustfsadmin2174 export AWS_SECRET_ACCESS_KEY=rustfsadmin2175 /nix/store/1mxif175wb0rn50pgaisvczshqv3mw1i-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin21762177server: (finished: must succeed: 2178 export S3_ENDPOINT_URL=http://localhost:90002179 export AWS_ACCESS_KEY_ID=rustfsadmin2180 export AWS_SECRET_ACCESS_KEY=rustfsadmin2181 /nix/store/1mxif175wb0rn50pgaisvczshqv3mw1i-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin2182, in 0.03 seconds)2183server: must succeed: 2184 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --pin ca-pin /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log21852186server # time=2026-09-21T21:31:13.452Z level=INFO msg="All 1 paths already cached"2187server # [ 41.831271] niks3-server[980]: 2026/09/21 21:31:13 INFO Received create pin request method=POST path=/api/pins/ca-pin2188server # time=2026-09-21T21:31:13.462Z level=INFO msg="Created pin" name=ca-pin store_path=/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2189server # [ 41.840568] niks3-server[980]: 2026/09/21 21:31:13 INFO Created/updated pin name=ca-pin store_path=/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log narinfo_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.narinfo2190server: (finished: must succeed: 2191 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 push --pin ca-pin /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2192, in 0.09 seconds)2193server: must succeed: 2194 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins list --names-only21952196server # [ 41.910644] niks3-server[980]: 2026/09/21 21:31:13 INFO Received list pins request method=GET path=/api/pins2197server: (finished: must succeed: 2198 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins list --names-only2199, in 0.07 seconds)2200server: must succeed: 2201 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins delete hello-pin22022203server # [ 41.972427] niks3-server[980]: 2026/09/21 21:31:13 INFO Received delete pin request method=DELETE path=/api/pins/hello-pin2204server # time=2026-09-21T21:31:13.603Z level=INFO msg="Deleted pin" name=hello-pin2205server # [ 41.980841] niks3-server[980]: 2026/09/21 21:31:13 INFO Deleted pin name=hello-pin2206server: (finished: must succeed: 2207 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins delete hello-pin2208, in 0.07 seconds)2209server: must succeed: 2210 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins list --names-only22112212server # [ 42.044151] niks3-server[980]: 2026/09/21 21:31:13 INFO Received list pins request method=GET path=/api/pins2213server: (finished: must succeed: 2214 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins list --names-only2215, in 0.06 seconds)2216server: must fail: 2217 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent22182219server # [ 42.107667] niks3-server[980]: 2026/09/21 21:31:13 INFO Received create pin request method=POST path=/api/pins/bad-pin2220server # time=2026-09-21T21:31:13.734Z level=ERROR msg="Fatal error" error="creating pin: server returned 404: closure not found: store path must be pushed before pinning\n"2221server # [ 42.112145] niks3-server[980]: 2026/09/21 21:31:13 ERROR Failed to get closure for pin narinfo_key=aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.narinfo error="no rows in result set"2222server: (finished: must fail: 2223 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/rl785p6cqmxdbc7nxdz0zcykc3d42fbh-niks3-1.12.0-beta.3/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent2224, in 0.07 seconds)2225server: must succeed: systemctl start niks3-gc.service2226server # [ 42.146585] systemd[1]: Starting niks3 garbage collection...2227server # [ 42.212826] niks3[1583]: time=2026-09-21T21:31:13.836Z level=INFO msg="Starting garbage collection" older-than=720h failed-uploads-older-than=6h force=false2228server # [ 42.216601] niks3-server[980]: 2026/09/21 21:31:13 INFO Starting cleanup of old closures method=DELETE path=/api/closures2229server # [ 42.219884] niks3[1583]: time=2026-09-21T21:31:13.842Z level=INFO msg="Garbage collection started"2230server # [ 42.222571] niks3-server[980]: 2026/09/21 21:31:13 INFO Aborted multipart uploads count=02231server # [ 42.229454] niks3-server[980]: 2026/09/21 21:31:13 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=3 objects-deleted-after-grace-period=0 objects-failed-to-delete=02232server # [ 42.235856] niks3-server[980]: 2026/09/21 21:31:13 INFO Vacuumed table table=pending_closures2233server # [ 42.239677] niks3-server[980]: 2026/09/21 21:31:13 INFO Vacuumed table table=pending_objects2234server # [ 42.243391] niks3-server[980]: 2026/09/21 21:31:13 INFO Vacuumed table table=multipart_uploads2235server # [ 42.247752] niks3-server[980]: 2026/09/21 21:31:13 INFO Vacuumed table table=closures2236server # [ 42.251561] niks3-server[980]: 2026/09/21 21:31:13 INFO Vacuumed table table=objects2237server # [ 44.221364] niks3[1583]: time=2026-09-21T21:31:15.843Z level=INFO msg="Garbage collection progress" phase="" failed_uploads_deleted=0 old_closures_deleted=0 objects_marked=3 objects_deleted=0 objects_failed=02238server # [ 44.221716] niks3[1583]: time=2026-09-21T21:31:15.844Z level=INFO msg="Garbage collection completed successfully" failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=3 objects-deleted-after-grace-period=0 objects-failed-to-delete=02239server # [ 44.246014] systemd[1]: niks3-gc.service: Deactivated successfully.2240server # [ 44.256809] systemd[1]: Finished niks3 garbage collection.2241server # [ 44.259145] systemd[1]: niks3-gc.service: Consumed 41ms CPU time over 2.103s wall clock time, 2.4M memory peak, 1.2K incoming IP traffic, 823B outgoing IP traffic.2242server: (finished: must succeed: systemctl start niks3-gc.service, in 2.16 seconds)2243builder: waiting for unit niks3-auto-upload.socket2244builder: waiting for the VM to finish booting2245builder: Guest shell says: b'Spawning backdoor root shell...\n'2246builder: connected to guest root shell2247builder: (connecting took 0.00 seconds)2248builder: (finished: waiting for the VM to finish booting, in 0.00 seconds)2249builder: (finished: waiting for unit niks3-auto-upload.socket, in 0.08 seconds)2250builder: must succeed: test -S /run/niks3/upload-to-cache.sock2251builder: (finished: must succeed: test -S /run/niks3/upload-to-cache.sock, in 0.03 seconds)2252builder: must succeed: grep post-build-hook /etc/nix/nix.conf2253builder: (finished: must succeed: grep post-build-hook /etc/nix/nix.conf, in 0.02 seconds)2254builder: must succeed: 2255cat > /tmp/test-drv.nix << 'EOF'2256derivation {2257 name = "post-build-hook-test";2258 system = builtins.currentSystem;2259 builder = "/bin/sh";2260 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2261}2262EOF22632264builder: (finished: must succeed: 2265cat > /tmp/test-drv.nix << 'EOF'2266derivation {2267 name = "post-build-hook-test";2268 system = builtins.currentSystem;2269 builder = "/bin/sh";2270 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2271}2272EOF2273, in 0.02 seconds)2274builder: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link2275builder # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2276builder # [ 44.541413] systemd[1]: Created slice Slice /system/nix-daemon.2277builder # [ 44.545602] systemd[1]: Started Nix Daemon instance (PID 773/UID 0).2278builder # [ 44.601202] nix-daemon[777]: remote pid 773 is unknown user (trusted)2279builder # 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 535 ms (attempt 1/5)2280builder # 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 880 ms (attempt 2/5)2281builder # 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 2016 ms (attempt 3/5)2282builder # 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 3917 ms (attempt 4/5)2283builder # 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)2284builder # this derivation will be built:2285builder # /nix/store/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv2286builder # building '/nix/store/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv'...2287builder # [ 52.179084] systemd[1]: Started niks3 auto-upload daemon.2288builder # [ 52.291947] niks3-hook[802]: time=2026-09-21T21:31:23.930Z 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=5s drain-timeout=0s2289builder # [ 52.299438] niks3-hook[802]: time=2026-09-21T21:31:23.937Z level=INFO msg="Upload queue status" pending=12290builder # [ 52.303554] niks3-hook[802]: time=2026-09-21T21:31:23.937Z level=INFO msg="Uploading batch" count=12291builder: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link, in 7.86 seconds)2292builder: waiting for unit niks3-auto-upload.service2293builder # [ 52.327089] systemd[1]: nix-daemon@0-1-773_774-0.service: Deactivated successfully.2294builder # [ 52.332384] systemd[1]: nix-daemon@0-1-773_774-0.service: Consumed 201ms CPU time over 7.783s wall clock time, 19M memory peak, 1.4K outgoing IP traffic.2295builder: (finished: waiting for unit niks3-auto-upload.service, in 0.08 seconds)2296??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2297 File "/nix/store/yyamlhbvx0lp00fbf9sswdcq9ckfh7qj-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392298builder: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive2299??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2300 File "/nix/store/yyamlhbvx0lp00fbf9sswdcq9ckfh7qj-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392301builder # [ 52.413755] systemd[1]: Started Nix Daemon instance (PID 813/UID 0).2302builder # [ 52.476571] nix-daemon[823]: remote pid 813 is unknown user (trusted)2303builder # [ 52.489338] systemd[1]: nix-daemon@1-2-813_814-0.service: Deactivated successfully.2304server # [ 52.458580] niks3-server[980]: 2026/09/21 21:31:24 INFO Received uploads request method=POST path=/api/pending_closures2305builder # [ 52.510437] niks3-hook[802]: time=2026-09-21T21:31:24.147Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2306builder # [ 52.511931] niks3-hook[802]: time=2026-09-21T21:31:24.147Z level=INFO msg="Uploading 1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test (144B)"2307server # [ 52.500618] niks3-server[980]: 2026/09/21 21:31:24 INFO Registered completed upload object_key=log/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv2308server # [ 52.524482] niks3-server[980]: 2026/09/21 21:31:24 INFO Received sign narinfos request method=POST path=/api/pending_closures/6/sign2309builder # [ 52.569152] niks3-hook[802]: time=2026-09-21T21:31:24.207Z level=INFO msg="Uploading 1 narinfos"2310server # [ 52.532829] niks3-server[980]: 2026/09/21 21:31:24 INFO Registered completed upload object_key=nar/1a0vfw93shmvlz6lac7d1lv82jxzfdswigh5s32hpvncf74i1i0y.nar.zst2311server # [ 52.539854] niks3-server[980]: 2026/09/21 21:31:24 INFO Signed narinfos id=6 count=12312server # [ 52.544337] niks3-server[980]: 2026/09/21 21:31:24 INFO Registered completed upload object_key=1qlca7drab1x7rccc3h1pw1fl9c0j7yx.ls2313server # [ 52.553586] niks3-server[980]: 2026/09/21 21:31:24 INFO Received complete upload request method=POST path=/api/pending_closures/6/complete2314server # [ 52.558654] niks3-server[980]: 2026/09/21 21:31:24 INFO Completed upload id=62315builder # [ 52.597634] niks3-hook[802]: time=2026-09-21T21:31:24.235Z level=INFO msg="Upload complete. (298ms)"2316server # [ 52.560796] niks3-server[980]: 2026/09/21 21:31:24 INFO Registered completed upload object_key=1qlca7drab1x7rccc3h1pw1fl9c0j7yx.narinfo2317builder # [ 57.301967] niks3-hook[802]: time=2026-09-21T21:31:28.937Z level=INFO msg="Idle timeout reached and queue is empty, shutting down"2318builder # [ 57.306813] niks3-hook[802]: time=2026-09-21T21:31:28.939Z level=INFO msg="niks3-hook serve stopped"2319builder # [ 57.323486] systemd[1]: niks3-auto-upload.service: Deactivated successfully.2320builder # [ 57.330038] systemd[1]: niks3-auto-upload.service: Consumed 139ms CPU time over 5.148s wall clock time, 10.9M memory peak, 68K written to disk, 5.5K incoming IP traffic, 8.3K outgoing IP traffic.2321builder: (finished: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive, in 5.33 seconds)2322server: must succeed: 2323 export AWS_ACCESS_KEY_ID=rustfsadmin2324export AWS_SECRET_ACCESS_KEY=rustfsadmin2325 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-test23262327server: (finished: must succeed: 2328 export AWS_ACCESS_KEY_ID=rustfsadmin2329export AWS_SECRET_ACCESS_KEY=rustfsadmin2330 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-test2331, in 0.26 seconds)2332server: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test2333server: (finished: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test, in 0.06 seconds)2334(finished: run the VM test script, in 58.81 seconds)2335test script finished in 58.92s2336cleanup2337kill QemuMachine (pid 47)2338builder # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2339builder # [2026-09-21T21:31:29Z INFO virtiofsd] Client disconnected, shutting down2340builder # [2026-09-21T21:31:29Z INFO virtiofsd] Client disconnected, shutting down2341builder # [2026-09-21T21:31:29Z INFO virtiofsd] Client disconnected, shutting down2342kill QemuMachine (pid 48)2343server # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2344server # [2026-09-21T21:31:29Z INFO virtiofsd] Client disconnected, shutting down2345server # [2026-09-21T21:31:29Z INFO virtiofsd] Client disconnected, shutting down2346server # [2026-09-21T21:31:29Z INFO virtiofsd] Client disconnected, shutting down2347(finished: cleanup, in 0.58 seconds)2348additionally exposed symbols:2349 builder, server,2350 vlan1,2351 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_ssh2352Hello store path: /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.32353Test output path: /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2354Symlink wrapper store path: /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper2355Symlink wrapper points to: /nix/store/xafv64bljflg1v8hnf22lyk0w4gma3v5-base-package/bin/test-program2356OIDC test store path: /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test2357Valid OIDC token obtained (length=677)2358OIDC push with valid token: SUCCESS2359Invalid OIDC token obtained (wrong org)2360OIDC push with wrong org: correctly rejected2361Wrong audience OIDC token obtained2362OIDC push with wrong audience: correctly rejected2363OIDC push with malformed token: correctly rejected2364All OIDC tests passed!2365All pin tests passed!2366Built store path: /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test2367Post-build-hook pipeline test passed!