vm-test-run-nixos-test-niks3
checks.aarch64-linux.nixos-test-niks3
· build #273
· 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 # Formatting '/build/vm-state-builder/tmp.9kIEuiBbAj', fmt=raw size=107374182412builder # mke2fs 1.47.4 (6-Mar-2025)13builder: QEMU running (pid 47)14builder # Discarding device blocks: 0/262144 done15builder # Creating filesystem with 262144 4k blocks and 65536 inodes16builder # Filesystem UUID: a67c1247-fd61-478c-9d00-5139c02103a117builder # Superblock backups stored on blocks:18builder # 32768, 98304, 163840, 22937619builder # 20builder # Allocating group tables: 0/8 done21builder # Writing inode tables: 0/8 done22builder # Creating journal (8192 blocks): done23builder # Writing superblocks and filesystem accounting information: 0/8 done24builder # 25builder # Virtualisation disk image created.26builder # Starting virtiofs daemons...27builder # [2026-09-24T18:04:51Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28builder # [2026-09-24T18:04:51Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29builder # [2026-09-24T18:04:51Z INFO virtiofsd] Waiting for vhost-user socket connection...30server # Disk image does not exist, creating the virtualisation disk image...31builder # [2026-09-24T18:04:51Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)32server # Formatting '/build/vm-state-server/tmp.9sYxyT5pZU', fmt=raw size=107374182433builder # [2026-09-24T18:04:51Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether34server # mke2fs 1.47.4 (6-Mar-2025)35builder # [2026-09-24T18:04:51Z INFO virtiofsd] Waiting for vhost-user socket connection...36server # Discarding device blocks: 0/262144 done37builder # [2026-09-24T18:04:51Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)38server # Creating filesystem with 262144 4k blocks and 65536 inodes39builder # [2026-09-24T18:04:51Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether40server # Filesystem UUID: fb1a74aa-a56e-44a7-a6ea-ada40ab0897041builder # [2026-09-24T18:04:51Z INFO virtiofsd] Waiting for vhost-user socket connection...42server # Superblock backups stored on blocks:43builder # [2026-09-24T18:04:51Z INFO virtiofsd] Client connected, servicing requests44server # 32768, 98304, 163840, 22937645builder # [2026-09-24T18:04:51Z INFO virtiofsd] Client connected, servicing requests46server # 47builder # [2026-09-24T18:04:51Z INFO virtiofsd] Client connected, servicing requests48server # Allocating group tables: 0/8 done49server: QEMU running (pid 48)50server # Writing inode tables: 0/8 done51server # Creating journal (8192 blocks): done52server # Writing superblocks and filesystem accounting information: 0/8 done53server # 54server # Virtualisation disk image created.55server # Starting virtiofs daemons...56server # [2026-09-24T18:04:51Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)57server # [2026-09-24T18:04:51Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether58(finished: start all VMs, in 0.51 seconds)59server # [2026-09-24T18:04:51Z INFO virtiofsd] Waiting for vhost-user socket connection...60server: waiting for unit postgresql.service61server # [2026-09-24T18:04:51Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)62server: waiting for the VM to finish booting63server # [2026-09-24T18:04:51Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether64server # [2026-09-24T18:04:51Z INFO virtiofsd] Waiting for vhost-user socket connection...65server # [2026-09-24T18:04:51Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)66server # [2026-09-24T18:04:51Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether67server # [2026-09-24T18:04:51Z INFO virtiofsd] Waiting for vhost-user socket connection...68server # [2026-09-24T18:04:51Z INFO virtiofsd] Client connected, servicing requests69server # [2026-09-24T18:04:51Z INFO virtiofsd] Client connected, servicing requests70server # [2026-09-24T18:04:51Z INFO virtiofsd] Client connected, servicing requests71builder # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]72builder # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 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 s186712 r8192 d116392 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/d4biv6zq93mg3l7fd2dx1vvsf6p3mp0m-nixos-system-builder-test/init regInfo=/nix/store/6apf3cksvvihr0knb8qhxvqx51bnymlg-closure-info/registration console=ttyAMA0,115200n8 console=tty0106builder # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/6apf3cksvvihr0knb8qhxvqx51bnymlg-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 74950 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.124builder # [ 0.000000] Trampoline variant of Tasks RCU enabled.125builder # [ 0.000000] Rude variant of Tasks RCU enabled.126builder # [ 0.000000] Tracing variant of Tasks RCU enabled.127builder # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.128builder # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1129builder # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.130builder # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.131server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]132builder # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.133builder # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0134server # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 2026135server # [ 0.000000] KASLR enabled136builder # [ 0.000000] GICv3: 256 SPIs implemented137server # [ 0.000000] random: crng init done138builder # [ 0.000000] GICv3: 0 Extended SPIs implemented139server # [ 0.000000] Machine model: linux,dummy-virt140builder # [ 0.000000] Root IRQ handler: gic_handle_irq141server # [ 0.000000] efi: UEFI not found.142builder # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI143server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT144builder # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0145server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]146builder # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000147builder # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]148server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]149server # [ 0.000000] Zone ranges:150server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]151builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)152server # [ 0.000000] DMA32 empty153server # [ 0.000000] Normal empty154server # [ 0.000000] Device empty155builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)156server # [ 0.000000] Movable zone start for each node157builder # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000158server # [ 0.000000] Early memory node ranges159server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]160builder # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000161server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]162builder # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.163server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000164server # [ 0.000000] psci: probing for conduit method from DT.165builder # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns166server # [ 0.000000] psci: PSCIv1.3 detected in firmware.167builder # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).168server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs169server # [ 0.000000] psci: Trusted OS migration not required170builder # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns171server # [ 0.000000] psci: SMC Calling Convention v1.1172builder # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns173server # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)174builder # [ 0.000031] arm-pv: using stolen time PV175server # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296176server # [ 0.000000] Detected PIPT I-cache on CPU0177builder # [ 0.000449] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)178builder # [ 0.000628] Console: colour dummy device 80x25179server # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)180builder # [ 0.000634] printk: legacy console [tty0] enabled181server # [ 0.000000] CPU features: detected: GICv3 CPU interface182server # [ 0.000000] CPU features: detected: Spectre-v4183builder # [ 0.000821] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)184server # [ 0.000000] CPU features: detected: Spectre-BHB185builder # [ 0.000828] pid_max: default: 32768 minimum: 301186server # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38187builder # [ 0.000904] LSM: initializing lsm=capability,landlock,yama,bpf,ima188builder # [ 0.001040] landlock: Up and running.189server # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23190builder # [ 0.001043] Yama: becoming mindful.191server # [ 0.000000] alternatives: applying boot alternatives192builder # [ 0.001509] LSM support for eBPF active193builder # [ 0.001637] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)194builder # [ 0.001656] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)195builder # [ 0.003440] rcu: Hierarchical SRCU implementation.196builder # [ 0.003445] rcu: Max phase no-delay instances is 1000.197builder # [ 0.004691] fsl-mc MSI: its@8080000 domain created198server # [ 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/zr4lbbj3mmgn536mw6wy9bklhc63i0a9-nixos-system-server-test/init regInfo=/nix/store/f4jqsyg5qyq40dwx9ijzqs839imrqycd-closure-info/registration console=ttyAMA0,115200n8 console=tty0199builder # [ 0.004781] EFI services will not be available.200builder # [ 0.004846] smp: Bringing up secondary CPUs ...201builder # [ 0.004854] smp: Brought up 1 node, 1 CPU202server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/f4jqsyg5qyq40dwx9ijzqs839imrqycd-closure-info/registration", will be passed to user space.203builder # [ 0.004856] SMP: Total of 1 processors activated.204builder # [ 0.004860] CPU: All CPU(s) started at EL1205server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes206builder # [ 0.004872] CPU features: detected: Branch Target Identification207server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)208builder # [ 0.004877] CPU features: detected: ARMv8.4 Translation Table Level209server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)210builder # [ 0.004879] CPU features: detected: Instruction cache invalidation not required for I/D coherence211server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB212server # [ 0.000000] software IO TLB: area num 1.213builder # [ 0.004883] CPU features: detected: Data cache clean to the PoU not required for I/D coherence214server # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)215builder # [ 0.004887] CPU features: detected: Common not Private translations216server # [ 0.000000] Fallback order for Node 0: 0217builder # [ 0.004890] CPU features: detected: CRC32 instructions218server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144219server # [ 0.000000] Policy zone: DMA220builder # [ 0.004893] CPU features: detected: Data cache clean to Point of Deep Persistence221server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off222builder # [ 0.004896] CPU features: detected: Data cache clean to Point of Persistence223server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1224builder # [ 0.004899] CPU features: detected: Data independent timing control (DIT)225server # [ 0.000000] allocated 2097152 bytes of page_ext226builder # [ 0.004903] CPU features: detected: E0PD227server # [ 0.000000] ftrace: allocating 74950 entries in 294 pages228builder # [ 0.004905] CPU features: detected: Enhanced Counter Virtualization229server # [ 0.000000] ftrace: allocated 294 pages with 4 groups230builder # [ 0.004908] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)231server # [ 0.000000] rcu: Hierarchical RCU implementation.232server # [ 0.000000] rcu: RCU event tracing is enabled.233builder # [ 0.004912] CPU features: detected: Enhanced Virtualization Traps234builder # [ 0.004915] CPU features: detected: Fine Grained Traps235server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.236server # [ 0.000000] Trampoline variant of Tasks RCU enabled.237builder # [ 0.004919] CPU features: detected: Generic authentication (architected QARMA5 algorithm)238server # [ 0.000000] Rude variant of Tasks RCU enabled.239builder # [ 0.004923] CPU features: detected: RCpc load-acquire (LDAPR)240server # [ 0.000000] Tracing variant of Tasks RCU enabled.241builder # [ 0.004927] CPU features: detected: LSE atomic instructions242server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.243builder # [ 0.004929] CPU features: detected: Privileged Access Never244builder # [ 0.004932] CPU features: detected: PMUv3245server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1246builder # [ 0.004935] CPU features: detected: RAS Extension Support247server # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.248builder # [ 0.004938] CPU features: detected: RASv1p1 Extension Support249builder # [ 0.004940] CPU features: detected: Random Number Generator250server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.251builder # [ 0.004943] CPU features: detected: Speculation barrier (SB)252builder # [ 0.004946] CPU features: detected: Stage-2 Force Write-Back253server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.254server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0255builder # [ 0.004949] CPU features: detected: TLB range maintenance instructions256server # [ 0.000000] GICv3: 256 SPIs implemented257builder # [ 0.004954] CPU features: detected: Speculative Store Bypassing Safe (SSBS)258server # [ 0.000000] GICv3: 0 Extended SPIs implemented259server # [ 0.000000] Root IRQ handler: gic_handle_irq260builder # [ 0.004991] alternatives: applying system-wide alternatives261server # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI262builder # [ 0.007832] CPU features: detected: BBM Level 2 without TLB conflict abort263server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0264server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000265server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]266builder # [ 0.008073] Memory: 893340K/1048576K available (24448K kernel code, 7094K rwdata, 26372K rodata, 4736K init, 1107K bss, 113896K reserved, 32768K cma-reserved)267builder # [ 0.008391] devtmpfs: initialized268server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)269server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)270server # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000271server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000272server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.273server # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns274server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).275server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns276server # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns277builder # [ 0.010062] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)278server # [ 0.000029] arm-pv: using stolen time PV279builder # [ 0.010084] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).280server # [ 0.000394] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)281builder # [ 0.010285] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL282server # [ 0.000528] Console: colour dummy device 80x25283builder # [ 0.010289] 0 pages in range for non-PLT usage284server # [ 0.000535] printk: legacy console [tty0] enabled285builder # [ 0.010290] 508272 pages in range for PLT usage286builder # [ 0.010393] pinctrl core: initialized pinctrl subsystem287server # [ 0.000717] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)288builder # [ 0.011151] DMI not present or invalid.289server # [ 0.000724] pid_max: default: 32768 minimum: 301290builder # [ 0.014362] NET: Registered PF_NETLINK/PF_ROUTE protocol family291server # [ 0.000792] LSM: initializing lsm=capability,landlock,yama,bpf,ima292server # [ 0.000967] landlock: Up and running.293builder # [ 0.016822] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations294server # [ 0.000970] Yama: becoming mindful.295server # [ 0.001387] LSM support for eBPF active296builder # [ 0.016981] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations297server # [ 0.001503] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)298builder # [ 0.017137] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations299builder # [ 0.017161] audit: initializing netlink subsys (disabled)300server # [ 0.001532] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)301builder # [ 0.017700] thermal_sys: Registered thermal governor 'fair_share'302server # [ 0.003312] rcu: Hierarchical SRCU implementation.303server # [ 0.003317] rcu: Max phase no-delay instances is 1000.304builder # [ 0.017701] thermal_sys: Registered thermal governor 'bang_bang'305server # [ 0.004573] fsl-mc MSI: its@8080000 domain created306builder # [ 0.017705] thermal_sys: Registered thermal governor 'step_wise'307server # [ 0.004663] EFI services will not be available.308builder # [ 0.017707] thermal_sys: Registered thermal governor 'user_space'309server # [ 0.004749] smp: Bringing up secondary CPUs ...310server # [ 0.004758] smp: Brought up 1 node, 1 CPU311builder # [ 0.017710] thermal_sys: Registered thermal governor 'power_allocator'312server # [ 0.004761] SMP: Total of 1 processors activated.313server # [ 0.004764] CPU: All CPU(s) started at EL1314builder # [ 0.017741] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1315builder # [ 0.017751] cpuidle: using governor ladder316server # [ 0.004777] CPU features: detected: Branch Target Identification317builder # [ 0.017757] cpuidle: using governor menu318server # [ 0.004781] CPU features: detected: ARMv8.4 Translation Table Level319builder # [ 0.017953] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.320builder # [ 0.017969] ASID allocator initialised with 65536 entries321server # [ 0.004785] CPU features: detected: Instruction cache invalidation not required for I/D coherence322builder # [ 0.019170] Serial: AMBA PL011 UART driver323server # [ 0.004788] CPU features: detected: Data cache clean to the PoU not required for I/D coherence324builder # [ 0.024469] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1325server # [ 0.004792] CPU features: detected: Common not Private translations326builder # [ 0.024622] printk: console [ttyAMA0] enabled327server # [ 0.004795] CPU features: detected: CRC32 instructions328server # [ 0.004798] CPU features: detected: Data cache clean to Point of Deep Persistence329builder # [ 0.147766] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages330builder # [ 0.147784] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page331server # [ 0.004802] CPU features: detected: Data cache clean to Point of Persistence332builder # [ 0.147789] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages333server # [ 0.004805] CPU features: detected: Data independent timing control (DIT)334server # [ 0.004808] CPU features: detected: E0PD335builder # [ 0.147793] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page336server # [ 0.004810] CPU features: detected: Enhanced Counter Virtualization337builder # [ 0.147798] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages338server # [ 0.004813] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)339builder # [ 0.147803] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page340server # [ 0.004817] CPU features: detected: Enhanced Virtualization Traps341builder # [ 0.147807] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages342server # [ 0.004820] CPU features: detected: Fine Grained Traps343builder # [ 0.147811] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page344server # [ 0.004823] CPU features: detected: Generic authentication (architected QARMA5 algorithm)345server # [ 0.004828] CPU features: detected: RCpc load-acquire (LDAPR)346server # [ 0.004831] CPU features: detected: LSE atomic instructions347builder # [ 0.155272] fbcon: Taking over console348server # [ 0.004833] CPU features: detected: Privileged Access Never349builder # [ 0.155285] ACPI: Interpreter disabled.350server # [ 0.004836] CPU features: detected: PMUv3351builder # [ 0.157134] iommu: Default domain type: Translated352server # [ 0.004839] CPU features: detected: RAS Extension Support353builder # [ 0.157144] iommu: DMA domain TLB invalidation policy: strict mode354server # [ 0.004842] CPU features: detected: RASv1p1 Extension Support355builder # [ 0.158808] SCSI subsystem initialized356server # [ 0.004845] CPU features: detected: Random Number Generator357server # [ 0.004847] CPU features: detected: Speculation barrier (SB)358server # [ 0.004850] CPU features: detected: Stage-2 Force Write-Back359server # [ 0.004853] CPU features: detected: TLB range maintenance instructions360server # [ 0.004858] CPU features: detected: Speculative Store Bypassing Safe (SSBS)361server # [ 0.004895] alternatives: applying system-wide alternatives362server # [ 0.007800] CPU features: detected: BBM Level 2 without TLB conflict abort363builder # [ 0.164148] usbcore: registered new interface driver usbfs364server # [ 0.007933] Memory: 893408K/1048576K available (24448K kernel code, 7094K rwdata, 26372K rodata, 4736K init, 1107K bss, 113872K reserved, 32768K cma-reserved)365builder # [ 0.164178] usbcore: registered new interface driver hub366builder # [ 0.164201] usbcore: registered new device driver usb367server # [ 0.008269] devtmpfs: initialized368builder # [ 0.164451] pps_core: LinuxPPS API ver. 1 registered369server # [ 0.009936] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)370builder # [ 0.164457] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>371server # [ 0.009957] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).372builder # [ 0.164466] PTP clock support registered373builder # [ 0.164518] EDAC MC: Ver: 3.0.0374server # [ 0.010140] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL375server # [ 0.010145] 0 pages in range for non-PLT usage376builder # [ 0.169165] scmi_core: SCMI protocol bus registered377server # [ 0.010146] 508272 pages in range for PLT usage378server # [ 0.010237] pinctrl core: initialized pinctrl subsystem379builder # [ 0.170152] FPGA manager framework380builder # [ 0.171114] vgaarb: loaded381server # [ 0.010970] DMI not present or invalid.382server # [ 0.013909] NET: Registered PF_NETLINK/PF_ROUTE protocol family383builder # [ 0.171727] clocksource: Switched to clocksource arch_sys_counter384server # [ 0.016115] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations385server # [ 0.016270] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations386server # [ 0.016427] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations387server # [ 0.016448] audit: initializing netlink subsys (disabled)388builder # [ 0.175489] VFS: Disk quotas dquot_6.6.0389server # [ 0.017011] thermal_sys: Registered thermal governor 'fair_share'390server # [ 0.017013] thermal_sys: Registered thermal governor 'bang_bang'391builder # [ 0.175518] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)392server # [ 0.017016] thermal_sys: Registered thermal governor 'step_wise'393server # [ 0.017019] thermal_sys: Registered thermal governor 'user_space'394server # [ 0.017022] thermal_sys: Registered thermal governor 'power_allocator'395builder # [ 0.179289] netfs: FS-Cache loaded396server # [ 0.017046] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1397builder # [ 0.179410] pnp: PnP ACPI: disabled398server # [ 0.017056] cpuidle: using governor ladder399server # [ 0.017061] cpuidle: using governor menu400server # [ 0.017252] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.401server # [ 0.017267] ASID allocator initialised with 65536 entries402server # [ 0.018457] Serial: AMBA PL011 UART driver403server # [ 0.023550] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1404builder # [ 0.183328] NET: Registered PF_INET protocol family405server # [ 0.023673] printk: console [ttyAMA0] enabled406builder # [ 0.183496] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)407server # [ 0.145395] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages408server # [ 0.145412] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page409server # [ 0.145417] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages410server # [ 0.145421] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page411server # [ 0.145426] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages412server # [ 0.145430] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page413server # [ 0.145434] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages414server # [ 0.145438] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page415server # [ 0.153083] fbcon: Taking over console416server # [ 0.153099] ACPI: Interpreter disabled.417server # [ 0.162078] iommu: Default domain type: Translated418server # [ 0.162089] iommu: DMA domain TLB invalidation policy: strict mode419server # [ 0.162422] SCSI subsystem initialized420server # [ 0.164403] usbcore: registered new interface driver usbfs421server # [ 0.164433] usbcore: registered new interface driver hub422server # [ 0.164455] usbcore: registered new device driver usb423server # [ 0.164702] pps_core: LinuxPPS API ver. 1 registered424server # [ 0.164708] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>425server # [ 0.164717] PTP clock support registered426server # [ 0.164778] EDAC MC: Ver: 3.0.0427server # [ 0.169452] scmi_core: SCMI protocol bus registered428server # [ 0.170429] FPGA manager framework429server # [ 0.171367] vgaarb: loaded430server # [ 0.171987] clocksource: Switched to clocksource arch_sys_counter431builder # [ 0.212581] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)432server # [ 0.175499] VFS: Disk quotas dquot_6.6.0433builder # [ 0.212618] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)434server # [ 0.175535] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)435builder # [ 0.212639] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)436builder # [ 0.212679] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)437builder # [ 0.212754] TCP: Hash tables configured (established 8192 bind 8192)438builder # [ 0.212829] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)439server # [ 0.179198] netfs: FS-Cache loaded440server # [ 0.179316] pnp: PnP ACPI: disabled441builder # [ 0.212885] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)442builder # [ 0.212935] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)443builder # [ 0.213036] NET: Registered PF_UNIX/PF_LOCAL protocol family444builder # [ 0.213057] NET: Registered PF_XDP protocol family445builder # [ 0.213073] PCI: CLS 0 bytes, default 64446builder # [ 0.213296] Trying to unpack rootfs image as initramfs...447server # [ 0.183149] NET: Registered PF_INET protocol family448server # [ 0.183306] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)449builder # [ 0.229469] kvm [1]: HYP mode not available450server # [ 0.212973] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)451server # [ 0.213016] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)452server # [ 0.213039] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)453server # [ 0.213083] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)454server # [ 0.213159] TCP: Hash tables configured (established 8192 bind 8192)455server # [ 0.213239] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)456server # [ 0.213293] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)457server # [ 0.213342] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)458server # [ 0.213477] NET: Registered PF_UNIX/PF_LOCAL protocol family459server # [ 0.213560] NET: Registered PF_XDP protocol family460server # [ 0.213578] PCI: CLS 0 bytes, default 64461server # [ 0.213812] Trying to unpack rootfs image as initramfs...462server # [ 0.229594] kvm [1]: HYP mode not available463builder # [ 0.313597] Initialise system trusted keyrings464builder # [ 0.319783] workingset: timestamp_bits=42 max_order=18 bucket_order=0465builder # [ 0.321167] squashfs: version 4.0 (2009/01/31) Phillip Lougher466builder # [ 0.321941] 9p: Installing v9fs 9p2000 file system support467builder # [ 0.342603] Key type asymmetric registered468builder # [ 0.342625] Asymmetric key parser 'x509' registered469builder # [ 0.342701] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)470builder # [ 0.351793] io scheduler mq-deadline registered471builder # [ 0.351814] io scheduler kyber registered472builder # [ 0.356775] pl061_gpio 9030000.pl061: PL061 GPIO chip registered473builder # [ 0.358046] ledtrig-cpu: registered to indicate activity on CPUs474builder # [ 0.358436] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:475builder # [ 0.358453] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000476builder # [ 0.358465] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000477builder # [ 0.358474] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000478builder # [ 0.358495] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits479builder # [ 0.358516] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]480server # [ 0.320551] Initialise system trusted keyrings481builder # [ 0.358586] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00482server # [ 0.321295] workingset: timestamp_bits=42 max_order=18 bucket_order=0483builder # [ 0.358596] pci_bus 0000:00: root bus resource [bus 00-ff]484builder # [ 0.358602] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]485server # [ 0.322610] squashfs: version 4.0 (2009/01/31) Phillip Lougher486builder # [ 0.358607] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]487server # [ 0.323402] 9p: Installing v9fs 9p2000 file system support488builder # [ 0.358612] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]489builder # [ 0.358665] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint490builder # [ 0.359110] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint491builder # [ 0.359300] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]492builder # [ 0.359316] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]493builder # [ 0.359345] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]494builder # [ 0.359361] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]495builder # [ 0.379860] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint496builder # [ 0.380058] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]497builder # [ 0.380075] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]498builder # [ 0.380104] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]499builder # [ 0.380594] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint500builder # [ 0.380776] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]501builder # [ 0.380792] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]502builder # [ 0.380821] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]503builder # [ 0.381264] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint504builder # [ 0.381446] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]505builder # [ 0.381461] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]506builder # [ 0.381490] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]507builder # [ 0.381506] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]508builder # [ 0.381950] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint509builder # [ 0.382135] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]510builder # [ 0.382164] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]511builder # [ 0.382616] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint512builder # [ 0.382800] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]513server # [ 0.352123] Key type asymmetric registered514server # [ 0.352145] Asymmetric key parser 'x509' registered515builder # [ 0.382828] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]516server # [ 0.352218] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)517builder # [ 0.383248] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint518server # [ 0.354387] io scheduler mq-deadline registered519builder # [ 0.383426] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]520server # [ 0.354399] io scheduler kyber registered521builder # [ 0.383665] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint522server # [ 0.364125] pl061_gpio 9030000.pl061: PL061 GPIO chip registered523server # [ 0.365516] ledtrig-cpu: registered to indicate activity on CPUs524server # [ 0.365890] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:525builder # [ 0.407915] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]526server # [ 0.365907] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000527builder # [ 0.407956] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]528server # [ 0.365920] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000529builder # [ 0.408437] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint530builder # [ 0.408622] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]531server # [ 0.365928] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000532builder # [ 0.408652] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]533server # [ 0.365954] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits534builder # [ 0.409092] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint535server # [ 0.365978] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]536builder # [ 0.409274] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]537server # [ 0.366077] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00538builder # [ 0.409303] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]539server # [ 0.366086] pci_bus 0000:00: root bus resource [bus 00-ff]540builder # [ 0.409752] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint541server # [ 0.366092] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]542builder # [ 0.410019] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]543server # [ 0.366097] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]544builder # [ 0.410037] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]545server # [ 0.366102] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]546builder # [ 0.410066] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]547server # [ 0.366192] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint548builder # [ 0.410513] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint549builder # [ 0.410696] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]550server # [ 0.366633] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint551server # [ 0.366817] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]552builder # [ 0.410711] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]553server # [ 0.366833] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]554builder # [ 0.410740] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]555server # [ 0.366863] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]556builder # [ 0.411331] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned557server # [ 0.366879] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]558builder # [ 0.411343] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned559server # [ 0.367331] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint560builder # [ 0.411348] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned561server # [ 0.367513] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]562builder # [ 0.411393] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned563server # [ 0.367529] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]564server # [ 0.367558] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]565builder # [ 0.411439] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned566builder # [ 0.411485] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned567server # [ 0.387382] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint568builder # [ 0.411533] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned569server # [ 0.387567] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]570builder # [ 0.411579] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned571server # [ 0.387583] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]572server # [ 0.387613] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]573builder # [ 0.411626] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned574builder # [ 0.411671] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned575server # [ 0.391291] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint576builder # [ 0.411716] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned577server # [ 0.391477] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]578builder # [ 0.411777] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned579server # [ 0.391493] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]580builder # [ 0.411852] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned581server # [ 0.391523] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]582server # [ 0.391538] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]583builder # [ 0.411904] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned584builder # [ 0.411926] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned585builder # [ 0.411948] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned586builder # [ 0.411969] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned587builder # [ 0.411990] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned588builder # [ 0.412012] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned589builder # [ 0.412033] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned590builder # [ 0.412056] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned591builder # [ 0.412080] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned592builder # [ 0.412101] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned593builder # [ 0.412123] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned594builder # [ 0.412145] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned595builder # [ 0.412166] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned596builder # [ 0.412187] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned597builder # [ 0.412208] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned598builder # [ 0.412229] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned599builder # [ 0.412250] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned600server # [ 0.404085] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint601builder # [ 0.412271] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned602server # [ 0.404300] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]603builder # [ 0.412297] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]604server # [ 0.404333] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]605builder # [ 0.412306] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]606server # [ 0.404827] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint607builder # [ 0.412311] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]608server # [ 0.405016] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]609builder # [ 0.413138] pci 0000:00:07.0: enabling device (0000 -> 0002)610server # [ 0.405046] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]611server # [ 0.405435] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint612server # [ 0.405625] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]613server # [ 0.405904] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint614server # [ 0.406091] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]615server # [ 0.406120] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]616server # [ 0.406572] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint617server # [ 0.406758] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]618server # [ 0.406787] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]619server # [ 0.407235] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint620server # [ 0.407421] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]621server # [ 0.407450] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]622server # [ 0.407921] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint623server # [ 0.423706] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]624server # [ 0.423726] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]625server # [ 0.423755] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]626server # [ 0.426437] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint627server # [ 0.426620] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]628server # [ 0.426635] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]629server # [ 0.426664] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]630server # [ 0.427234] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned631server # [ 0.427245] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned632server # [ 0.427250] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned633server # [ 0.427295] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned634server # [ 0.427341] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned635builder # [ 0.481664] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)636server # [ 0.427387] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned637server # [ 0.427434] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned638server # [ 0.427479] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned639server # [ 0.427528] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned640builder # [ 0.484595] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)641server # [ 0.427573] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned642builder # [ 0.486560] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)643server # [ 0.427618] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned644server # [ 0.427663] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned645server # [ 0.427744] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned646server # [ 0.427797] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned647server # [ 0.427818] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned648server # [ 0.427839] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned649server # [ 0.427860] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned650server # [ 0.427881] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned651server # [ 0.427903] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned652server # [ 0.427924] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned653server # [ 0.427946] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned654server # [ 0.427971] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned655server # [ 0.428002] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned656builder # [ 0.496839] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)657server # [ 0.428024] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned658builder # [ 0.499067] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)659server # [ 0.428046] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned660server # [ 0.428067] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned661server # [ 0.428088] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned662server # [ 0.428109] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned663server # [ 0.428130] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned664server # [ 0.428151] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned665server # [ 0.428172] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned666server # [ 0.428198] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]667server # [ 0.428207] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]668server # [ 0.428212] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]669server # [ 0.429002] pci 0000:00:07.0: enabling device (0000 -> 0002)670builder # [ 0.510399] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)671builder # [ 0.512881] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)672builder # [ 0.514586] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)673builder # [ 0.516319] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)674builder # [ 0.518328] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)675builder # [ 0.532824] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)676server # [ 0.490055] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)677server # [ 0.492961] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)678server # [ 0.494980] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)679builder # [ 0.548171] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled680builder # [ 0.550670] msm_serial: driver initialized681builder # [ 0.550826] SuperH (H)SCI(F) driver initialized682builder # [ 0.550880] STM32 USART driver initialized683server # [ 0.505228] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)684server # [ 0.507421] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)685server # [ 0.511103] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)686server # [ 0.517831] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)687server # [ 0.524068] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)688server # [ 0.525979] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)689server # [ 0.527754] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)690builder # [ 0.581249] loop: module loaded691builder # [ 0.581435] virtio_blk virtio2: 1/0/0 default/read/poll queues692builder # [ 0.582247] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)693server # [ 0.537437] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)694builder # [ 0.588277] megasas: 07.734.00.00-rc1695builder # [ 0.588954] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]696builder # [ 0.591079] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000697builder # [ 0.591131] Intel/Sharp Extended Query Table at 0x0031698server # [ 0.554411] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled699builder # [ 0.600623] Using buffer write method700builder # [ 0.600684] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]701server # [ 0.556872] msm_serial: driver initialized702server # [ 0.557002] SuperH (H)SCI(F) driver initialized703builder # [ 0.603436] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000704server # [ 0.557060] STM32 USART driver initialized705builder # [ 0.603458] Intel/Sharp Extended Query Table at 0x0031706builder # [ 0.616788] Using buffer write method707builder # [ 0.616824] Concatenating MTD devices:708builder # [ 0.616828] (0): "0.flash"709builder # [ 0.616833] (1): "0.flash"710builder # [ 0.616836] into device "0.flash"711server # [ 0.588545] loop: module loaded712server # [ 0.588706] virtio_blk virtio2: 1/0/0 default/read/poll queues713server # [ 0.589444] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)714server # [ 0.600582] megasas: 07.734.00.00-rc1715server # [ 0.601266] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]716server # [ 0.603463] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000717server # [ 0.603490] Intel/Sharp Extended Query Table at 0x0031718server # [ 0.605126] Using buffer write method719server # [ 0.605190] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]720server # [ 0.606854] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000721server # [ 0.606877] Intel/Sharp Extended Query Table at 0x0031722server # [ 0.626179] Using buffer write method723server # [ 0.626213] Concatenating MTD devices:724server # [ 0.626217] (0): "0.flash"725server # [ 0.626222] (1): "0.flash"726server # [ 0.626225] into device "0.flash"727builder # [ 0.851962] Freeing initrd memory: 26972K728builder # [ 0.857983] tun: Universal TUN/TAP device driver, 1.6729builder # [ 0.861787] thunder_xcv, ver 1.0730builder # [ 0.861835] thunder_bgx, ver 1.0731builder # [ 0.861856] nicpf, ver 1.0732builder # [ 0.862407] e1000: Intel(R) PRO/1000 Network Driver733builder # [ 0.862414] e1000: Copyright (c) 1999-2006 Intel Corporation.734builder # [ 0.862440] e1000e: Intel(R) PRO/1000 Network Driver735builder # [ 0.862448] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.736builder # [ 0.862473] igb: Intel(R) Gigabit Ethernet Network Driver737builder # [ 0.862478] igb: Copyright (c) 2007-2014 Intel Corporation.738builder # [ 0.862500] igbvf: Intel(R) Gigabit Virtual Function Network Driver739builder # [ 0.862506] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.740builder # [ 0.862639] sky2: driver version 1.30741builder # [ 0.871287] usbcore: registered new interface driver usb-storage742builder # [ 0.871379] usbcore: registered new interface driver usbserial_generic743builder # [ 0.871393] usbserial: USB Serial support registered for generic744builder # [ 0.874248] hv_vmbus: registering driver hyperv_keyboard745builder # [ 0.876484] ehci-pci 0000:00:07.0: EHCI Host Controller746builder # [ 0.876519] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1747builder # [ 0.876684] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000748builder # [ 0.879246] rtc-pl031 9010000.pl031: registered as rtc0749builder # [ 0.879277] rtc-pl031 9010000.pl031: setting system clock to 2026-09-24T18:04:53 UTC (1790273093)750builder # [ 0.879660] i2c_dev: i2c /dev entries driver751builder # [ 0.884985] sdhci: Secure Digital Host Controller Interface driver752builder # [ 0.885000] sdhci: Copyright(c) Pierre Ossman753builder # [ 0.885260] Synopsys Designware Multimedia Card Interface Driver754builder # [ 0.885618] sdhci-pltfm: SDHCI platform and OF driver helper755builder # [ 0.887106] hid: raw HID events driver (C) Jiri Kosina756builder # [ 0.887347] usbcore: registered new interface driver usbhid757builder # [ 0.887355] usbhid: USB HID core driver758builder # [ 0.892201] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00759builder # [ 0.892518] hub 1-0:1.0: USB hub found760builder # [ 0.892542] hub 1-0:1.0: 6 ports detected761builder # [ 0.895294] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available762builder # [ 0.897963] drop_monitor: Initializing network drop monitor service763builder # [ 0.898145] NET: Registered PF_INET6 protocol family764builder # [ 0.900116] Segment Routing with IPv6765builder # [ 0.900136] In-situ OAM (IOAM) with IPv6766builder # [ 0.900165] NET: Registered PF_PACKET protocol family767builder # [ 0.901789] 9pnet: Installing 9P2000 support768builder # [ 0.901841] Key type dns_resolver registered769server # [ 0.864029] Freeing initrd memory: 26948K770builder # [ 0.908515] registered taskstats version 1771builder # [ 0.908671] Loading compiled-in X.509 certificates772server # [ 0.870160] tun: Universal TUN/TAP device driver, 1.6773builder # [ 0.917596] Demotion targets for Node 0: null774builder # [ 0.917720] Key type .fscrypt registered775builder # [ 0.917734] Key type fscrypt-provisioning registered776builder # [ 0.917841] ima: No TPM chip found, activating TPM-bypass!777builder # [ 0.917861] ima: Allocated hash algorithm: sha1778builder # [ 0.917883] ima: No architecture policies found779builder # [ 0.921940] input: gpio-keys as /devices/platform/gpio-keys/input/input0780server # [ 0.873935] thunder_xcv, ver 1.0781server # [ 0.873978] thunder_bgx, ver 1.0782server # [ 0.874000] nicpf, ver 1.0783server # [ 0.874544] e1000: Intel(R) PRO/1000 Network Driver784server # [ 0.874551] e1000: Copyright (c) 1999-2006 Intel Corporation.785server # [ 0.874577] e1000e: Intel(R) PRO/1000 Network Driver786server # [ 0.874585] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.787server # [ 0.874610] igb: Intel(R) Gigabit Ethernet Network Driver788server # [ 0.874616] igb: Copyright (c) 2007-2014 Intel Corporation.789server # [ 0.874637] igbvf: Intel(R) Gigabit Virtual Function Network Driver790server # [ 0.874643] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.791server # [ 0.874780] sky2: driver version 1.30792server # [ 0.876733] ehci-pci 0000:00:07.0: EHCI Host Controller793server # [ 0.876758] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1794server # [ 0.877039] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000795server # [ 0.886869] usbcore: registered new interface driver usb-storage796server # [ 0.886985] usbcore: registered new interface driver usbserial_generic797server # [ 0.886997] usbserial: USB Serial support registered for generic798server # [ 0.887597] hv_vmbus: registering driver hyperv_keyboard799server # [ 0.888045] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00800server # [ 0.888359] hub 1-0:1.0: USB hub found801server # [ 0.888392] hub 1-0:1.0: 6 ports detected802server # [ 0.893736] rtc-pl031 9010000.pl031: registered as rtc0803server # [ 0.893765] rtc-pl031 9010000.pl031: setting system clock to 2026-09-24T18:04:53 UTC (1790273093)804builder # [ 0.939778] clk: Disabling unused clocks805server # [ 0.894084] i2c_dev: i2c /dev entries driver806builder # [ 0.939807] PM: genpd: Disabling unused power domains807builder # [ 0.943980] Freeing unused kernel memory: 4736K808builder # [ 0.944204] Run /init as init process809server # [ 0.899515] sdhci: Secure Digital Host Controller Interface driver810server # [ 0.899527] sdhci: Copyright(c) Pierre Ossman811server # [ 0.899793] Synopsys Designware Multimedia Card Interface Driver812server # [ 0.902193] sdhci-pltfm: SDHCI platform and OF driver helper813server # [ 0.903737] hid: raw HID events driver (C) Jiri Kosina814server # [ 0.903972] usbcore: registered new interface driver usbhid815server # [ 0.903981] usbhid: USB HID core driver816server # [ 0.907572] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available817server # [ 0.910216] drop_monitor: Initializing network drop monitor service818server # [ 0.910352] NET: Registered PF_INET6 protocol family819server # [ 0.912519] Segment Routing with IPv6820server # [ 0.912539] In-situ OAM (IOAM) with IPv6821server # [ 0.912567] NET: Registered PF_PACKET protocol family822server # [ 0.914226] 9pnet: Installing 9P2000 support823server # [ 0.914274] Key type dns_resolver registered824builder # [ 0.962012] systemd[1]: Successfully made /usr/ read-only.825server # [ 0.921118] registered taskstats version 1826server # [ 0.921264] Loading compiled-in X.509 certificates827server # [ 0.930033] Demotion targets for Node 0: null828server # [ 0.930137] Key type .fscrypt registered829server # [ 0.930147] Key type fscrypt-provisioning registered830server # [ 0.930240] ima: No TPM chip found, activating TPM-bypass!831server # [ 0.930260] ima: Allocated hash algorithm: sha1832server # [ 0.930284] ima: No architecture policies found833server # [ 0.934351] input: gpio-keys as /devices/platform/gpio-keys/input/input0834server # [ 0.952489] clk: Disabling unused clocks835server # [ 0.952523] PM: genpd: Disabling unused power domains836server # [ 0.956713] Freeing unused kernel memory: 4736K837server # [ 0.956926] Run /init as init process838server # [ 0.974306] systemd[1]: Successfully made /usr/ read-only.839builder # [ 1.139812] usb 1-1: new high-speed USB device number 2 using ehci-pci840server # [ 1.136075] usb 1-1: new high-speed USB device number 2 using ehci-pci841builder # [ 1.294496] 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/input1842builder # [ 1.301021] 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)843builder # [ 1.301088] systemd[1]: Detected virtualization qemu.844builder # [ 1.301175] systemd[1]: Detected architecture arm64.845builder # [ 1.301203] systemd[1]: Running in initrd.846builder # [ 1.302269] systemd[1]: Initializing machine ID from random generator.847builder # [ 1.302591] systemd[1]: Hostname set to <builder>.848server # [ 1.290685] 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/input1849server # [ 1.309396] 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)850server # [ 1.321979] systemd[1]: Detected virtualization qemu.851server # [ 1.324155] systemd[1]: Detected architecture arm64.852server # [ 1.326141] systemd[1]: Running in initrd.853server # [ 1.329072] systemd[1]: Initializing machine ID from random generator.854server # [ 1.332156] systemd[1]: Hostname set to <server>.855builder # [ 1.383964] 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.376274] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0857builder # [ 1.507783] usb 1-2: new high-speed USB device number 3 using ehci-pci858server # [ 1.500076] usb 1-2: new high-speed USB device number 3 using ehci-pci859builder # [ 1.662273] 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/input2860builder # [ 1.668119] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0861server # [ 1.643200] systemd[1]: bpf-restrict-fs: LSM BPF program attached862server # [ 1.660614] 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/input2863server # [ 1.666393] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0864builder # [ 1.749577] systemd[1]: bpf-restrict-fs: LSM BPF program attached865server # [ 1.757582] systemd[1]: Queued start job for default target Initrd Default Target.866server # [ 1.766340] systemd[1]: Created slice Slice /system/modprobe.867server # [ 1.767509] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.868server # [ 1.768888] systemd[1]: Expecting device /dev/disk/by-label/nixos...869server # [ 1.769930] systemd[1]: Reached target Path Units.870server # [ 1.770728] systemd[1]: Reached target Slice Units.871server # [ 1.771532] systemd[1]: Reached target Swaps.872server # [ 1.772322] systemd[1]: Reached target Timer Units.873server # [ 1.773297] systemd[1]: Listening on D-Bus System Message Bus Socket.874server # [ 1.774504] systemd[1]: Listening on Journal Socket (/dev/log).875server # [ 1.775619] systemd[1]: Listening on Journal Sockets.876server # [ 1.776637] systemd[1]: Listening on udev Control Socket.877server # [ 1.776757] systemd[1]: Listening on udev Kernel Socket.878server # [ 1.776781] systemd[1]: Reached target Socket Units.879builder # [ 1.825373] systemd[1]: Queued start job for default target Initrd Default Target.880server # [ 1.780897] systemd[1]: Starting Create List of Static Device Nodes...881server # [ 1.782019] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs882server # [ 1.789288] systemd[1]: Mounting Kernel Configuration File System...883builder # [ 1.836458] systemd[1]: Created slice Slice /system/modprobe.884builder # [ 1.838095] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.885builder # [ 1.840016] systemd[1]: Expecting device /dev/disk/by-label/nixos...886builder # [ 1.841511] systemd[1]: Reached target Path Units.887builder # [ 1.842651] systemd[1]: Reached target Slice Units.888builder # [ 1.843975] systemd[1]: Reached target Swaps.889builder # [ 1.845026] systemd[1]: Reached target Timer Units.890builder # [ 1.846486] systemd[1]: Listening on D-Bus System Message Bus Socket.891builder # [ 1.848243] systemd[1]: Listening on Journal Socket (/dev/log).892server # [ 1.804444] systemd[1]: Starting Journal Service...893builder # [ 1.848455] systemd[1]: Listening on Journal Sockets.894builder # [ 1.848640] systemd[1]: Listening on udev Control Socket.895builder # [ 1.848798] systemd[1]: Listening on udev Kernel Socket.896builder # [ 1.848828] systemd[1]: Reached target Socket Units.897builder # [ 1.857050] systemd[1]: Starting Create List of Static Device Nodes...898builder # [ 1.858653] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs899server # [ 1.820308] systemd[1]: Starting Load Kernel Modules...900server # [ 1.821309] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os901builder # [ 1.865524] systemd[1]: Mounting Kernel Configuration File System...902builder # [ 1.888361] systemd[1]: Starting Journal Service...903server # [ 1.847208] systemd[1]: Starting Coldplug All udev Devices...904server # [ 1.861651] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.905builder # [ 1.907925] systemd[1]: Starting Load Kernel Modules...906server # [ 1.863471] systemd[1]: Finished Create List of Static Device Nodes.907builder # [ 1.909179] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os908server # [ 1.876090] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev909builder # [ 1.927923] systemd[1]: Starting Coldplug All udev Devices...910server # [ 1.882089] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0911server # [ 1.882321] [drm] features: -virgl +edid -resource_blob -host_visible912server # [ 1.882329] [drm] features: -context_init913server # [ 1.883025] [drm] number of scanouts: 1914server # [ 1.883042] [drm] number of cap sets: 0915server # [ 1.888114] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...916builder # [ 1.939994] systemd[1]: Finished Create List of Static Device Nodes.917server # [ 1.900699] systemd[1]: Mounted Kernel Configuration File System.918server # [ 1.903162] systemd-journald[72]: Collecting audit messages is disabled.919server # [ 1.908313] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic920builder # [ 1.960465] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...921server # [ 1.908329] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0922builder # [ 1.964341] systemd[1]: Mounted Kernel Configuration File System.923builder # [ 1.966595] systemd-journald[72]: Collecting audit messages is disabled.924server # [ 1.936573] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.925server # [ 1.939130] systemd[1]: Starting Create Static Device Nodes in /dev...926builder # [ 1.983545] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.927builder # [ 1.991622] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.928builder # [ 1.994072] systemd[1]: Starting Create Static Device Nodes in /dev...929server # [ 1.943633] Console: switching to colour frame buffer device 160x50930server # [ 1.950253] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device931builder # [ 2.003834] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev932builder # [ 2.018027] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0933builder # [ 2.018264] [drm] features: -virgl +edid -resource_blob -host_visible934builder # [ 2.018274] [drm] features: -context_init935builder # [ 2.018959] [drm] number of scanouts: 1936builder # [ 2.018978] [drm] number of cap sets: 0937server # [ 1.976809] systemd[1]: Finished Load Kernel Modules.938server # [ 1.980088] systemd[1]: Starting Apply Kernel Variables...939builder # [ 2.036143] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic940builder # [ 2.036167] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0941server # [ 2.000699] systemd[1]: Finished Create Static Device Nodes in /dev.942server # [ 2.000910] systemd[1]: Reached target Preparation for Local File Systems.943server # [ 2.000942] systemd[1]: Reached target Local File Systems.944builder # [ 2.052383] systemd[1]: Finished Create Static Device Nodes in /dev.945builder # [ 2.052587] systemd[1]: Reached target Preparation for Local File Systems.946server # [ 2.008433] systemd[1]: Starting Rule-based Manager for Device Events and Files...947builder # [ 2.052612] systemd[1]: Reached target Local File Systems.948builder # [ 2.060074] systemd[1]: Starting Rule-based Manager for Device Events and Files...949builder # [ 2.067981] Console: switching to colour frame buffer device 160x50950builder # [ 2.074668] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device951server # [ 2.040363] systemd[1]: Finished Apply Kernel Variables.952builder # [ 2.100340] systemd[1]: Finished Load Kernel Modules.953builder # [ 2.108099] systemd[1]: Starting Apply Kernel Variables...954server # [ 2.073665] systemd[1]: Started Journal Service.955server # [ 2.067092] systemd-modules-load[73]: Inserted module 'dm_mod'956server # [ 2.068264] systemd-modules-load[73]: Module 'virtio_balloon' is built in957server # [ 2.069386] systemd-modules-load[73]: Module 'virtio_console' is built in958server # [ 2.070480] systemd-modules-load[73]: Inserted module 'virtio_gpu'959server # [ 2.071459] systemd-modules-load[73]: Module 'virtio_rng' is built in960server # [ 2.086172] systemd[1]: Starting Create System Files and Directories...961builder # [ 2.152374] systemd[1]: Finished Apply Kernel Variables.962builder # [ 2.140458] systemd-modules-load[73]: Inserted module 'dm_mod'963builder # [ 2.141508] systemd-modules-load[73]: Module 'virtio_balloon' is built in964server # [ 2.095942] systemd-udevd[79]: Using default interface naming scheme 'v261'.965builder # [ 2.159941] systemd[1]: Started Journal Service.966builder # [ 2.147661] systemd-modules-load[73]: Module 'virtio_console' is built in967server # [ 2.106198] systemd[1]: Finished Create System Files and Directories.968builder # [ 2.156459] systemd-modules-load[73]: Inserted module 'virtio_gpu'969builder # [ 2.157480] systemd-modules-load[73]: Module 'virtio_rng' is built in970builder # [ 2.158528] systemd[1]: Starting Create System Files and Directories...971builder # [ 2.159846] systemd-udevd[78]: Using default interface naming scheme 'v261'.972server # [ 2.130019] systemd[1]: Started Rule-based Manager for Device Events and Files.973builder # [ 2.201783] systemd[1]: Finished Create System Files and Directories.974builder # [ 2.210499] systemd[1]: Started Rule-based Manager for Device Events and Files.975server # [ 2.181788] systemd[1]: Starting Virtual Console Setup...976builder # [ 2.270158] systemd[1]: Starting Virtual Console Setup...977server # [ 2.236487] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones.978server # [ 2.240044] systemd[1]: Finished Virtual Console Setup.979builder # [ 2.312434] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.980builder # [ 2.315816] systemd[1]: Finished Virtual Console Setup.981server # [ 2.825199] systemd[1]: Finished Coldplug All udev Devices.982server # [ 2.826185] systemd[1]: Reached target System Initialization.983server # [ 2.828121] systemd[1]: Reached target Basic System.984builder # [ 2.912179] systemd[1]: Finished Coldplug All udev Devices.985builder # [ 2.916153] systemd[1]: Reached target System Initialization.986builder # [ 2.916993] systemd[1]: Reached target Basic System.987server # [ 2.990994] (udev-worker)[91]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.988builder # [ 3.043610] (udev-worker)[99]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.989server # [ 3.005392] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.990server # [ 3.008318] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.991builder # [ 3.076343] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.992builder # [ 3.083236] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line.993server # [ 3.085666] systemd[1]: Found device /dev/disk/by-label/nixos.994server # [ 3.090050] systemd[1]: Reached target Initrd Root Device.995builder # [ 3.138407] systemd[1]: Found device /dev/disk/by-label/nixos.996builder # [ 3.140397] systemd[1]: Reached target Initrd Root Device.997server # [ 3.095247] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...998builder # [ 3.143966] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...999server # [ 3.140917] systemd-fsck[108]: nixos: clean, 12/65536 files, 13019/262144 blocks1000server # [ 3.149385] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1001builder # [ 3.195274] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks1002server # [ 3.152155] systemd[1]: Mounting /sysroot...1003builder # [ 3.201065] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1004builder # [ 3.209921] systemd[1]: Mounting /sysroot...1005server # [ 3.202849] EXT4-fs (vda): mounted filesystem fb1a74aa-a56e-44a7-a6ea-ada40ab08970 r/w with ordered data mode. Quota mode: none.1006server # [ 3.195854] systemd[1]: Mounted /sysroot.1007server # [ 3.196975] systemd[1]: Reached target Initrd Root File System.1008builder # [ 3.256911] EXT4-fs (vda): mounted filesystem a67c1247-fd61-478c-9d00-5139c02103a1 r/w with ordered data mode. Quota mode: none.1009builder # [ 3.246219] systemd[1]: Mounted /sysroot.1010server # [ 3.200774] systemd[1]: Starting Mountpoints Configured in the Real Root...1011builder # [ 3.251799] systemd[1]: Reached target Initrd Root File System.1012builder # [ 3.252851] systemd[1]: Starting Mountpoints Configured in the Real Root...1013builder # [ 3.264366] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.1014builder # [ 3.272092] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...1015server # [ 3.227629] systemd-sysroot-fstab-check[116]: /sysroot should be mounted in the initrd, will request daemon-reload.1016builder # [ 3.276232] systemd[1]: Reloading...1017server # [ 3.233642] systemd[1]: Reload requested from client PID 116 ('systemd-sysroot') (unit initrd-parse-etc.service)...1018server # [ 3.237086] systemd[1]: Reloading...1019builder # [ 3.478781] systemd[1]: Reloading finished in 207 ms.1020server # [ 3.444513] systemd[1]: Reloading finished in 208 ms.1021builder # [ 3.503718] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...1022builder # [ 3.508521] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...1023builder # [ 3.514235] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1024builder # [ 3.520140] systemd[1]: Finished Mountpoints Configured in the Real Root.1025server # [ 3.474763] systemd-sysroot-fstab-check[116]: Requesting initrd-fs.target/start/replace...1026builder # [ 3.521128] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1027server # [ 3.478356] systemd-sysroot-fstab-check[116]: Requesting swap.target/start/replace...1028server # [ 3.485104] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1029server # [ 3.487560] systemd[1]: Finished Mountpoints Configured in the Real Root.1030server # [ 3.489800] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1031server # [ 3.846365] systemd[1]: Mounting /sysroot/nix/.ro-store...1032server # [ 3.862979] systemd[1]: Mounting /sysroot/nix/.rw-store...1033server # [ 3.868126] systemd[1]: Mounting /sysroot/run...1034builder # [ 3.917389] systemd[1]: Mounting /sysroot/nix/.ro-store...1035server # [ 3.886776] systemd[1]: Mounting /sysroot/tmp/shared...1036builder # [ 3.935489] systemd[1]: Mounting /sysroot/nix/.rw-store...1037server # [ 3.906809] systemd[1]: Mounting /sysroot/tmp/xchg...1038builder # [ 3.955581] systemd[1]: Mounting /sysroot/run...1039builder # [ 3.970219] systemd[1]: Mounting /sysroot/tmp/shared...1040builder # [ 3.979752] systemd[1]: Mounting /sysroot/tmp/xchg...1041builder # [ 3.982872] systemd[1]: Mounted /sysroot/nix/.rw-store.1042server # [ 3.966735] fuse: init (API version 7.45)1043server # [ 3.954158] systemd[1]: Mounted /sysroot/nix/.rw-store.1044server # [ 3.979690] virtiofs virtio6: discovered new tag: nix-store1045builder # [ 4.012370] systemd[1]: Starting rw-sysroot-nix-store.service...1046server # [ 3.986666] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1047builder # [ 4.038016] fuse: init (API version 7.45)1048server # [ 4.001795] virtiofs virtio7: discovered new tag: shared1049server # [ 4.002544] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1050server # [ 4.010029] virtiofs virtio8: discovered new tag: xchg1051builder # [ 4.055731] virtiofs virtio6: discovered new tag: nix-store1052server # [ 4.010763] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1053builder # [ 4.056574] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1054server # [ 3.998952] systemd[1]: Starting rw-sysroot-nix-store.service...1055server # [ 4.003322] systemd[1]: Mounted /sysroot/run.1056builder # [ 4.073423] virtiofs virtio7: discovered new tag: shared1057builder # [ 4.074155] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1058builder # [ 4.078869] virtiofs virtio8: discovered new tag: xchg1059server # [ 4.019545] systemd[1]: Mounted /sysroot/nix/.ro-store.1060builder # [ 4.079662] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1061server # [ 4.026764] systemd[1]: Mounted /sysroot/tmp/shared.1062server # [ 4.028674] systemd[1]: Mounted /sysroot/tmp/xchg.1063server # [ 4.037379] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1064server # [ 4.040136] systemd[1]: Finished rw-sysroot-nix-store.service.1065server # [ 4.044285] systemd[1]: Mounting /sysroot/nix/store...1066builder # [ 4.091917] systemd[1]: Mounted /sysroot/nix/.ro-store.1067builder # [ 4.095656] systemd[1]: Mounted /sysroot/run.1068builder # [ 4.098565] systemd[1]: Mounted /sysroot/tmp/shared.1069builder # [ 4.100162] systemd[1]: Mounted /sysroot/tmp/xchg.1070builder # [ 4.102880] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1071builder # [ 4.105885] systemd[1]: Finished rw-sysroot-nix-store.service.1072builder # [ 4.112352] systemd[1]: Mounting /sysroot/nix/store...1073server # [ 4.087555] systemd[1]: Mounted /sysroot/nix/store.1074server # [ 4.089535] systemd[1]: Reached target Initrd File Systems.1075server # [ 4.091532] systemd[1]: Starting Find NixOS closure...1076server # [ 4.101264] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1077builder # [ 4.158841] systemd[1]: Mounted /sysroot/nix/store.1078builder # [ 4.160842] systemd[1]: Reached target Initrd File Systems.1079builder # [ 4.162850] systemd[1]: Starting Find NixOS closure...1080builder # [ 4.182679] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1081server # [ 4.138693] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1082server # [ 4.145263] systemd[1]: Finished Find NixOS closure.1083server # [ 4.147679] systemd[1]: Reached target Initrd Default Target.1084builder # [ 4.194917] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1085server # [ 4.152232] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1086builder # [ 4.212576] systemd[1]: Finished Find NixOS closure.1087builder # [ 4.213379] systemd[1]: Reached target Initrd Default Target.1088builder # [ 4.223201] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1089server # [ 4.186930] systemd[1]: Stopped target Initrd Default Target.1090server # [ 4.189380] systemd[1]: Stopped target Basic System.1091server # [ 4.190295] systemd[1]: Stopped target Initrd Root Device.1092server # [ 4.193256] systemd[1]: Stopped target Path Units.1093server # [ 4.197356] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1094builder # [ 4.243995] systemd[1]: Stopped target Initrd Default Target.1095server # [ 4.199005] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1096builder # [ 4.246676] systemd[1]: Stopped target Basic System.1097server # [ 4.202021] systemd[1]: Stopped target Slice Units.1098server # [ 4.204307] systemd[1]: Stopped target Socket Units.1099builder # [ 4.251629] systemd[1]: Stopped target Initrd Root Device.1100builder # [ 4.252546] systemd[1]: Stopped target Path Units.1101builder # [ 4.254249] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1102server # [ 4.209148] systemd[1]: Stopped target System Initialization.1103server # [ 4.211079] systemd[1]: Stopped target Swaps.1104builder # [ 4.257167] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1105server # [ 4.211748] systemd[1]: Stopped target Timer Units.1106server # [ 4.213699] systemd[1]: dbus.socket: Deactivated successfully.1107builder # [ 4.261710] systemd[1]: Stopped target Slice Units.1108server # [ 4.217174] systemd[1]: Closed D-Bus System Message Bus Socket.1109builder # [ 4.263435] systemd[1]: Stopped target Socket Units.1110server # [ 4.221388] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1111builder # [ 4.268445] systemd[1]: Stopped target System Initialization.1112builder # [ 4.269289] systemd[1]: Stopped target Swaps.1113builder # [ 4.269903] systemd[1]: Stopped target Timer Units.1114server # [ 4.224071] systemd[1]: Stopped Find NixOS closure.1115builder # [ 4.270583] systemd[1]: dbus.socket: Deactivated successfully.1116builder # [ 4.271402] systemd[1]: Closed D-Bus System Message Bus Socket.1117server # [ 4.226224] systemd[1]: Starting rw-sysroot-nix-store.service...1118server # [ 4.229589] systemd[1]: systemd-sysctl.service: Deactivated successfully.1119builder # [ 4.278209] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1120server # [ 4.232861] systemd[1]: Stopped Apply Kernel Variables.1121server # [ 4.234953] systemd[1]: systemd-modules-load.service: Deactivated successfully.1122builder # [ 4.282232] systemd[1]: Stopped Find NixOS closure.1123builder # [ 4.288251] systemd[1]: Starting rw-sysroot-nix-store.service...1124server # [ 4.243196] systemd[1]: Stopped Load Kernel Modules.1125server # [ 4.244003] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1126builder # [ 4.290262] systemd[1]: systemd-sysctl.service: Deactivated successfully.1127builder # [ 4.291582] systemd[1]: Stopped Apply Kernel Variables.1128builder # [ 4.292710] systemd[1]: systemd-modules-load.service: Deactivated successfully.1129server # [ 4.247297] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1130builder # [ 4.293701] systemd[1]: Stopped Load Kernel Modules.1131builder # [ 4.296259] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1132server # [ 4.252603] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1133builder # [ 4.298678] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1134server # [ 4.253707] systemd[1]: Stopped Create System Files and Directories.1135builder # [ 4.299769] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1136server # [ 4.254578] systemd[1]: Stopped target Local File Systems.1137server # [ 4.255328] systemd[1]: Stopped target Preparation for Local File Systems.1138server # [ 4.260925] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1139server # [ 4.263533] systemd[1]: Stopped Coldplug All udev Devices.1140builder # [ 4.310392] systemd[1]: Stopped Create System Files and Directories.1141server # [ 4.265864] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1142builder # [ 4.312328] systemd[1]: Stopped target Local File Systems.1143server # [ 4.266930] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1144server # [ 4.267920] systemd[1]: Stopped Virtual Console Setup.1145server # [ 4.268901] systemd[1]: initrd-cleanup.service: Deactivated successfully.1146server # [ 4.269828] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1147builder # [ 4.316560] systemd[1]: Stopped target Preparation for Local File Systems.1148server # [ 4.270717] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1149server # [ 4.271699] systemd[1]: Finished rw-sysroot-nix-store.service.1150builder # [ 4.317603] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1151builder # [ 4.318598] systemd[1]: Stopped Coldplug All udev Devices.1152builder # [ 4.320243] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1153builder # [ 4.321944] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1154builder # [ 4.323773] systemd[1]: Stopped Virtual Console Setup.1155builder # [ 4.324748] systemd[1]: initrd-cleanup.service: Deactivated successfully.1156builder # [ 4.325671] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1157server # [ 4.280212] systemd[1]: systemd-udevd.service: Deactivated successfully.1158builder # [ 4.326563] systemd[1]: systemd-udevd.service: Deactivated successfully.1159server # [ 4.281277] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1160builder # [ 4.327478] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1161server # [ 4.282289] systemd[1]: systemd-udevd.service: Consumed 1.124s CPU time over 2.282s wall clock time, 17.9M memory peak.1162builder # [ 4.328591] systemd[1]: systemd-udevd.service: Consumed 1.105s CPU time over 2.261s wall clock time, 17.9M memory peak.1163builder # [ 4.329997] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1164builder # [ 4.330977] systemd[1]: Closed udev Control Socket.1165builder # [ 4.331676] systemd[1]: Starting Cleanup udev Database...1166server # [ 4.285826] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1167server # [ 4.286959] systemd[1]: Closed udev Control Socket.1168server # [ 4.287667] systemd[1]: Starting Cleanup udev Database...1169server # [ 4.288537] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1170server # [ 4.289715] systemd[1]: Stopped Create Static Device Nodes in /dev.1171server # [ 4.290555] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1172server # [ 4.291618] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1173builder # [ 4.340310] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1174builder # [ 4.341390] systemd[1]: Stopped Create Static Device Nodes in /dev.1175builder # [ 4.342491] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1176builder # [ 4.344241] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1177server # [ 4.300211] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1178server # [ 4.301210] systemd[1]: Stopped Create List of Static Device Nodes.1179builder # [ 4.348217] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1180builder # [ 4.349193] systemd[1]: Stopped Create List of Static Device Nodes.1181builder # [ 4.350052] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1182builder # [ 4.352135] systemd[1]: Finished rw-sysroot-nix-store.service.1183builder # [ 4.374608] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1184server # [ 4.329460] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1185builder # [ 4.375742] systemd[1]: Finished Cleanup udev Database.1186builder # [ 4.376539] systemd[1]: Reached target Switch Root.1187server # [ 4.332517] systemd[1]: Finished Cleanup udev Database.1188server # [ 4.333332] systemd[1]: Reached target Switch Root.1189builder # [ 4.380476] systemd[1]: Starting NixOS Activation...1190server # [ 4.336166] systemd[1]: Starting NixOS Activation...1191builder # [ 4.457030] initrd-nixos-activation-start[168]: booting system configuration /nix/store/d4biv6zq93mg3l7fd2dx1vvsf6p3mp0m-nixos-system-builder-test1192server # [ 4.412168] initrd-nixos-activation-start[169]: booting system configuration /nix/store/zr4lbbj3mmgn536mw6wy9bklhc63i0a9-nixos-system-server-test1193builder # [ 4.489093] initrd-nixos-activation-start[168]: running activation script...1194server # [ 4.443890] initrd-nixos-activation-start[169]: running activation script...1195builder # [ 4.717521] initrd-nixos-activation-start[191]: setting up /etc...1196server # [ 4.687447] initrd-nixos-activation-start[192]: setting up /etc...1197builder # [ 4.833648] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1198builder # [ 4.836211] systemd[1]: Finished NixOS Activation.1199builder # [ 4.837599] systemd[1]: Starting Switch Root...1200server # [ 4.805823] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1201server # [ 4.808196] systemd[1]: Finished NixOS Activation.1202server # [ 4.810005] systemd[1]: Starting Switch Root...1203builder # [ 4.858935] systemd[1]: Switching root.1204server # [ 4.830987] systemd[1]: Switching root.1205builder # [ 5.041611] systemd-journald[72]: Received SIGTERM from PID 1 (systemd).1206server # [ 5.018305] systemd-journald[72]: Received SIGTERM from PID 1 (systemd).1207builder # [ 5.740664] 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)1208server # [ 5.696327] 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)1209builder # [ 5.753519] systemd[1]: Detected virtualization qemu.1210server # [ 5.709069] systemd[1]: Detected virtualization qemu.1211builder # [ 5.756668] systemd[1]: Detected architecture arm64.1212server # [ 5.711973] systemd[1]: Detected architecture arm64.1213builder # [ 5.759777] systemd[1]: Detected first boot.1214server # [ 5.714908] systemd[1]: Detected first boot.1215builder # [ 5.765281] systemd[1]: Initializing machine ID from random generator.1216server # [ 5.721841] systemd[1]: Initializing machine ID from random generator.1217server # [ 6.039972] systemd[1]: bpf-restrict-fs: LSM BPF program attached1218builder # [ 6.090710] systemd[1]: bpf-restrict-fs: LSM BPF program attached1219server # [ 6.230327] systemd[1]: Applying preset policy.1220builder # [ 6.284901] systemd[1]: Applying preset policy.1221builder # [ 6.522102] systemd[1]: Populated /etc with preset unit settings.1222server # [ 6.477962] systemd[1]: Populated /etc with preset unit settings.1223builder # [ 6.741876] systemd[1]: initrd-switch-root.service: Deactivated successfully.1224builder # [ 6.743522] systemd[1]: Stopped initrd-switch-root.service.1225server # [ 6.701877] systemd[1]: initrd-switch-root.service: Deactivated successfully.1226builder # [ 6.748283] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1227server # [ 6.703482] systemd[1]: Stopped initrd-switch-root.service.1228builder # [ 6.752135] systemd[1]: Created slice Slice /system/getty.1229builder # [ 6.753968] systemd[1]: Created slice User and Session Slice.1230server # [ 6.707915] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1231builder # [ 6.756203] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1232server # [ 6.712229] systemd[1]: Created slice Slice /system/getty.1233builder # [ 6.758520] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1234server # [ 6.714124] systemd[1]: Created slice User and Session Slice.1235builder # [ 6.760854] systemd[1]: Expecting device /dev/hvc0...1236server # [ 6.716322] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1237builder # [ 6.762689] systemd[1]: Expecting device /dev/ttyAMA0...1238server # [ 6.718666] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1239builder # [ 6.764663] systemd[1]: Reached target Local Encrypted Volumes.1240server # [ 6.720990] systemd[1]: Expecting device /dev/hvc0...1241builder # [ 6.766608] systemd[1]: Stopped target initrd-fs.target.1242builder # [ 6.768567] systemd[1]: Stopped target initrd-root-fs.target.1243server # [ 6.722866] systemd[1]: Expecting device /dev/ttyAMA0...1244builder # [ 6.769610] systemd[1]: Stopped target initrd-switch-root.target.1245server # [ 6.724878] systemd[1]: Reached target Local Encrypted Volumes.1246builder # [ 6.771238] systemd[1]: Reached target Virtual Machines and Containers.1247server # [ 6.726858] systemd[1]: Stopped target initrd-fs.target.1248server # [ 6.728819] systemd[1]: Stopped target initrd-root-fs.target.1249builder # [ 6.773880] systemd[1]: Reached target Path Units.1250builder # [ 6.774200] systemd[1]: Reached target Remote File Systems.1251server # [ 6.729881] systemd[1]: Stopped target initrd-switch-root.target.1252builder # [ 6.774442] systemd[1]: Reached target Slice Units.1253builder # [ 6.774688] systemd[1]: Reached target Swaps.1254server # [ 6.731517] systemd[1]: Reached target Virtual Machines and Containers.1255builder # [ 6.778907] systemd[1]: Listening on Query the User Interactively for a Password.1256server # [ 6.733322] systemd[1]: Reached target Path Units.1257server # [ 6.735596] systemd[1]: Reached target Remote File Systems.1258builder # [ 6.781806] systemd[1]: Listening on Process Core Dump Socket.1259server # [ 6.736705] systemd[1]: Reached target Slice Units.1260builder # [ 6.783988] systemd[1]: Listening on Credential Encryption/Decryption.1261server # [ 6.738992] systemd[1]: Reached target Swaps.1262builder # [ 6.786271] systemd[1]: Listening on Factory Reset Management.1263server # [ 6.741229] systemd[1]: Listening on Query the User Interactively for a Password.1264builder # [ 6.787460] systemd[1]: Listening on Hostname Service Socket.1265server # [ 6.744089] systemd[1]: Listening on Process Core Dump Socket.1266builder # [ 6.791214] systemd[1]: Starting Journal Log Access Socket...1267server # [ 6.746228] systemd[1]: Listening on Credential Encryption/Decryption.1268builder # [ 6.793233] systemd[1]: Listening on Journal Audit Socket.1269server # [ 6.748516] systemd[1]: Listening on Factory Reset Management.1270server # [ 6.749718] systemd[1]: Listening on Hostname Service Socket.1271builder # [ 6.796675] systemd[1]: Listening on Console Output Muting Service Socket.1272builder # [ 6.798171] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1273server # [ 6.753566] systemd[1]: Starting Journal Log Access Socket...1274builder # [ 6.799640] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1275server # [ 6.755541] systemd[1]: Listening on Journal Audit Socket.1276builder # [ 6.801510] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1277server # [ 6.759180] systemd[1]: Listening on Console Output Muting Service Socket.1278server # [ 6.760758] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1279builder # [ 6.807609] systemd[1]: Listening on Disk Repartitioning Service Socket.1280server # [ 6.762900] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1281builder # [ 6.808960] systemd[1]: Listening on udev Control Socket.1282builder # [ 6.810387] systemd[1]: Listening on udev Varlink Socket.1283server # [ 6.764589] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1284builder # [ 6.813518] systemd[1]: Mounting Huge Pages File System...1285server # [ 6.770755] systemd[1]: Listening on Disk Repartitioning Service Socket.1286server # [ 6.772114] systemd[1]: Listening on udev Control Socket.1287server # [ 6.773590] systemd[1]: Listening on udev Varlink Socket.1288builder # [ 6.820738] systemd[1]: Mounting POSIX Message Queue File System...1289server # [ 6.776786] systemd[1]: Mounting Huge Pages File System...1290builder # [ 6.830488] systemd[1]: Mounting Kernel Debug File System...1291server # [ 6.784994] systemd[1]: Mounting POSIX Message Queue File System...1292builder # [ 6.838497] systemd[1]: Mounting Kernel Trace File System...1293server # [ 6.798088] systemd[1]: Mounting Kernel Debug File System...1294builder # [ 6.845895] systemd[1]: Starting Create List of Static Device Nodes...1295builder # [ 6.851860] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1296server # [ 6.809225] systemd[1]: Mounting Kernel Trace File System...1297builder # [ 6.862356] systemd[1]: Mounting Kernel Configuration File System...1298builder # [ 6.866403] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1299server # [ 6.828435] systemd[1]: Starting Create List of Static Device Nodes...1300server # [ 6.829706] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1301builder # [ 6.876780] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1302builder # [ 6.879404] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1303server # [ 6.842091] systemd[1]: Mounting Kernel Configuration File System...1304builder # [ 6.893624] systemd[1]: Mounting FUSE Control File System...1305server # [ 6.848124] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1306builder # [ 6.899117] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671307server # [ 6.853610] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1308server # [ 6.856357] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1309server # [ 6.878259] systemd[1]: Mounting FUSE Control File System...1310server # [ 6.878653] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671311builder # [ 6.931210] systemd[1]: Starting Journal Service...1312server # [ 6.900151] systemd[1]: Starting Journal Service...1313builder # [ 6.949352] systemd[1]: Starting Load Kernel Modules...1314server # [ 6.912400] systemd[1]: Starting Load Kernel Modules...1315builder # [ 6.967506] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1316server # [ 6.930867] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1317builder # [ 6.982663] systemd[1]: Starting Remount Root and Kernel File Systems...1318builder # [ 6.983022] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1319server # [ 6.939707] systemd[1]: Starting Remount Root and Kernel File Systems...1320server # [ 6.941423] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1321builder # [ 7.003300] systemd[1]: Starting Coldplug All udev Devices...1322server # [ 6.960601] systemd[1]: Starting Coldplug All udev Devices...1323builder # [ 7.007161] systemd[1]: Listening on Journal Log Access Socket.1324server # [ 6.966656] systemd[1]: Listening on Journal Log Access Socket.1325server # [ 6.968464] systemd[1]: Mounted Huge Pages File System.1326server # [ 6.970016] systemd[1]: Mounted POSIX Message Queue File System.1327builder # [ 7.019603] systemd[1]: Mounted Huge Pages File System.1328server # [ 6.975670] systemd[1]: Mounted Kernel Debug File System.1329builder # [ 7.026169] systemd[1]: Mounted POSIX Message Queue File System.1330builder # [ 7.033276] systemd-journald[262]: Collecting audit messages is enabled.1331server # [ 6.991047] systemd[1]: Mounted Kernel Trace File System.1332builder # [ 7.038813] systemd[1]: Mounted Kernel Debug File System.1333builder # [ 7.041311] systemd[1]: Mounted Kernel Trace File System.1334server # [ 6.995215] systemd-journald[263]: Collecting audit messages is enabled.1335builder # [ 7.030317] systemd[1]: Queued start job for default target Multi-User System.1336builder # [ 7.047846] systemd[1]: Finished Create List of Static Device Nodes.1337builder # [ 7.048358] systemd[1]: Started Journal Service.1338builder # [ 7.038264] systemd[1]: systemd-journald.service: Deactivated successfully.1339builder # [ 7.039409] systemd[1]: Mounted Kernel Configuration File System.1340server # [ 7.007664] systemd[1]: Finished Create List of Static Device Nodes.1341server # [ 7.008334] systemd[1]: Mounted Kernel Configuration File System.1342server # [ 7.008721] systemd[1]: Mounted FUSE Control File System.1343builder # [ 7.050388] systemd-modules-load[263]: Module 'atkbd' is built in1344builder # [ 7.051420] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1345server # [ 7.026459] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1346builder # [ 7.059339] systemd-modules-load[263]: Module 'loop' is built in1347server # [ 7.030378] systemd[1]: Started Journal Service.1348server # [ 7.020245] systemd[1]: Queued start job for default target Multi-User System.1349server # [ 7.021739] systemd[1]: systemd-journald.service: Deactivated successfully.1350builder # [ 7.068705] systemd[1]: Finished Load Kernel Modules.1351builder # [ 7.069490] systemd[1]: Mounted FUSE Control File System.1352server # [ 7.023929] systemd-modules-load[264]: Module 'atkbd' is built in1353builder # [ 7.099960] EXT4-fs (vda): re-mounted a67c1247-fd61-478c-9d00-5139c02103a1.1354server # [ 7.040398] systemd-modules-load[264]: Module 'loop' is built in1355server # [ 7.041437] systemd-modules-load[264]: Inserted module 'tls'1356server # [ 7.050501] systemd[1]: Finished Load Kernel Modules.1357builder # [ 7.099006] systemd[1]: Starting Firewall...1358builder # [ 7.110245] systemd[1]: Starting Apply Kernel Variables...1359server # [ 7.080051] EXT4-fs (vda): re-mounted fb1a74aa-a56e-44a7-a6ea-ada40ab08970.1360server # [ 7.071751] systemd[1]: Starting Firewall...1361builder # [ 7.120426] systemd-oomd[264]: No swap; memory pressure usage will be degraded1362builder # [ 7.129929] systemd[1]: Finished Remount Root and Kernel File Systems.1363server # [ 7.084196] systemd[1]: Starting Apply Kernel Variables...1364builder # [ 7.130907] systemd[1]: Listening on Disk Image Download Service Socket.1365server # [ 7.088979] systemd[1]: Finished Remount Root and Kernel File Systems.1366builder # [ 7.140113] systemd[1]: Starting Flush Journal to Persistent Storage...1367builder # [ 7.144341] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1368server # [ 7.103146] systemd[1]: Listening on Disk Image Download Service Socket.1369builder # [ 7.152125] systemd[1]: Starting Load/Save OS Random Seed...1370server # [ 7.106687] systemd[1]: Starting Flush Journal to Persistent Storage...1371builder # [ 7.153011] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1372builder # [ 7.154199] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1373server # [ 7.107669] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1374server # [ 7.118102] systemd-oomd[265]: No swap; memory pressure usage will be degraded1375server # [ 7.127584] systemd[1]: Starting Load/Save OS Random Seed...1376server # [ 7.135649] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1377server # [ 7.141410] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1378builder # [ 7.244254] systemd-journald[262]: Received client request to flush runtime journal.1379server # [ 7.229571] systemd-journald[263]: Received client request to flush runtime journal.1380builder # [ 7.287959] systemd[1]: Finished Load/Save OS Random Seed.1381builder # [ 7.288974] systemd[1]: Reached target First Boot Complete.1382builder # [ 7.297184] systemd[1]: Finished Flush Journal to Persistent Storage.1383builder # [ 7.304463] systemd[1]: Finished Apply Kernel Variables.1384server # [ 7.276706] systemd[1]: Finished Load/Save OS Random Seed.1385server # [ 7.277640] systemd[1]: Reached target First Boot Complete.1386server # [ 7.278405] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1387server # [ 7.279360] systemd[1]: Starting Create Static Device Nodes in /dev...1388builder # [ 7.329152] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1389server # [ 7.285479] systemd[1]: Finished Apply Kernel Variables.1390server # [ 7.286314] systemd[1]: Finished Flush Journal to Persistent Storage.1391builder # [ 7.337096] systemd[1]: Starting Create Static Device Nodes in /dev...1392builder # [ 7.589572] systemd[1]: Finished Create Static Device Nodes in /dev.1393builder # [ 7.590885] systemd[1]: Reached target Preparation for Local File Systems.1394builder # [ 7.600819] systemd[1]: Starting Rule-based Manager for Device Events and Files...1395server # [ 7.574387] systemd[1]: Finished Create Static Device Nodes in /dev.1396server # [ 7.575425] systemd[1]: Reached target Preparation for Local File Systems.1397server # [ 7.580851] systemd[1]: Starting Rule-based Manager for Device Events and Files...1398builder # [ 7.734349] systemd[1]: Mounting /run/wrappers...1399server # [ 7.692407] systemd[1]: Mounting /run/wrappers...1400builder # [ 7.843120] systemd[1]: Mounted /run/wrappers.1401builder # [ 7.843944] systemd[1]: Reached target Local File Systems.1402builder # [ 7.850522] systemd[1]: Listening on Boot Loader Control Service Socket.1403builder # [ 7.853757] systemd[1]: Starting register-nix-paths.service...1404server # [ 7.813247] systemd[1]: Mounted /run/wrappers.1405server # [ 7.814034] systemd[1]: Reached target Local File Systems.1406builder # [ 7.861871] systemd[1]: Starting Create SUID/SGID Wrappers...1407builder # [ 7.862760] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1408server # [ 7.818190] systemd[1]: Listening on Boot Loader Control Service Socket.1409builder # [ 7.868371] systemd[1]: Starting Save Transient machine-id to Disk...1410builder # [ 7.869799] systemd-udevd[301]: Using default interface naming scheme 'v261'.1411server # [ 7.824219] systemd[1]: Starting register-nix-paths.service...1412server # [ 7.827335] systemd[1]: Starting Create SUID/SGID Wrappers...1413server # [ 7.828385] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1414server # [ 7.836133] systemd[1]: Starting Save Transient machine-id to Disk...1415builder # [ 7.884151] systemd[1]: Starting Create System Files and Directories...1416server # [ 7.843351] systemd[1]: Starting Create System Files and Directories...1417server # [ 7.850291] systemd-udevd[299]: Using default interface naming scheme 'v261'.1418builder # [ 8.011610] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1419builder # [ 8.024658] systemd[1]: Finished Save Transient machine-id to Disk.1420server # [ 8.001670] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1421server # [ 8.011766] systemd[1]: Finished Save Transient machine-id to Disk.1422builder # [ 8.102440] systemd[1]: Started Rule-based Manager for Device Events and Files.1423server # [ 8.060080] systemd[1]: Started Rule-based Manager for Device Events and Files.1424builder # [ 8.115975] systemd[1]: Finished Create System Files and Directories.1425builder # [ 8.128251] systemd[1]: Starting Rebuild Journal Catalog...1426builder # [ 8.140172] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1427server # [ 8.094684] systemd[1]: Finished Create System Files and Directories.1428server # [ 8.099210] systemd[1]: Starting Rebuild Journal Catalog...1429server # [ 8.102282] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1430builder # [ 8.288728] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1431server # [ 8.243221] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1432builder # [ 8.327146] systemd[1]: Finished Rebuild Journal Catalog.1433server # [ 8.290408] systemd[1]: Finished Rebuild Journal Catalog.1434builder # [ 8.341000] systemd[1]: Starting Update is Completed...1435server # [ 8.304216] systemd[1]: Starting Update is Completed...1436server # [ 8.405296] systemd[1]: Finished Update is Completed.1437builder # [ 8.454075] systemd[1]: Finished Update is Completed.1438server # [ 8.465853] systemd[1]: Finished Coldplug All udev Devices.1439builder # [ 8.525067] systemd[1]: Finished Coldplug All udev Devices.1440server # [ 8.956858] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1441server # [ 8.958088] systemd[1]: Finished Create SUID/SGID Wrappers.1442builder # [ 9.025877] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1443builder # [ 9.033306] systemd[1]: Finished Create SUID/SGID Wrappers.1444server # [ 9.071666] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31445builder # [ 9.164056] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31446server # [ 9.119642] systemd[1]: Finished register-nix-paths.service.1447builder # [ 9.169617] systemd[1]: Finished register-nix-paths.service.1448server # [ 9.124349] systemd[1]: Reached target System Initialization.1449server # [ 9.125292] systemd[1]: Started Discard unused filesystem blocks once a week.1450server # [ 9.126334] systemd[1]: Started niks3 garbage collection timer.1451builder # [ 9.172155] systemd[1]: Reached target System Initialization.1452server # [ 9.127190] systemd[1]: Started Daily Cleanup of Temporary Directories.1453builder # [ 9.173162] systemd[1]: Started Discard unused filesystem blocks once a week.1454builder # [ 9.174192] systemd[1]: Started Daily Cleanup of Temporary Directories.1455builder # [ 9.175137] systemd[1]: Reached target Timer Units.1456server # [ 9.137541] systemd[1]: Reached target Timer Units.1457server # [ 9.138315] systemd[1]: Listening on D-Bus System Message Bus Socket.1458builder # [ 9.184516] systemd[1]: Listening on D-Bus System Message Bus Socket.1459server # [ 9.139210] systemd[1]: Starting niks3 server proxy socket...1460builder # [ 9.185596] systemd[1]: Starting niks3 auto-upload socket...1461builder # [ 9.186434] systemd[1]: Listening on Nix Daemon Socket.1462builder # [ 9.187217] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1463server # [ 9.148234] systemd[1]: Listening on niks3 server socket.1464server # [ 9.149115] systemd[1]: Listening on Nix Daemon Socket.1465server # [ 9.149879] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1466builder # [ 9.200363] systemd[1]: Starting D-Bus System Message Bus...1467builder # [ 9.203445] systemd[1]: Listening on niks3 auto-upload socket.1468builder # [ 9.207180] systemd[1]: Reached target Socket Units.1469server # [ 9.162934] systemd[1]: Starting D-Bus System Message Bus...1470server # [ 9.168193] systemd[1]: Listening on niks3 server proxy socket.1471server # [ 9.169282] systemd[1]: Reached target Socket Units.1472builder # [ 9.367891] dbus-broker-launch[444]: Looking up NSS user entry for 'systemd-timesync'...1473builder # [ 9.379849] dbus-broker-launch[444]: NSS returned no entry for 'systemd-timesync'1474builder # [ 9.382394] dbus-broker-launch[444]: Invalid user-name in /nix/store/64ybjy0j0jm3q7n35jd2p8yj5r0bll5b-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1475server # [ 9.348637] dbus-broker-launch[442]: Looking up NSS user entry for 'systemd-timesync'...1476server # [ 9.357497] dbus-broker-launch[442]: NSS returned no entry for 'systemd-timesync'1477server # [ 9.358608] dbus-broker-launch[442]: Invalid user-name in /nix/store/b19mc116rikagvwfpji7hvndh956i7yi-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1478builder # [ 9.448112] systemd[1]: Started D-Bus System Message Bus.1479builder # [ 9.449086] systemd[1]: Reached target Basic System.1480server # [ 9.407922] systemd[1]: Started D-Bus System Message Bus.1481server # [ 9.414699] systemd[1]: Reached target Basic System.1482builder # [ 9.462682] systemd[1]: Starting Import lastlog data into lastlog2 database...1483builder # [ 9.466811] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1484server # [ 9.423689] systemd[1]: Starting Import lastlog data into lastlog2 database...1485builder # [ 9.474827] systemd[1]: Starting Post-Boot Actions...1486server # [ 9.430380] systemd[1]: Starting Generate test mTLS certs...1487server # [ 9.446531] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1488server # [ 9.456934] systemd[1]: Starting Post-Boot Actions...1489server # [ 9.464405] dbus-broker-launch[442]: Ready1490server # [ 9.494582] systemd[1]: Started Reset console on configuration changes.1491builder # [ 9.540461] systemd[1]: Started Reset console on configuration changes.1492server # [ 9.517309] systemd[1]: Starting resolvconf update...1493builder # [ 9.572492] dbus-broker-launch[444]: Ready1494builder # [ 9.585394] systemd[1]: Starting resolvconf update...1495server # [ 9.659630] systemd[1]: Started Name Service Cache Daemon (nsncd).1496server # [ 9.665907] nsncd[455]: Sep 24 18:05:02.282 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1497server # [ 9.680968] systemd[1]: Finished Post-Boot Actions.1498builder # [ 9.726537] nsncd[460]: Sep 24 18:05:02.361 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1499builder # [ 9.737876] systemd[1]: Started Name Service Cache Daemon (nsncd).1500builder # [ 9.748188] systemd[1]: Reached target Host and Network Name Lookups.1501builder # [ 9.749283] systemd[1]: Reached target User and Group Name Lookups.1502server # [ 9.706016] niks3-test-certs-start[470]: -----1503server # [ 9.713791] systemd[1]: Reached target Host and Network Name Lookups.1504server # [ 9.714754] systemd[1]: Reached target User and Group Name Lookups.1505builder # [ 9.762697] systemd[1]: Starting User Login Management...1506builder # [ 9.776844] systemd[1]: Finished Post-Boot Actions.1507server # [ 9.736564] systemd[1]: Starting User Login Management...1508server # [ 9.771235] niks3-test-certs-start[487]: -----1509server # [ 9.793856] systemd[1]: Finished Import lastlog data into lastlog2 database.1510builder # [ 9.915878] systemd[1]: Finished Import lastlog data into lastlog2 database.1511server # [ 9.949333] niks3-test-certs-start[498]: Certificate request self-signature ok1512server # [ 9.950529] niks3-test-certs-start[498]: subject=CN=server1513builder # [ 10.003033] systemd-logind[486]: New seat seat0.1514builder # [ 10.018562] systemd[1]: Started User Login Management.1515builder # [ 10.045842] systemd[1]: Stopped target Host and Network Name Lookups.1516server # [ 10.002638] systemd-logind[486]: New seat seat0.1517builder # [ 10.051129] systemd[1]: Stopping Host and Network Name Lookups...1518builder # [ 10.055580] systemd[1]: Stopped target User and Group Name Lookups.1519builder # [ 10.062403] systemd[1]: Stopping User and Group Name Lookups...1520builder # [ 10.063266] systemd[1]: Starting linger-users.service...1521server # [ 10.017843] niks3-test-certs-start[528]: -----1522server # [ 10.018624] systemd[1]: Started User Login Management.1523builder # [ 10.071640] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1524server # [ 10.031604] systemd[1]: Starting linger-users.service...1525builder # [ 10.084503] systemd[1]: Starting Virtual Console Setup...1526builder # [ 10.095657] systemd[1]: Finished Firewall.1527builder # [ 10.100380] systemd[1]: nscd.service: Deactivated successfully.1528builder # [ 10.114620] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1529server # [ 10.114599] systemd[1]: Stopped target Host and Network Name Lookups.1530server # [ 10.117036] systemd[1]: Stopping Host and Network Name Lookups...1531server # [ 10.125402] systemd[1]: Stopped target User and Group Name Lookups.1532server # [ 10.126483] systemd[1]: Stopping User and Group Name Lookups...1533server # [ 10.135598] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1534server # [ 10.139066] systemd[1]: nscd.service: Deactivated successfully.1535server # [ 10.144179] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1536builder # [ 10.195516] systemd[1]: linger-users.service: Deactivated successfully.1537builder # [ 10.199152] systemd[1]: Finished linger-users.service.1538server # [ 10.172401] niks3-test-certs-start[536]: Certificate request self-signature ok1539server # [ 10.173492] niks3-test-certs-start[536]: subject=CN=niks3 test client1540builder # [ 10.222117] systemd[1]: Finished resolvconf update.1541builder # [ 10.232790] systemd[1]: Reached target Preparation for Network.1542builder # [ 10.241959] systemd[1]: Starting DHCP Client...1543builder # [ 10.242782] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1544server # [ 10.203408] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1545server # [ 10.206148] systemd[1]: linger-users.service: Deactivated successfully.1546builder # [ 10.254307] systemd[1]: Starting Extra networking commands....1547server # [ 10.211896] systemd[1]: Finished linger-users.service.1548builder # [ 10.267990] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1549server # [ 10.240344] systemd[1]: Finished Generate test mTLS certs.1550server # [ 10.279089] systemd[1]: Starting Virtual Console Setup...1551builder # [ 10.383067] systemd[1]: Started Name Service Cache Daemon (nsncd).1552builder # [ 10.389204] nsncd[570]: Sep 24 18:05:03.021 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1553builder # [ 10.397720] systemd[1]: Reached target Host and Network Name Lookups.1554server # [ 10.356289] nsncd[554]: Sep 24 18:05:02.977 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1555builder # [ 10.403673] systemd[1]: Reached target User and Group Name Lookups.1556server # [ 10.364395] systemd[1]: Started Name Service Cache Daemon (nsncd).1557server # [ 10.369743] systemd[1]: Finished resolvconf update.1558server # [ 10.373702] systemd[1]: Reached target Host and Network Name Lookups.1559server # [ 10.380138] systemd[1]: Reached target User and Group Name Lookups.1560server # [ 10.391765] systemd[1]: Starting DHCP Client...1561server # [ 10.393773] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1562server # [ 10.535415] systemd[1]: Finished Firewall.1563server # [ 10.538842] systemd[1]: Reached target Preparation for Network.1564builder # [ 10.589540] dhcpcd[586]: dhcpcd-10.3.2 starting1565server # [ 10.546097] systemd[1]: Starting Extra networking commands....1566builder # [ 10.612124] dhcpcd[621]: dev: loaded udev1567builder # [ 10.612915] dhcpcd[621]: no valid interfaces found1568builder # [ 10.613650] dhcpcd[621]: no valid interfaces found1569builder # [ 10.623367] dhcpcd[621]: libudev: received NULL device1570builder # [ 10.625742] dhcpcd[621]: libudev: received NULL device1571builder # [ 10.635994] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1572builder # [ 10.676585] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1573builder # [ 10.685599] systemd[1]: Started backdoor.service.1574builder # [ 10.709516] systemd[1]: Finished Extra networking commands..1575builder # [ 10.714617] systemd[1]: Reached target Network.1576builder # [ 10.725424] systemd[1]: Starting Permit User Sessions...1577server # [ 10.689787] dhcpcd[603]: dhcpcd-10.3.2 starting1578server # [ 10.704486] dhcpcd[612]: dev: loaded udev1579server # [ 10.709479] dhcpcd[612]: no valid interfaces found1580server # [ 10.711748] dhcpcd[612]: no valid interfaces found1581builder # [ 10.797449] systemd[1]: Finished Permit User Sessions.1582builder # connecting to host...1583server # [ 10.767192] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1584builder # [ 10.814034] systemd[1]: Started Getty on tty1.1585builder # [ 10.818733] systemd[1]: Reached target Login Prompts.1586builder # [ 10.875509] 8021q: 802.1Q VLAN Support v1.81587builder # [ 10.866305] (udev-worker)[397]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1588builder # [ 10.878915] (udev-worker)[397]: Network interface NamePolicy= disabled on kernel command line.1589builder # [ 10.883328] (udev-worker)[395]: Network interface NamePolicy= disabled on kernel command line.1590server # [ 10.868789] systemd[1]: Finished Extra networking commands..1591server # [ 10.874854] systemd[1]: Reached target Network.1592server # [ 10.884756] systemd[1]: Started Mock OIDC server for testing.1593server # [ 10.892137] systemd[1]: Starting Nginx Web Server...1594server # [ 10.916788] 8021q: 802.1Q VLAN Support v1.81595server # [ 10.915084] systemd[1]: Starting PostgreSQL Server...1596server # [ 10.929991] systemd[1]: Started RustFS S3-compatible object storage.1597builder # [ 10.982332] systemd[1]: Condition check resulted in Virtio network device being skipped.1598builder # [ 10.994591] systemd[1]: Starting Address configuration of eth1...1599server # [ 10.950872] systemd[1]: Starting Setup RustFS bucket...1600server # [ 10.954945] systemd[1]: Starting Permit User Sessions...1601builder # [ 11.018931] cfg80211: Loading compiled-in X.509 certificates for regulatory database1602builder # [ 11.064373] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1603builder # [ 11.064925] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1604builder # [ 11.070724] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21605builder # [ 11.071091] cfg80211: failed to load regulatory.db1606builder # [ 11.128418] 8021q: adding VLAN 0 to HW filter on device eth11607builder # [ 11.119436] systemd-vconsole-setup[549]: Configuration of first virtual console was skipped, ignoring remaining ones.1608builder # [ 11.127809] systemd[1]: Finished Virtual Console Setup.1609builder # [ 11.149134] network-addresses-eth1-start[651]: adding address 192.168.1.1/24... done1610builder # [ 11.172808] network-addresses-eth1-start[651]: adding address 2001:db8:1::1/64... done1611server # [ 11.142780] systemd[1]: Finished Permit User Sessions.1612builder # [ 11.206968] 8021q: adding VLAN 0 to HW filter on device eth01613builder # [ 11.194189] dhcpcd[621]: eth0: waiting for carrier1614builder # [ 11.196115] dhcpcd[621]: eth0: carrier acquired1615builder # [ 11.203715] systemd[1]: Finished Address configuration of eth1.1616server # [ 11.160290] systemd[1]: Started Getty on tty1.1617server # [ 11.183891] cfg80211: Loading compiled-in X.509 certificates for regulatory database1618builder # [ 11.215768] dhcpcd[621]: DUID 00:01:00:01:32:48:22:cf:52:54:00:12:34:561619server # [ 11.171309] systemd[1]: Reached target Login Prompts.1620builder # [ 11.217944] dhcpcd[621]: eth0: IAID 00:12:34:561621builder # [ 11.218613] dhcpcd[621]: eth0: adding address fe80::5054:ff:fe12:34561622server # [ 11.257882] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1623server # [ 11.283610] systemd[1]: Started backdoor.service.1624server # [ 11.333647] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1625server # [ 11.334233] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1626server # [ 11.347373] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21627server # [ 11.347737] cfg80211: failed to load regulatory.db1628builder # [ 11.387542] systemd-logind[486]: Watching system buttons on /dev/input/event0 (gpio-keys)1629builder # [ 11.487893] mousedev: PS/2 mouse device common for all mice1630server # [ 11.447150] systemd-vconsole-setup[569]: Configuration of first virtual console was skipped, ignoring remaining ones.1631server # [ 11.472494] systemd[1]: Finished Virtual Console Setup.1632builder # [ 11.591908] systemd-logind[486]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1633server # connecting to host...1634server # [ 11.625352] (udev-worker)[687]: Network interface NamePolicy= disabled on kernel command line.1635server # [ 11.632816] nginx-pre-start[677]: nginx: the configuration file /nix/store/dmvygyna1j5jhfwnhgm5gxfmi5y8yliq-nginx.conf syntax is ok1636server # [ 11.634374] nginx-pre-start[677]: nginx: configuration file /nix/store/dmvygyna1j5jhfwnhgm5gxfmi5y8yliq-nginx.conf test is successful1637server: Guest shell says: b'Spawning backdoor root shell...\n'1638server # [ 11.667545] systemd[1]: Started Nginx Web Server.1639server: connected to guest root shell1640server: (connecting took 12.01 seconds)1641server: (finished: waiting for the VM to finish booting, in 12.01 seconds)1642server # [ 11.813096] mock-oidc-server[658]: Mock OIDC Server running1643server # [ 11.814031] mock-oidc-server[658]: OIDC Address: 127.0.0.1:80801644server # [ 11.814844] mock-oidc-server[658]: Issue Address: 127.0.0.1:80811645server # [ 11.815650] mock-oidc-server[658]: Issuer: http://127.0.0.1:8080/oidc1646server # [ 11.828277] mock-oidc-server[658]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json1647server # [ 11.829387] mock-oidc-server[658]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration1648server # [ 11.830618] mock-oidc-server[658]: Issue tokens: http://127.0.0.1:8081/issue?sub=...1649server # [ 11.845712] (udev-worker)[690]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1650server # [ 11.847834] (udev-worker)[690]: Network interface NamePolicy= disabled on kernel command line.1651server # [ 11.871811] postgresql-pre-start[693]: The files belonging to this database system will be owned by user "postgres".1652server # [ 11.875406] postgresql-pre-start[693]: This user must also own the server process.1653server # [ 11.886815] postgresql-pre-start[693]: The database cluster will be initialized with locale "en_US.UTF-8".1654server # [ 11.892837] postgresql-pre-start[693]: The default database encoding has accordingly been set to "UTF8".1655server # [ 11.894094] postgresql-pre-start[693]: The default text search configuration will be set to "english".1656server # [ 11.895283] postgresql-pre-start[693]: Data page checksums are enabled.1657server # [ 11.906316] postgresql-pre-start[693]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok1658server # [ 11.907645] postgresql-pre-start[693]: creating subdirectories ... ok1659server # [ 11.914332] postgresql-pre-start[693]: selecting dynamic shared memory implementation ... posix1660server # [ 12.153160] postgresql-pre-start[693]: selecting default "max_connections" ... 1001661server # [ 12.375656] systemd[1]: Condition check resulted in Virtio network device being skipped.1662server # [ 12.383362] systemd[1]: Starting Address configuration of eth1...1663server # [ 12.429852] 8021q: adding VLAN 0 to HW filter on device eth01664server # [ 12.420816] systemd-logind[486]: Watching system buttons on /dev/input/event0 (gpio-keys)1665server # [ 12.423687] dhcpcd[612]: eth0: waiting for carrier1666server # [ 12.429770] dhcpcd[612]: eth0: carrier acquired1667server # [ 12.430472] postgresql-pre-start[693]: selecting default "shared_buffers" ... 128MB1668server # [ 12.458181] dhcpcd[612]: DUID 00:01:00:01:32:48:22:d1:52:54:00:12:34:561669server # [ 12.459199] dhcpcd[612]: eth0: IAID 00:12:34:561670server # [ 12.459862] dhcpcd[612]: eth0: adding address fe80::5054:ff:fe12:34561671server # [ 12.560845] 8021q: adding VLAN 0 to HW filter on device eth11672server # [ 12.580647] network-addresses-eth1-start[746]: adding address 192.168.1.2/24... done1673builder # [ 12.631994] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1674builder # [ 12.633291] systemd[1]: Stopped Virtual Console Setup.1675builder # [ 12.634014] systemd[1]: Stopping Virtual Console Setup...1676builder # [ 12.640493] systemd[1]: Starting Virtual Console Setup...1677server # [ 12.608794] network-addresses-eth1-start[746]: adding address 2001:db8:1::2/64... done1678builder # [ 12.663693] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1679builder # [ 12.666097] systemd[1]: Stopped Virtual Console Setup.1680builder # [ 12.686806] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1681builder # [ 12.691386] systemd[1]: Starting Virtual Console Setup...1682server # [ 12.645685] systemd[1]: Finished Address configuration of eth1.1683builder # [ 12.731787] systemd-logind[486]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1684server # [ 12.722427] mousedev: PS/2 mouse device common for all mice1685builder # [ 12.834295] systemd-vconsole-setup[690]: Configuration of first virtual console was skipped, ignoring remaining ones.1686builder # [ 12.838854] systemd[1]: Finished Virtual Console Setup.1687builder # [ 12.849791] dhcpcd[621]: eth0: soliciting an IPv6 router1688builder # [ 12.850611] dhcpcd[621]: eth0: Router Advertisement from fe80::21689builder # [ 12.851483] dhcpcd[621]: eth0: adding address fec0::5054:ff:fe12:3456/641690builder # [ 12.852713] dhcpcd[621]: eth0: adding route to fec0::/641691builder # [ 12.853448] dhcpcd[621]: eth0: adding default route via fe80::21692server # [ 12.904197] systemd-logind[486]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1693builder # [ 13.073465] dhcpcd[621]: eth0: soliciting a DHCP lease1694builder # [ 13.077296] dhcpcd[621]: eth0: offered 10.0.2.15 from 10.0.2.21695builder # [ 13.084557] dhcpcd[621]: eth0: probing address 10.0.2.15/241696server # [ 14.139152] dhcpcd[612]: eth0: soliciting a DHCP lease1697server # [ 14.144554] dhcpcd[612]: eth0: offered 10.0.2.15 from 10.0.2.21698server # [ 14.152300] dhcpcd[612]: eth0: probing address 10.0.2.15/241699server # [ 14.392541] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1700server # [ 14.397087] systemd[1]: Stopped Virtual Console Setup.1701server # [ 14.399116] systemd[1]: Stopping Virtual Console Setup...1702server # [ 14.403370] systemd[1]: Starting Virtual Console Setup...1703server # [ 14.438996] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1704server # [ 14.486729] systemd-logind[486]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1705server # [ 14.710881] systemd-vconsole-setup[785]: Configuration of first virtual console was skipped, ignoring remaining ones.1706server # [ 14.715005] systemd[1]: Finished Virtual Console Setup.1707server # [ 14.860442] postgresql-pre-start[693]: selecting default time zone ... UTC1708server # [ 14.863111] postgresql-pre-start[693]: creating configuration files ... ok1709server # [ 15.012362] dhcpcd[612]: eth0: soliciting an IPv6 router1710server # [ 15.013721] dhcpcd[612]: eth0: Router Advertisement from fe80::21711server # [ 15.014542] dhcpcd[612]: eth0: adding address fec0::5054:ff:fe12:3456/641712server # [ 15.015418] dhcpcd[612]: eth0: adding route to fec0::/641713server # [ 15.018125] dhcpcd[612]: eth0: adding default route via fe80::21714server # [ 15.087817] postgresql-pre-start[693]: running bootstrap script ... ok1715server # [ 15.587945] postgresql-pre-start[693]: performing post-bootstrap initialization ... ok1716server # [ 15.742195] postgresql-pre-start[693]: syncing data to disk ... ok1717server # [ 15.744324] postgresql-pre-start[693]: initdb: warning: enabling "trust" authentication for local connections1718server # [ 15.745613] postgresql-pre-start[693]: 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.1719server # [ 15.747581] postgresql-pre-start[693]: Success. You can now start the database server using:1720server # [ 15.749203] postgresql-pre-start[693]: pg_ctl -D /var/lib/postgresql/18 -l logfile start1721server # [ 15.840514] postgres[799]: [799] LOG: starting PostgreSQL 18.6 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit1722server # [ 15.842787] postgres[799]: [799] LOG: listening on IPv6 address "::1", port 54321723server # [ 15.843962] postgres[799]: [799] LOG: listening on IPv4 address "127.0.0.1", port 54321724server # [ 15.847073] postgres[799]: [799] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1725server # [ 15.857435] postgres[808]: [808] LOG: database system was shut down at 2026-09-24 18:05:08 GMT1726server # [ 15.864211] postgres[799]: [799] LOG: database system is ready to accept connections1727server # [ 15.868928] systemd[1]: Started PostgreSQL Server.1728server # [ 15.876709] systemd[1]: Starting PostgreSQL Setup Scripts...1729server # [ 16.044834] postgresql-setup-start[819]: CREATE DATABASE1730server # [ 16.082838] postgresql-setup-start[824]: CREATE ROLE1731server # [ 16.098473] postgresql-setup-start[826]: ALTER DATABASE1732server # [ 16.103831] systemd[1]: Finished PostgreSQL Setup Scripts.1733server # [ 16.105980] systemd[1]: Reached target PostgreSQL.1734server: (finished: waiting for unit postgresql.service, in 16.70 seconds)1735server: waiting for unit rustfs.service1736server: (finished: waiting for unit rustfs.service, in 0.04 seconds)1737server: waiting for unit rustfs-setup.service1738builder # [ 18.311031] dhcpcd[621]: eth0: leased 10.0.2.15 for 86400 seconds1739builder # [ 18.314256] dhcpcd[621]: eth0: adding route to 10.0.2.0/241740builder # [ 18.318630] dhcpcd[621]: eth0: adding default route via 10.0.2.21741builder # [ 18.450738] systemd[1]: Started DHCP Client.1742builder # [ 18.454079] systemd[1]: Reached target Multi-User System.1743builder # [ 18.455776] systemd[1]: Startup finished in 933ms (kernel) + 4.363s (initrd) + 13.158s (userspace) = 18.455s.1744server # [ 19.778014] dhcpcd[612]: eth0: leased 10.0.2.15 for 86400 seconds1745server # [ 19.781027] dhcpcd[612]: eth0: adding route to 10.0.2.0/241746server # [ 19.783329] dhcpcd[612]: eth0: adding default route via 10.0.2.21747server # [ 19.921269] systemd[1]: Started DHCP Client.1748server # [ 31.974664] rustfs-setup-start[962]: mb s3://niks3-test1749server # [ 31.991355] systemd[1]: Finished Setup RustFS bucket.1750server # [ 32.001515] systemd[1]: Starting niks3 server...1751server # [ 32.171596] niks3-server[971]: 2026/09/24 18:05:24 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:8080/oidc1752server # [ 32.177890] niks3-server[971]: 2026/09/24 18:05:24 INFO OIDC authentication enabled config=/nix/store/z019gvf710g6d8396v9cvsyidwg4d4yk-niks3-oidc.json1753server # [ 32.179657] niks3-server[971]: 2026/09/24 18:05:24 INFO Loaded signing key name=niks3-test-1 path=/nix/store/6jgpl7z0sjpvlynzpcik37wwz06qi4np-niks3-signing-key1754server # [ 32.199916] niks3-server[971]: 2026/09/24 18:05:24 INFO Using socket-activated listener address=0.0.0.0:57511755server # [ 32.205354] niks3-server[971]: 2026/09/24 18:05:24 INFO Using socket-activated listener address=/run/niks3/proxy.sock1756server # [ 32.206746] niks3-server[971]: 2026/09/24 18:05:24 INFO systemd watchdog enabled interval=15s1757server # [ 32.207830] niks3-server[971]: 2026/09/24 18:05:24 INFO Starting HTTP server address=0.0.0.0:57511758server # [ 32.209564] niks3-server[971]: 2026/09/24 18:05:24 INFO Starting HTTP server address=/run/niks3/proxy.sock1759server # [ 32.211153] systemd[1]: Started niks3 server.1760server # [ 32.211796] systemd[1]: Reached target Multi-User System.1761server # [ 32.212859] systemd[1]: Startup finished in 945ms (kernel) + 4.306s (initrd) + 26.952s (userspace) = 32.205s.1762server: (finished: waiting for unit rustfs-setup.service, in 16.02 seconds)1763server: waiting for unit mock-oidc.service1764server: (finished: waiting for unit mock-oidc.service, in 0.05 seconds)1765server: waiting for unit niks3.service1766server: (finished: waiting for unit niks3.service, in 0.04 seconds)1767server: waiting for TCP port 5751 on localhost1768server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded!1769server: (finished: waiting for TCP port 5751 on localhost, in 0.03 seconds)1770server: waiting for TCP port 8080 on localhost1771server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded!1772server: (finished: waiting for TCP port 8080 on localhost, in 0.02 seconds)1773server: waiting for TCP port 9000 on localhost1774server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded!1775server: (finished: waiting for TCP port 9000 on localhost, in 0.02 seconds)1776server: must succeed: mkdir -p /tmp/test-config1777server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.01 seconds)1778server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token1779server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.01 seconds)1780server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31781server # [ 32.794832] niks3-server[971]: 2026/09/24 18:05:25 INFO Received push request method=POST path=/api/pushes1782server # time=2026-09-24T18:05:25.433Z level=INFO msg="Uploading 5 paths to server (0 already cached)"1783server # time=2026-09-24T18:05:25.434Z level=INFO msg="Uploading m54cs0m994hc3n9lax7sg98sgy729qsa-glibc-2.42-84 (44.4MB)"1784server # time=2026-09-24T18:05:25.436Z level=INFO msg="Uploading waax852balprvsvdziyaf1h7r3ldvakg-xgcc-15.3.0-libgcc (150.1KB)"1785server # time=2026-09-24T18:05:25.438Z level=INFO msg="Uploading 0s40b0an0cz4vypbji92cb7qbr65icl4-libidn2-2.3.8 (366.1KB)"1786server # time=2026-09-24T18:05:25.440Z level=INFO msg="Uploading h0hd048jzxs7fx9a6g75jwrrhbg50bp8-libunistring-1.4.2 (2.0MB)"1787server # time=2026-09-24T18:05:25.442Z level=INFO msg="Uploading kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 (287.5KB)"1788server # [ 32.952332] niks3-server[971]: 2026/09/24 18:05:25 INFO Registered completed upload object_key=nar/0x48iv4crw96wayixvwmbhz9znvm9djawdf9j0wszdqdxpn92kw1.nar.zst1789server # [ 32.972932] niks3-server[971]: 2026/09/24 18:05:25 INFO Registered completed upload object_key=waax852balprvsvdziyaf1h7r3ldvakg.ls1790server # [ 32.983887] niks3-server[971]: 2026/09/24 18:05:25 INFO Registered completed upload object_key=h0hd048jzxs7fx9a6g75jwrrhbg50bp8.ls1791server # [ 33.004631] niks3-server[971]: 2026/09/24 18:05:25 INFO Registered completed upload object_key=nar/07lydviym1nzmxy9y72apc8lgzy9v6x110r4vxac3kk2xslg0gcg.nar.zst1792server # [ 33.047726] niks3-server[971]: 2026/09/24 18:05:25 INFO Registered completed upload object_key=nar/16z5dldwcmmhv0ij0pz8h0z98mv285jvqw0ly64fsg0s9lbffamb.nar.zst1793server # [ 33.060345] niks3-server[971]: 2026/09/24 18:05:25 INFO Registered completed upload object_key=kwhxkl8yn5y8wqiq11jsybagw3fbc4iv.ls1794server # [ 33.101727] niks3-server[971]: 2026/09/24 18:05:25 INFO Registered completed upload object_key=0s40b0an0cz4vypbji92cb7qbr65icl4.ls1795server # [ 33.108822] niks3-server[971]: 2026/09/24 18:05:25 INFO Registered completed upload object_key=nar/0rbj231dr19n59qcahf76cdwmqlqv239j85j94g8c89kf9kf5p04.nar.zst1796server # [ 33.746158] niks3-server[971]: 2026/09/24 18:05:26 INFO Registered completed upload object_key=m54cs0m994hc3n9lax7sg98sgy729qsa.ls1797server # [ 33.774219] niks3-server[971]: 2026/09/24 18:05:26 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1798server # [ 33.788792] niks3-server[971]: 2026/09/24 18:05:26 INFO Completed multipart upload object_key=nar/1vzbqdhwmbhwll0g4bniam5qjj71a1ci2n6lc100n2yfpbpygw8c.nar.zst upload_id=YThiYTA1Y2ItZjM3OC00ZDhiLWFhNWQtMTEzMjlhYzhkYWNjLjE1YzJmMTgwLTYzNTItNDIyNy1hMDVkLWFkNzY2NzY5MjllMngxNzkwMjczMTI1NDI1NjMxNDAw parts=11799server # time=2026-09-24T18:05:26.417Z level=INFO msg="Uploading 5 narinfos"1800server # [ 33.796572] niks3-server[971]: 2026/09/24 18:05:26 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign1801server # [ 33.800793] niks3-server[971]: 2026/09/24 18:05:26 INFO Signed narinfos id=1 count=51802server # [ 33.825507] niks3-server[971]: 2026/09/24 18:05:26 INFO Registered completed upload object_key=h0hd048jzxs7fx9a6g75jwrrhbg50bp8.narinfo1803server # [ 33.834345] niks3-server[971]: 2026/09/24 18:05:26 INFO Received complete push request method=POST path=/api/pushes/1/complete1804server # time=2026-09-24T18:05:26.474Z level=INFO msg="Upload complete. (1.11s)"1805server # [ 33.859409] niks3-server[971]: 2026/09/24 18:05:26 INFO Registered completed upload object_key=m54cs0m994hc3n9lax7sg98sgy729qsa.narinfo1806server # [ 33.872558] niks3-server[971]: 2026/09/24 18:05:26 INFO Registered completed upload object_key=waax852balprvsvdziyaf1h7r3ldvakg.narinfo1807server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 1.24 seconds)1808server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token1809server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.01 seconds)1810server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31811server # [ 33.969962] niks3-server[971]: 2026/09/24 18:05:26 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]1812server # [ 34.018563] niks3-server[971]: 2026/09/24 18:05:26 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]1813server # time=2026-09-24T18:05:26.643Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"1814server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.13 seconds)1815server: waiting for unit nginx.service1816server: (finished: waiting for unit nginx.service, in 0.03 seconds)1817server: waiting for TCP port 443 on localhost1818server # Connection to localhost (::1) 443 port [tcp/https] succeeded!1819server: (finished: waiting for TCP port 443 on localhost, in 0.02 seconds)1820server: must succeed: /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31821server # time=2026-09-24T18:05:26.753Z 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.pem1822server # time=2026-09-24T18:05:26.765Z level=INFO msg="All 1 paths already cached"1823server: (finished: must succeed: /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.07 seconds)1824server: must fail: curl -sf -X POST -H 'X-SSL-Client-Verify: SUCCESS' -H 'X-SSL-Client-Dn: CN=niks3 test client' -d '{}' http://127.0.0.1:5751/api/pending_closures1825server: (finished: must fail: curl -sf -X POST -H 'X-SSL-Client-Verify: SUCCESS' -H 'X-SSL-Client-Dn: CN=niks3 test client' -d '{}' http://127.0.0.1:5751/api/pending_closures, in 0.03 seconds)1826server: must fail: /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31827server # time=2026-09-24T18:05:26.808Z 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)"1828server: (finished: must fail: /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/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)1829server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31830server # time=2026-09-24T18:05:26.871Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem1831server # time=2026-09-24T18:05:26.879Z level=INFO msg="All 1 paths already cached"1832server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/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)1833server: 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'1834server # -----1835server: (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)1836server: 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.pem1837server # Certificate request self-signature ok1838server # subject=CN=other client1839server: (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)1840server: must fail: /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31841server # time=2026-09-24T18:05:27.002Z 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.pem1842server # [ 34.388614] niks3-server[971]: 2026/09/24 18:05:27 WARN mTLS auth: subject not in bound subjects subject="CN=other client"1843server # [ 34.440904] niks3-server[971]: 2026/09/24 18:05:27 WARN mTLS auth: subject not in bound subjects subject="CN=other client"1844server # time=2026-09-24T18:05:27.065Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"1845server: (finished: must fail: /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.13 seconds)1846server: must succeed: mkdir -p /tmp/test-store1847server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.02 seconds)1848server: must succeed: 1849 export AWS_ACCESS_KEY_ID=rustfsadmin1850export AWS_SECRET_ACCESS_KEY=rustfsadmin1851 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.318521853server # copying 5 paths...1854server # copying path '/nix/store/h0hd048jzxs7fx9a6g75jwrrhbg50bp8-libunistring-1.4.2' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1855server # copying path '/nix/store/waax852balprvsvdziyaf1h7r3ldvakg-xgcc-15.3.0-libgcc' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1856server # copying path '/nix/store/0s40b0an0cz4vypbji92cb7qbr65icl4-libidn2-2.3.8' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1857server # copying path '/nix/store/m54cs0m994hc3n9lax7sg98sgy729qsa-glibc-2.42-84' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1858server # copying path '/nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1859server: (finished: must succeed: 1860 export AWS_ACCESS_KEY_ID=rustfsadmin1861export AWS_SECRET_ACCESS_KEY=rustfsadmin1862 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31863, in 0.54 seconds)1864server: must succeed: 1865cat > /tmp/test-drv.nix << 'EOF'1866derivation {1867 name = "test-build-log";1868 system = builtins.currentSystem;1869 builder = "/bin/sh";1870 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];1871}1872EOF18731874server: (finished: must succeed: 1875cat > /tmp/test-drv.nix << 'EOF'1876derivation {1877 name = "test-build-log";1878 system = builtins.currentSystem;1879 builder = "/bin/sh";1880 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];1881}1882EOF1883, in 0.02 seconds)1884server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix1885server # this derivation will be built:1886server # /nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv1887server # building '/nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv'...1888server # test-build-log> test build log output1889server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.19 seconds)1890server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1891server # [ 35.333281] niks3-server[971]: 2026/09/24 18:05:27 INFO Received push request method=POST path=/api/pushes1892server # time=2026-09-24T18:05:27.971Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1893server # time=2026-09-24T18:05:27.972Z level=INFO msg="Uploading z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log (128B)"1894server # [ 35.373988] niks3-server[971]: 2026/09/24 18:05:27 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign1895server # time=2026-09-24T18:05:28.001Z level=INFO msg="Uploading 1 narinfos"1896server # [ 35.382210] niks3-server[971]: 2026/09/24 18:05:28 INFO Signed narinfos id=2 count=11897server # [ 35.389087] niks3-server[971]: 2026/09/24 18:05:28 INFO Registered completed upload object_key=log/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv1898server # [ 35.393580] niks3-server[971]: 2026/09/24 18:05:28 INFO Registered completed upload object_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.ls1899server # [ 35.396919] niks3-server[971]: 2026/09/24 18:05:28 INFO Registered completed upload object_key=nar/00zns3gj9hwz2a4b0i07y7nmxybq59lh24bl3xsxblcl6333mjil.nar.zst1900server # [ 35.404888] niks3-server[971]: 2026/09/24 18:05:28 INFO Received complete push request method=POST path=/api/pushes/2/complete1901server # time=2026-09-24T18:05:28.031Z level=INFO msg="Upload complete. (126ms)"1902server # [ 35.411034] niks3-server[971]: 2026/09/24 18:05:28 INFO Registered completed upload object_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.narinfo1903server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log, in 0.21 seconds)1904server: must succeed: 1905 export AWS_ACCESS_KEY_ID=rustfsadmin1906export AWS_SECRET_ACCESS_KEY=rustfsadmin1907 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log19081909server # got build log for '/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'1910server: (finished: must succeed: 1911 export AWS_ACCESS_KEY_ID=rustfsadmin1912export AWS_SECRET_ACCESS_KEY=rustfsadmin1913 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1914, in 0.12 seconds)1915subtest: push --stdin streams paths and reports each one1916server: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }'1917server # this derivation will be built:1918server # /nix/store/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv1919server # building '/nix/store/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv'...1920server: (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.16 seconds)1921server: 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/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --stdin1922server # [ 35.813832] niks3-server[971]: 2026/09/24 18:05:28 INFO Received push request method=POST path=/api/pushes1923server # time=2026-09-24T18:05:28.441Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1924server # time=2026-09-24T18:05:28.442Z level=INFO msg="Uploading 7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test (120B)"1925server # [ 35.841639] niks3-server[971]: 2026/09/24 18:05:28 INFO Registered completed upload object_key=7j8147s8h4si9jmv6bxa86ql7z6j2m37.ls1926server # time=2026-09-24T18:05:28.467Z level=INFO msg="Uploading 1 narinfos"1927server # [ 35.848272] niks3-server[971]: 2026/09/24 18:05:28 INFO Received sign narinfos request method=POST path=/api/pushes/3/sign1928server # [ 35.852794] niks3-server[971]: 2026/09/24 18:05:28 INFO Signed narinfos id=3 count=11929server # [ 35.853850] niks3-server[971]: 2026/09/24 18:05:28 INFO Registered completed upload object_key=log/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv1930server # [ 35.859509] niks3-server[971]: 2026/09/24 18:05:28 INFO Registered completed upload object_key=nar/1hj1r0g515rfxjx685mfn3dgr6akqbn4gf64snlv6bxaxph69lna.nar.zst1931server # [ 35.867556] niks3-server[971]: 2026/09/24 18:05:28 INFO Received complete push request method=POST path=/api/pushes/3/complete1932server # time=2026-09-24T18:05:28.494Z level=INFO msg="Upload complete. (110ms)"1933server # [ 35.873558] niks3-server[971]: 2026/09/24 18:05:28 INFO Registered completed upload object_key=7j8147s8h4si9jmv6bxa86ql7z6j2m37.narinfo1934server: (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/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --stdin, in 0.18 seconds)1935server: must succeed: 1936 export AWS_ACCESS_KEY_ID=rustfsadmin1937export AWS_SECRET_ACCESS_KEY=rustfsadmin1938 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test1939 1940server # copying 1 paths...1941server # copying path '/nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1942server: (finished: must succeed: 1943 export AWS_ACCESS_KEY_ID=rustfsadmin1944export AWS_SECRET_ACCESS_KEY=rustfsadmin1945 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test1946 , in 0.14 seconds)1947(finished: subtest: push --stdin streams paths and reports each one, in 0.49 seconds)1948server: must succeed: 1949cat > /tmp/ca-test.nix << 'EOF'1950derivation {1951 name = "ca-test";1952 system = builtins.currentSystem;1953 builder = "/bin/sh";1954 args = [ "-c" "echo 'Hello from CA derivation' > $out" ];1955 __contentAddressed = true;1956 outputHashMode = "recursive";1957 outputHashAlgo = "sha256";1958}1959EOF19601961server: (finished: must succeed: 1962cat > /tmp/ca-test.nix << 'EOF'1963derivation {1964 name = "ca-test";1965 system = builtins.currentSystem;1966 builder = "/bin/sh";1967 args = [ "-c" "echo 'Hello from CA derivation' > $out" ];1968 __contentAddressed = true;1969 outputHashMode = "recursive";1970 outputHashAlgo = "sha256";1971}1972EOF1973, in 0.02 seconds)1974server: must succeed: nix-build --log-format bar-with-logs /tmp/ca-test.nix --no-out-link1975server # this derivation will be built:1976server # /nix/store/8j226mdc8yhan5fv0s31bbzx0iiny3pa-ca-test.drv1977server # building '/nix/store/8j226mdc8yhan5fv0s31bbzx0iiny3pa-ca-test.drv'...1978server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/ca-test.nix --no-out-link, in 0.16 seconds)1979server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test1980server # [ 36.365051] niks3-server[971]: 2026/09/24 18:05:28 INFO Received push request method=POST path=/api/pushes1981server # time=2026-09-24T18:05:28.992Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1982server # time=2026-09-24T18:05:28.993Z level=INFO msg="Uploading 5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test (144B)"1983server # [ 36.388278] niks3-server[971]: 2026/09/24 18:05:29 INFO Registered completed upload object_key=nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1984server # [ 36.396930] niks3-server[971]: 2026/09/24 18:05:29 INFO Received sign narinfos request method=POST path=/api/pushes/4/sign1985server # time=2026-09-24T18:05:29.022Z level=INFO msg="Uploading 1 narinfos"1986server # [ 36.404002] niks3-server[971]: 2026/09/24 18:05:29 INFO Signed narinfos id=4 count=11987server # [ 36.405209] niks3-server[971]: 2026/09/24 18:05:29 INFO Registered completed upload object_key=5v8l1dc99hcg2ss10dgkivlhwbkpppjh.ls1988server # [ 36.409854] niks3-server[971]: 2026/09/24 18:05:29 INFO Registered completed upload object_key=log/8j226mdc8yhan5fv0s31bbzx0iiny3pa-ca-test.drv1989server # [ 36.415513] niks3-server[971]: 2026/09/24 18:05:29 INFO Received complete push request method=POST path=/api/pushes/4/complete1990server # time=2026-09-24T18:05:29.040Z level=INFO msg="Upload complete. (150ms)"1991server # [ 36.422420] niks3-server[971]: 2026/09/24 18:05:29 INFO Registered completed upload object_key=5v8l1dc99hcg2ss10dgkivlhwbkpppjh.narinfo1992server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.22 seconds)1993server: must succeed: mkdir -p /tmp/chroot-store1994server: (finished: must succeed: mkdir -p /tmp/chroot-store, in 0.02 seconds)1995server: must succeed: 1996 export AWS_ACCESS_KEY_ID=rustfsadmin1997export AWS_SECRET_ACCESS_KEY=rustfsadmin1998 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/chroot-store /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test19992000server # copying 1 paths...2001server # copying path '/nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2002server: (finished: must succeed: 2003 export AWS_ACCESS_KEY_ID=rustfsadmin2004export AWS_SECRET_ACCESS_KEY=rustfsadmin2005 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/chroot-store /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2006, in 0.14 seconds)2007server: must succeed: nix --store /tmp/chroot-store store cat /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2008server: (finished: must succeed: nix --store /tmp/chroot-store store cat /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.06 seconds)2009server: must succeed: nix --store /tmp/chroot-store realisation info /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2010server # warning: 'realisation' is a deprecated alias for 'store build-trace'2011server: (finished: must succeed: nix --store /tmp/chroot-store realisation info /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.06 seconds)2012server: must succeed: readlink /etc/niks3-test/symlink-wrapper2013server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.02 seconds)2014server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper2015server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.01 seconds)2016server: must succeed: test -L /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper2017server: (finished: must succeed: test -L /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper, in 0.01 seconds)2018server: must succeed: readlink /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper2019server: (finished: must succeed: readlink /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper, in 0.01 seconds)2020server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper2021server # [ 36.869566] niks3-server[971]: 2026/09/24 18:05:29 INFO Received push request method=POST path=/api/pushes2022server # time=2026-09-24T18:05:29.496Z level=INFO msg="Uploading 2 paths to server (0 already cached)"2023server # time=2026-09-24T18:05:29.497Z level=INFO msg="Uploading xafv64bljflg1v8hnf22lyk0w4gma3v5-base-package (536B)"2024server # time=2026-09-24T18:05:29.499Z level=INFO msg="Uploading 0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper (192B)"2025server # [ 36.896332] niks3-server[971]: 2026/09/24 18:05:29 INFO Registered completed upload object_key=0caxmbk1mdnhh6zbyh0sz4faqqy5f08r.ls2026server # [ 36.907774] niks3-server[971]: 2026/09/24 18:05:29 INFO Registered completed upload object_key=xafv64bljflg1v8hnf22lyk0w4gma3v5.ls2027server # time=2026-09-24T18:05:29.534Z level=INFO msg="Uploading 2 narinfos"2028server # [ 36.914398] niks3-server[971]: 2026/09/24 18:05:29 INFO Registered completed upload object_key=nar/1ncc9lqll9lyam30m8vdy4yyaym0q7sps47afadhzvyqsw833fy8.nar.zst2029server # [ 36.919555] niks3-server[971]: 2026/09/24 18:05:29 INFO Received sign narinfos request method=POST path=/api/pushes/5/sign2030server # [ 36.922646] niks3-server[971]: 2026/09/24 18:05:29 INFO Signed narinfos id=5 count=22031server # [ 36.926172] niks3-server[971]: 2026/09/24 18:05:29 INFO Registered completed upload object_key=nar/0820av37plmfgrxw6pcbym6phk1sv14dilyrablrnkzb7z1fd5js.nar.zst2032server # [ 36.931182] niks3-server[971]: 2026/09/24 18:05:29 INFO Received complete push request method=POST path=/api/pushes/5/complete2033server # time=2026-09-24T18:05:29.560Z level=INFO msg="Upload complete. (120ms)"2034server # [ 36.940424] niks3-server[971]: 2026/09/24 18:05:29 INFO Registered completed upload object_key=xafv64bljflg1v8hnf22lyk0w4gma3v5.narinfo2035server # [ 36.944170] niks3-server[971]: 2026/09/24 18:05:29 INFO Registered completed upload object_key=0caxmbk1mdnhh6zbyh0sz4faqqy5f08r.narinfo2036server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper, in 0.19 seconds)2037server: must succeed: 2038 export AWS_ACCESS_KEY_ID=rustfsadmin2039export AWS_SECRET_ACCESS_KEY=rustfsadmin2040 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper20412042server # copying 2 paths...2043server # copying path '/nix/store/xafv64bljflg1v8hnf22lyk0w4gma3v5-base-package' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2044server # copying path '/nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2045server: (finished: must succeed: 2046 export AWS_ACCESS_KEY_ID=rustfsadmin2047export AWS_SECRET_ACCESS_KEY=rustfsadmin2048 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper2049, in 0.14 seconds)2050server: must succeed: 2051cat > /tmp/oidc-test.nix << 'EOF'2052derivation {2053 name = "oidc-test";2054 system = builtins.currentSystem;2055 builder = "/bin/sh";2056 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2057}2058EOF20592060server: (finished: must succeed: 2061cat > /tmp/oidc-test.nix << 'EOF'2062derivation {2063 name = "oidc-test";2064 system = builtins.currentSystem;2065 builder = "/bin/sh";2066 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2067}2068EOF2069, in 0.02 seconds)2070server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link2071server # this derivation will be built:2072server # /nix/store/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv2073server # building '/nix/store/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv'...2074server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link, in 0.16 seconds)2075server: 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'2076server: (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.03 seconds)2077server: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6InkzUzJ3d2Q3bzBoWkE2ZDFVSWZxd1ozUUdYRHdMNVF4XzVZcEJzUjRRRkUiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTAyNzY3MjksImlhdCI6MTc5MDI3MzEyOSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.CNK8lVfw_DiM0D_vJ8oj15-Vm-CqNREwq1UajUWw4Bz6e2LRKW0_NEmP2eWCcXQ7ELZqKNY31fU7S9n_6VP-HFK0MM--aM8gz6El9OzEfJdDrp4rAkUZ4KShnoIJOI-nk3VhBfYXqEhAqfzEqJr5eGH6DKuINA98AC8BrExMPpmF3TYCtdF2HYOnkdGcuYdW-pU9N11Yq9WkvvCuuDm36QGMxgEuc1zQ3zjhgdCJDum0B1FxoIGp0U2UivNWOCoXWl3CUQj7oCaavMq6DgIzMD0ihJGfEc7OliWRw6KO3aFdqt_JRaJCPG8YvOO7IJN0AV-ijyolgCJwjJdc-8igbw' /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test2078server # time=2026-09-24T18:05:29.936Z 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"2079server # [ 37.418358] niks3-server[971]: 2026/09/24 18:05:30 INFO Received push request method=POST path=/api/pushes2080server # time=2026-09-24T18:05:30.044Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2081server # time=2026-09-24T18:05:30.045Z level=INFO msg="Uploading x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test (136B)"2082server # [ 37.446185] niks3-server[971]: 2026/09/24 18:05:30 INFO Registered completed upload object_key=x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj.ls2083server # time=2026-09-24T18:05:30.072Z level=INFO msg="Uploading 1 narinfos"2084server # [ 37.452949] niks3-server[971]: 2026/09/24 18:05:30 INFO Received sign narinfos request method=POST path=/api/pushes/6/sign2085server # [ 37.458789] niks3-server[971]: 2026/09/24 18:05:30 INFO Signed narinfos id=6 count=12086server # [ 37.459835] niks3-server[971]: 2026/09/24 18:05:30 INFO Registered completed upload object_key=nar/17h0qgwf5334wia961ibqy9hmpsyvfhrvj5ji9m031klp6sx4v0q.nar.zst2087server # [ 37.465552] niks3-server[971]: 2026/09/24 18:05:30 INFO Registered completed upload object_key=log/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv2088server # [ 37.471721] niks3-server[971]: 2026/09/24 18:05:30 INFO Received complete push request method=POST path=/api/pushes/6/complete2089server # time=2026-09-24T18:05:30.097Z level=INFO msg="Upload complete. (109ms)"2090server # [ 37.476831] niks3-server[971]: 2026/09/24 18:05:30 INFO Registered completed upload object_key=x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj.narinfo2091server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6InkzUzJ3d2Q3bzBoWkE2ZDFVSWZxd1ozUUdYRHdMNVF4XzVZcEJzUjRRRkUiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTAyNzY3MjksImlhdCI6MTc5MDI3MzEyOSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.CNK8lVfw_DiM0D_vJ8oj15-Vm-CqNREwq1UajUWw4Bz6e2LRKW0_NEmP2eWCcXQ7ELZqKNY31fU7S9n_6VP-HFK0MM--aM8gz6El9OzEfJdDrp4rAkUZ4KShnoIJOI-nk3VhBfYXqEhAqfzEqJr5eGH6DKuINA98AC8BrExMPpmF3TYCtdF2HYOnkdGcuYdW-pU9N11Yq9WkvvCuuDm36QGMxgEuc1zQ3zjhgdCJDum0B1FxoIGp0U2UivNWOCoXWl3CUQj7oCaavMq6DgIzMD0ihJGfEc7OliWRw6KO3aFdqt_JRaJCPG8YvOO7IJN0AV-ijyolgCJwjJdc-8igbw' /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test, in 0.18 seconds)2092server: must succeed: 2093cat > /tmp/oidc-test2.nix << 'EOF'2094derivation {2095 name = "oidc-test2";2096 system = builtins.currentSystem;2097 builder = "/bin/sh";2098 args = [ "-c" "echo 'OIDC test 2' > $out" ];2099}2100EOF21012102server: (finished: must succeed: 2103cat > /tmp/oidc-test2.nix << 'EOF'2104derivation {2105 name = "oidc-test2";2106 system = builtins.currentSystem;2107 builder = "/bin/sh";2108 args = [ "-c" "echo 'OIDC test 2' > $out" ];2109}2110EOF2111, in 0.02 seconds)2112server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link2113server # this derivation will be built:2114server # /nix/store/ldlfjwl576hkm4b5ag3b447nnn59cwiw-oidc-test2.drv2115server # building '/nix/store/ldlfjwl576hkm4b5ag3b447nnn59cwiw-oidc-test2.drv'...2116server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link, in 0.16 seconds)2117server: 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'2118server: (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.03 seconds)2119server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6InkzUzJ3d2Q3bzBoWkE2ZDFVSWZxd1ozUUdYRHdMNVF4XzVZcEJzUjRRRkUiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTAyNzY3MzAsImlhdCI6MTc5MDI3MzEzMCwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.BhJPVY6HW_zzyqIQAxlfOfJubOgDucVOLbWcdT4sYvsBPa18S9okqLrrzkzAUgj1NU_I03BgsR3-T9c9cCryXz_SNrhJ6GD80b3sU-ERk7BHWHz1jRxylLli-ZHhoGxjkWQ-ogcn49AyYeMbTJF1eZtr9IYti-KpnGY_FP9cX6TZ21nnsJS7w10I4rEGMF8H7y11cWBHpBsTVkcFUIu077JrbZq8WWUC6-hW32u-K1xMcBUnkCnhQ_D8adcEoNgaOYdaAzCzOhsBPs1VS3zeWSTlGzOLb_z3ncujFFxwvcp4Vs4S3nMQjQnAwzVTzS-nbAVtFcxUH_u4TP59u4BK-w' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22120server # time=2026-09-24T18:05:30.331Z 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"2121server # [ 37.763251] niks3-server[971]: 2026/09/24 18:05:30 WARN Authentication failed token_preview=eyJhbGciOi...TP59u4BK-w token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2122server # [ 37.814938] niks3-server[971]: 2026/09/24 18:05:30 WARN Authentication failed token_preview=eyJhbGciOi...TP59u4BK-w token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2123server # time=2026-09-24T18:05:30.440Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2124server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6InkzUzJ3d2Q3bzBoWkE2ZDFVSWZxd1ozUUdYRHdMNVF4XzVZcEJzUjRRRkUiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTAyNzY3MzAsImlhdCI6MTc5MDI3MzEzMCwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.BhJPVY6HW_zzyqIQAxlfOfJubOgDucVOLbWcdT4sYvsBPa18S9okqLrrzkzAUgj1NU_I03BgsR3-T9c9cCryXz_SNrhJ6GD80b3sU-ERk7BHWHz1jRxylLli-ZHhoGxjkWQ-ogcn49AyYeMbTJF1eZtr9IYti-KpnGY_FP9cX6TZ21nnsJS7w10I4rEGMF8H7y11cWBHpBsTVkcFUIu077JrbZq8WWUC6-hW32u-K1xMcBUnkCnhQ_D8adcEoNgaOYdaAzCzOhsBPs1VS3zeWSTlGzOLb_z3ncujFFxwvcp4Vs4S3nMQjQnAwzVTzS-nbAVtFcxUH_u4TP59u4BK-w' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.13 seconds)2125server: 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'2126server: (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)2127server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6InkzUzJ3d2Q3bzBoWkE2ZDFVSWZxd1ozUUdYRHdMNVF4XzVZcEJzUjRRRkUiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MDI3NjczMCwiaWF0IjoxNzkwMjczMTMwLCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.OV5WUepcwwT2cjK9_XjwpfwCtYxOuczgPFDhRyc-dNyVgDSKJbe3iY8uiIN5Z9gIMF7aVZ_LhXtd5Ok_5mBzyQH62I-cxqaiRRzNtZfuq1aDojQ-cfkXHTnmavy7gmSWkIHjjzCCdqF18tu-bel7Lp44m8VLVKEhWSJ3Umk3IHDiDpHKFwXvvN_Q3JyKrKH2zePU0WQ8cKuyU6uqQoIxHDM7BFvzARqwHcyAdaDZ8h-fQ-Am7U-5kW48pSv57dz8MQDrmbvLSIU3hajGFOBlOa7ApMcVIEWdAr48NkeHNryZJ6Tq9msTFonfV_jl1xnNscsXXMfhNiPWkO68cw7vcQ' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22128server # time=2026-09-24T18:05:30.497Z 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"2129server # [ 37.959884] niks3-server[971]: 2026/09/24 18:05:30 WARN Authentication failed token_preview=eyJhbGciOi...kO68cw7vcQ token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2130server # [ 38.009952] niks3-server[971]: 2026/09/24 18:05:30 WARN Authentication failed token_preview=eyJhbGciOi...kO68cw7vcQ token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2131server # time=2026-09-24T18:05:30.635Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2132server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6InkzUzJ3d2Q3bzBoWkE2ZDFVSWZxd1ozUUdYRHdMNVF4XzVZcEJzUjRRRkUiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MDI3NjczMCwiaWF0IjoxNzkwMjczMTMwLCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.OV5WUepcwwT2cjK9_XjwpfwCtYxOuczgPFDhRyc-dNyVgDSKJbe3iY8uiIN5Z9gIMF7aVZ_LhXtd5Ok_5mBzyQH62I-cxqaiRRzNtZfuq1aDojQ-cfkXHTnmavy7gmSWkIHjjzCCdqF18tu-bel7Lp44m8VLVKEhWSJ3Umk3IHDiDpHKFwXvvN_Q3JyKrKH2zePU0WQ8cKuyU6uqQoIxHDM7BFvzARqwHcyAdaDZ8h-fQ-Am7U-5kW48pSv57dz8MQDrmbvLSIU3hajGFOBlOa7ApMcVIEWdAr48NkeHNryZJ6Tq9msTFonfV_jl1xnNscsXXMfhNiPWkO68cw7vcQ' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.17 seconds)2133server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22134server # time=2026-09-24T18:05:30.653Z 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"2135server # [ 38.087776] niks3-server[971]: 2026/09/24 18:05:30 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]2136server # [ 38.143293] niks3-server[971]: 2026/09/24 18:05:30 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]2137server # time=2026-09-24T18:05:30.768Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2138server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.13 seconds)2139server: must succeed: 2140 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins create hello-pin /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.321412142server # [ 38.221114] niks3-server[971]: 2026/09/24 18:05:30 INFO Received create pin request method=POST path=/api/pins/hello-pin2143server # time=2026-09-24T18:05:30.852Z level=INFO msg="Created pin" name=hello-pin store_path=/nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.32144server # [ 38.231713] niks3-server[971]: 2026/09/24 18:05:30 INFO Created/updated pin name=hello-pin store_path=/nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 narinfo_key=kwhxkl8yn5y8wqiq11jsybagw3fbc4iv.narinfo2145server: (finished: must succeed: 2146 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins create hello-pin /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.32147, in 0.09 seconds)2148server: must succeed: 2149 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins list21502151server # [ 38.311820] niks3-server[971]: 2026/09/24 18:05:30 INFO Received list pins request method=GET path=/api/pins2152server: (finished: must succeed: 2153 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins list2154, in 0.08 seconds)2155server: must succeed: 2156 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins list --names-only21572158server # [ 38.398572] niks3-server[971]: 2026/09/24 18:05:31 INFO Received list pins request method=GET path=/api/pins2159server: (finished: must succeed: 2160 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins list --names-only2161, in 0.09 seconds)2162server: must succeed: 2163 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins list --json21642165server # [ 38.482735] niks3-server[971]: 2026/09/24 18:05:31 INFO Received list pins request method=GET path=/api/pins2166server: (finished: must succeed: 2167 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins list --json2168, in 0.08 seconds)2169server: must succeed: 2170 export S3_ENDPOINT_URL=http://localhost:90002171 export AWS_ACCESS_KEY_ID=rustfsadmin2172 export AWS_SECRET_ACCESS_KEY=rustfsadmin2173 /nix/store/1mxif175wb0rn50pgaisvczshqv3mw1i-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin21742175server: (finished: must succeed: 2176 export S3_ENDPOINT_URL=http://localhost:90002177 export AWS_ACCESS_KEY_ID=rustfsadmin2178 export AWS_SECRET_ACCESS_KEY=rustfsadmin2179 /nix/store/1mxif175wb0rn50pgaisvczshqv3mw1i-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin2180, in 0.03 seconds)2181server: must succeed: 2182 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --pin ca-pin /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log21832184server # time=2026-09-24T18:05:31.206Z level=INFO msg="All 1 paths already cached"2185server # [ 38.586153] niks3-server[971]: 2026/09/24 18:05:31 INFO Received create pin request method=POST path=/api/pins/ca-pin2186server # time=2026-09-24T18:05:31.214Z level=INFO msg="Created pin" name=ca-pin store_path=/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2187server # [ 38.594093] niks3-server[971]: 2026/09/24 18:05:31 INFO Created/updated pin name=ca-pin store_path=/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log narinfo_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.narinfo2188server: (finished: must succeed: 2189 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 push --pin ca-pin /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2190, in 0.08 seconds)2191server: must succeed: 2192 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins list --names-only21932194server # [ 38.670924] niks3-server[971]: 2026/09/24 18:05:31 INFO Received list pins request method=GET path=/api/pins2195server: (finished: must succeed: 2196 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins list --names-only2197, in 0.08 seconds)2198server: must succeed: 2199 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins delete hello-pin22002201server # [ 38.739467] niks3-server[971]: 2026/09/24 18:05:31 INFO Received delete pin request method=DELETE path=/api/pins/hello-pin2202server # time=2026-09-24T18:05:31.370Z level=INFO msg="Deleted pin" name=hello-pin2203server # [ 38.749638] niks3-server[971]: 2026/09/24 18:05:31 INFO Deleted pin name=hello-pin2204server: (finished: must succeed: 2205 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins delete hello-pin2206, in 0.08 seconds)2207server: must succeed: 2208 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins list --names-only22092210server # [ 38.818532] niks3-server[971]: 2026/09/24 18:05:31 INFO Received list pins request method=GET path=/api/pins2211server: (finished: must succeed: 2212 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins list --names-only2213, in 0.07 seconds)2214server: must fail: 2215 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent22162217server # [ 38.886620] niks3-server[971]: 2026/09/24 18:05:31 INFO Received create pin request method=POST path=/api/pins/bad-pin2218server # time=2026-09-24T18:05:31.510Z level=ERROR msg="Fatal error" error="creating pin: server returned 400: invalid store path: invalid store path format: /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent\n"2219server: (finished: must fail: 2220 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/n7h0x6qfjdnxdwzb0mb1xgh2si9ilpjy-niks3-1.13.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent2221, in 0.07 seconds)2222server: must succeed: systemctl start niks3-gc.service2223server # [ 38.916726] systemd[1]: Starting niks3 garbage collection...2224server # [ 38.965684] niks3[1553]: time=2026-09-24T18:05:31.587Z level=INFO msg="Starting garbage collection" older-than=720h failed-uploads-older-than=6h force=false2225server # [ 38.969729] niks3-server[971]: 2026/09/24 18:05:31 INFO Starting cleanup of old closures method=DELETE path=/api/closures2226server # [ 38.973508] niks3-server[971]: 2026/09/24 18:05:31 INFO Aborted multipart uploads count=0 kept=02227server # [ 38.976172] niks3[1553]: time=2026-09-24T18:05:31.594Z level=INFO msg="Garbage collection started"2228server # [ 38.980415] niks3-server[971]: 2026/09/24 18:05:31 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=02229server # [ 38.987607] niks3-server[971]: 2026/09/24 18:05:31 INFO Vacuumed table table=pending_closures2230server # [ 38.991677] niks3-server[971]: 2026/09/24 18:05:31 INFO Vacuumed table table=pending_objects2231server # [ 38.995617] niks3-server[971]: 2026/09/24 18:05:31 INFO Vacuumed table table=multipart_uploads2232server # [ 38.998636] niks3-server[971]: 2026/09/24 18:05:31 INFO Vacuumed table table=closures2233server # [ 39.002613] niks3-server[971]: 2026/09/24 18:05:31 INFO Vacuumed table table=objects2234server # [ 40.975869] niks3[1553]: time=2026-09-24T18:05:33.596Z level=INFO msg="Garbage collection completed successfully" failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=02235server # [ 40.993810] systemd[1]: niks3-gc.service: Deactivated successfully.2236server # [ 40.998992] systemd[1]: Finished niks3 garbage collection.2237server # [ 41.005701] systemd[1]: niks3-gc.service: Consumed 39ms CPU time over 2.081s wall clock time, 2.5M memory peak, 1.2K incoming IP traffic, 823B outgoing IP traffic.2238server: (finished: must succeed: systemctl start niks3-gc.service, in 2.13 seconds)2239builder: waiting for unit niks3-auto-upload.socket2240builder: waiting for the VM to finish booting2241builder: Guest shell says: b'Spawning backdoor root shell...\n'2242builder: connected to guest root shell2243builder: (connecting took 0.00 seconds)2244builder: (finished: waiting for the VM to finish booting, in 0.00 seconds)2245builder: (finished: waiting for unit niks3-auto-upload.socket, in 0.08 seconds)2246builder: must succeed: test -S /run/niks3/upload-to-cache.sock2247builder: (finished: must succeed: test -S /run/niks3/upload-to-cache.sock, in 0.02 seconds)2248builder: must succeed: grep post-build-hook /etc/nix/nix.conf2249builder: (finished: must succeed: grep post-build-hook /etc/nix/nix.conf, in 0.03 seconds)2250builder: must succeed: 2251cat > /tmp/test-drv.nix << 'EOF'2252derivation {2253 name = "post-build-hook-test";2254 system = builtins.currentSystem;2255 builder = "/bin/sh";2256 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2257}2258EOF22592260builder: (finished: must succeed: 2261cat > /tmp/test-drv.nix << 'EOF'2262derivation {2263 name = "post-build-hook-test";2264 system = builtins.currentSystem;2265 builder = "/bin/sh";2266 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2267}2268EOF2269, in 0.02 seconds)2270builder: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link2271builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 96 ms (attempt 1/5)2272builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 9 ms (attempt 2/5)2273builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 115 ms (attempt 3/5)2274builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 278 ms (attempt 4/5)2275builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org2276builder # this derivation will be built:2277builder # /nix/store/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv2278builder # building '/nix/store/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv'...2279builder # [ 42.059860] systemd[1]: Started niks3 auto-upload daemon.2280builder # [ 42.189940] niks3-hook[797]: time=2026-09-24T18:05:34.825Z 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=0s2281builder # [ 42.197269] niks3-hook[797]: time=2026-09-24T18:05:34.833Z level=INFO msg="Upload queue status" pending=12282builder # [ 42.198592] niks3-hook[797]: time=2026-09-24T18:05:34.833Z level=INFO msg="Uploading batch" count=12283builder: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link, in 1.00 seconds)2284builder: waiting for unit niks3-auto-upload.service2285builder: (finished: waiting for unit niks3-auto-upload.service, in 0.08 seconds)2286??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2287 File "/nix/store/d3bsqqyiqphqrb0s8sivc5iq46djqzda-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392288builder: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive2289??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2290 File "/nix/store/d3bsqqyiqphqrb0s8sivc5iq46djqzda-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392291builder # [ 42.298826] systemd[1]: Started Nix Daemon.2292builder # [ 42.371055] nix-daemon[816]: accepted connection from pid 811, user root (trusted)2293builder # [ 42.386273] nix-daemon[816]: reaped child process 823, status = succeeded2294server # [ 42.347331] niks3-server[971]: 2026/09/24 18:05:34 INFO Received push request method=POST path=/api/pushes2295builder # [ 42.407342] niks3-hook[797]: time=2026-09-24T18:05:35.041Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2296builder # [ 42.408949] niks3-hook[797]: time=2026-09-24T18:05:35.041Z level=INFO msg="Uploading 1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test (144B)"2297server # [ 42.400678] niks3-server[971]: 2026/09/24 18:05:35 INFO Received sign narinfos request method=POST path=/api/pushes/7/sign2298builder # [ 42.455987] niks3-hook[797]: time=2026-09-24T18:05:35.091Z level=INFO msg="Uploading 1 narinfos"2299server # [ 42.414676] niks3-server[971]: 2026/09/24 18:05:35 INFO Signed narinfos id=7 count=12300server # [ 42.422277] niks3-server[971]: 2026/09/24 18:05:35 INFO Registered completed upload object_key=1qlca7drab1x7rccc3h1pw1fl9c0j7yx.ls2301server # [ 42.429429] niks3-server[971]: 2026/09/24 18:05:35 INFO Registered completed upload object_key=nar/1a0vfw93shmvlz6lac7d1lv82jxzfdswigh5s32hpvncf74i1i0y.nar.zst2302server # [ 42.441388] niks3-server[971]: 2026/09/24 18:05:35 INFO Registered completed upload object_key=log/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv2303server # [ 42.450438] niks3-server[971]: 2026/09/24 18:05:35 INFO Received complete push request method=POST path=/api/pushes/7/complete2304server # [ 42.453912] niks3-server[971]: 2026/09/24 18:05:35 INFO Registered completed upload object_key=1qlca7drab1x7rccc3h1pw1fl9c0j7yx.narinfo2305builder # [ 42.504395] niks3-hook[797]: time=2026-09-24T18:05:35.140Z level=INFO msg="Upload complete. (307ms)"2306builder # [ 47.198100] niks3-hook[797]: time=2026-09-24T18:05:39.833Z level=INFO msg="Idle timeout reached and queue is empty, shutting down"2307builder # [ 47.204685] niks3-hook[797]: time=2026-09-24T18:05:39.835Z level=INFO msg="niks3-hook serve stopped"2308builder # [ 47.218988] systemd[1]: niks3-auto-upload.service: Deactivated successfully.2309builder # [ 47.233830] systemd[1]: niks3-auto-upload.service: Consumed 165ms CPU time over 5.166s wall clock time, 19.6M memory peak, 68K written to disk, 6K incoming IP traffic, 9K outgoing IP traffic.2310builder: (finished: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive, in 5.33 seconds)2311server: must succeed: 2312 export AWS_ACCESS_KEY_ID=rustfsadmin2313export AWS_SECRET_ACCESS_KEY=rustfsadmin2314 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-test23152316server # copying 1 paths...2317server # copying path '/nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2318server: (finished: must succeed: 2319 export AWS_ACCESS_KEY_ID=rustfsadmin2320export AWS_SECRET_ACCESS_KEY=rustfsadmin2321 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-test2322, in 0.26 seconds)2323server: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test2324server: (finished: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test, in 0.07 seconds)2325(finished: run the VM test script, in 48.75 seconds)2326test script finished in 49.02s2327cleanup2328kill QemuMachine (pid 47)2329builder # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2330builder # [2026-09-24T18:05:40Z INFO virtiofsd] Client disconnected, shutting down2331builder # [2026-09-24T18:05:40Z INFO virtiofsd] Client disconnected, shutting down2332builder # [2026-09-24T18:05:40Z INFO virtiofsd] Client disconnected, shutting down2333kill QemuMachine (pid 48)2334server # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2335server # [2026-09-24T18:05:40Z INFO virtiofsd] Client disconnected, shutting down2336server # [2026-09-24T18:05:40Z INFO virtiofsd] Client disconnected, shutting down2337server # [2026-09-24T18:05:40Z INFO virtiofsd] Client disconnected, shutting down2338(finished: cleanup, in 0.54 seconds)2339additionally exposed symbols:2340 builder, server,2341 vlan1,2342 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_ssh2343Hello store path: /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.32344Test output path: /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2345CA derivation output path: /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2346Realisation info in chroot store: /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test23472348Symlink wrapper store path: /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper2349Symlink wrapper points to: /nix/store/xafv64bljflg1v8hnf22lyk0w4gma3v5-base-package/bin/test-program2350OIDC test store path: /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test2351Valid OIDC token obtained (length=677)2352OIDC push with valid token: SUCCESS2353Invalid OIDC token obtained (wrong org)2354OIDC push with wrong org: correctly rejected2355Wrong audience OIDC token obtained2356OIDC push with wrong audience: correctly rejected2357OIDC push with malformed token: correctly rejected2358All OIDC tests passed!2359All pin tests passed!2360Built store path: /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test2361Post-build-hook pipeline test passed!