vm-test-run-nixos-test-niks3
checks.aarch64-linux.nixos-test-niks3
· build #399
· 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.KjjPxhRjVu', fmt=raw size=107374182412builder: QEMU running (pid 47)13builder # mke2fs 1.47.4 (6-Mar-2025)14server # Disk image does not exist, creating the virtualisation disk image...15builder # Discarding device blocks: 0/262144 done16server # Formatting '/build/vm-state-server/tmp.QFnNk8F0AO', fmt=raw size=107374182417builder # Creating filesystem with 262144 4k blocks and 65536 inodes18server # mke2fs 1.47.4 (6-Mar-2025)19builder # Filesystem UUID: 357a2f32-0c41-4c35-a6f4-197a593c432e20server # Discarding device blocks: 0/262144 done21builder # Superblock backups stored on blocks:22server # Creating filesystem with 262144 4k blocks and 65536 inodes23builder # 32768, 98304, 163840, 22937624server # Filesystem UUID: 5efadf8a-aa45-4366-93da-ff2053f1b62e25builder # 26server # Superblock backups stored on blocks:27builder # Allocating group tables: 0/8 done28server # 32768, 98304, 163840, 22937629builder # Writing inode tables: 0/8 done30server # 31builder # Creating journal (8192 blocks): done32server # Allocating group tables: 0/8 done33builder # Writing superblocks and filesystem accounting information: 0/8 done34server # Writing inode tables: 0/8 done35builder # 36server # Creating journal (8192 blocks): done37builder # Virtualisation disk image created.38server # Writing superblocks and filesystem accounting information: 0/8 done39builder # Starting virtiofs daemons...40server # 41builder # [2026-10-08T12:58:09Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)42server # Virtualisation disk image created.43builder # [2026-10-08T12:58:09Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether44server # Starting virtiofs daemons...45builder # [2026-10-08T12:58:09Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)46server # [2026-10-08T12:58:09Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)47builder # [2026-10-08T12:58:09Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether48server # [2026-10-08T12:58:09Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether49builder # [2026-10-08T12:58:09Z INFO virtiofsd] Waiting for vhost-user socket connection...50server # [2026-10-08T12:58:09Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)51builder # [2026-10-08T12:58:09Z INFO virtiofsd] Waiting for vhost-user socket connection...52server # [2026-10-08T12:58:09Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether53builder # [2026-10-08T12:58:09Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)54server # [2026-10-08T12:58:09Z INFO virtiofsd] Waiting for vhost-user socket connection...55builder # [2026-10-08T12:58:09Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether56server # [2026-10-08T12:58:09Z INFO virtiofsd] Waiting for vhost-user socket connection...57builder # [2026-10-08T12:58:09Z INFO virtiofsd] Waiting for vhost-user socket connection...58server # [2026-10-08T12:58:09Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)59builder # [2026-10-08T12:58:09Z INFO virtiofsd] Client connected, servicing requests60server # [2026-10-08T12:58:09Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether61builder # [2026-10-08T12:58:09Z INFO virtiofsd] Client connected, servicing requests62server # [2026-10-08T12:58:09Z INFO virtiofsd] Waiting for vhost-user socket connection...63builder # [2026-10-08T12:58:09Z INFO virtiofsd] Client connected, servicing requests64server # [2026-10-08T12:58:09Z INFO virtiofsd] Client connected, servicing requests65server: QEMU running (pid 48)66server # [2026-10-08T12:58:09Z INFO virtiofsd] Client connected, servicing requests67server # [2026-10-08T12:58:09Z INFO virtiofsd] Client connected, servicing requests68(finished: start all VMs, in 0.36 seconds)69server: waiting for unit postgresql.service70server: waiting for the VM to finish booting71server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]72server # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 202673server # [ 0.000000] KASLR enabled74server # [ 0.000000] random: crng init done75server # [ 0.000000] Machine model: linux,dummy-virt76server # [ 0.000000] efi: UEFI not found.77server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT78server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]79server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]80server # [ 0.000000] Zone ranges:81server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]82server # [ 0.000000] DMA32 empty83server # [ 0.000000] Normal empty84server # [ 0.000000] Device empty85server # [ 0.000000] Movable zone start for each node86server # [ 0.000000] Early memory node ranges87server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]88server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]89server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000090server # [ 0.000000] psci: probing for conduit method from DT.91server # [ 0.000000] psci: PSCIv1.3 detected in firmware.92server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs93builder # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]94server # [ 0.000000] psci: Trusted OS migration not required95server # [ 0.000000] psci: SMC Calling Convention v1.196builder # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 202697server # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)98builder # [ 0.000000] KASLR enabled99builder # [ 0.000000] random: crng init done100server # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296101builder # [ 0.000000] Machine model: linux,dummy-virt102server # [ 0.000000] Detected PIPT I-cache on CPU0103builder # [ 0.000000] efi: UEFI not found.104server # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)105builder # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT106server # [ 0.000000] CPU features: detected: GICv3 CPU interface107builder # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]108server # [ 0.000000] CPU features: detected: Spectre-v4109server # [ 0.000000] CPU features: detected: Spectre-BHB110builder # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]111builder # [ 0.000000] Zone ranges:112server # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38113builder # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]114builder # [ 0.000000] DMA32 empty115server # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23116builder # [ 0.000000] Normal empty117server # [ 0.000000] alternatives: applying boot alternatives118builder # [ 0.000000] Device empty119builder # [ 0.000000] Movable zone start for each node120builder # [ 0.000000] Early memory node ranges121builder # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]122builder # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]123builder # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000124server # [ 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/4ac77i24693xz8b1kw7ay9a87rnxg6f6-nixos-system-server-test/init regInfo=/nix/.ro-store/j5pqqwlnpir566cighg03m8gxygnib0s-closure-info/registration console=ttyAMA0,115200n8 console=tty0125builder # [ 0.000000] psci: probing for conduit method from DT.126builder # [ 0.000000] psci: PSCIv1.3 detected in firmware.127builder # [ 0.000000] psci: Using standard PSCI v0.2 function IDs128builder # [ 0.000000] psci: Trusted OS migration not required129builder # [ 0.000000] psci: SMC Calling Convention v1.1130server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/j5pqqwlnpir566cighg03m8gxygnib0s-closure-info/registration", will be passed to user space.131server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes132builder # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)133builder # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296134server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)135builder # [ 0.000000] Detected PIPT I-cache on CPU0136server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)137builder # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)138server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB139builder # [ 0.000000] CPU features: detected: GICv3 CPU interface140server # [ 0.000000] software IO TLB: area num 1.141builder # [ 0.000000] CPU features: detected: Spectre-v4142server # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)143builder # [ 0.000000] CPU features: detected: Spectre-BHB144server # [ 0.000000] Fallback order for Node 0: 0145builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38146server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144147builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23148server # [ 0.000000] Policy zone: DMA149builder # [ 0.000000] alternatives: applying boot alternatives150server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off151server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1152server # [ 0.000000] allocated 2097152 bytes of page_ext153server # [ 0.000000] ftrace: allocating 75026 entries in 294 pages154server # [ 0.000000] ftrace: allocated 294 pages with 4 groups155server # [ 0.000000] rcu: Hierarchical RCU implementation.156builder # [ 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/gwcipgaswac59x6rcnm9zqcjjm3mx039-nixos-system-builder-test/init regInfo=/nix/.ro-store/wv7ahrvsj359wp1xc964hg2vh48yfabm-closure-info/registration console=ttyAMA0,115200n8 console=tty0157server # [ 0.000000] rcu: RCU event tracing is enabled.158server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.159server # [ 0.000000] Trampoline variant of Tasks RCU enabled.160builder # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/wv7ahrvsj359wp1xc964hg2vh48yfabm-closure-info/registration", will be passed to user space.161server # [ 0.000000] Rude variant of Tasks RCU enabled.162builder # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes163server # [ 0.000000] Tracing variant of Tasks RCU enabled.164builder # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)165server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.166server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1167builder # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)168builder # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB169server # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.170builder # [ 0.000000] software IO TLB: area num 1.171server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.172builder # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)173builder # [ 0.000000] Fallback order for Node 0: 0174server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.175builder # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144176server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0177builder # [ 0.000000] Policy zone: DMA178server # [ 0.000000] GICv3: 256 SPIs implemented179builder # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off180server # [ 0.000000] GICv3: 0 Extended SPIs implemented181server # [ 0.000000] Root IRQ handler: gic_handle_irq182builder # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1183server # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI184builder # [ 0.000000] allocated 2097152 bytes of page_ext185server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0186builder # [ 0.000000] ftrace: allocating 75026 entries in 294 pages187builder # [ 0.000000] ftrace: allocated 294 pages with 4 groups188server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000189server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]190builder # [ 0.000000] rcu: Hierarchical RCU implementation.191builder # [ 0.000000] rcu: RCU event tracing is enabled.192server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)193builder # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.194builder # [ 0.000000] Trampoline variant of Tasks RCU enabled.195server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)196builder # [ 0.000000] Rude variant of Tasks RCU enabled.197server # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000198builder # [ 0.000000] Tracing variant of Tasks RCU enabled.199server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000200builder # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.201server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.202builder # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1203server # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns204builder # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.205server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).206builder # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.207server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns208builder # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.209builder # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0210server # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns211builder # [ 0.000000] GICv3: 256 SPIs implemented212server # [ 0.000033] arm-pv: using stolen time PV213builder # [ 0.000000] GICv3: 0 Extended SPIs implemented214builder # [ 0.000000] Root IRQ handler: gic_handle_irq215server # [ 0.000553] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)216builder # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI217server # [ 0.000733] Console: colour dummy device 80x25218builder # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0219server # [ 0.000741] printk: legacy console [tty0] enabled220builder # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000221builder # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]222server # [ 0.000927] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)223server # [ 0.000934] pid_max: default: 32768 minimum: 301224builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)225server # [ 0.001015] LSM: initializing lsm=capability,landlock,yama,bpf,ima226server # [ 0.001161] landlock: Up and running.227server # [ 0.001164] Yama: becoming mindful.228builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)229server # [ 0.001681] LSM support for eBPF active230builder # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000231server # [ 0.001808] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)232builder # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000233server # [ 0.001828] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)234builder # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.235server # [ 0.003740] rcu: Hierarchical SRCU implementation.236server # [ 0.003745] rcu: Max phase no-delay instances is 1000.237builder # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns238server # [ 0.005052] fsl-mc MSI: its@8080000 domain created239builder # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).240server # [ 0.005175] EFI services will not be available.241server # [ 0.005247] smp: Bringing up secondary CPUs ...242server # [ 0.005257] smp: Brought up 1 node, 1 CPU243builder # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns244server # [ 0.005261] SMP: Total of 1 processors activated.245server # [ 0.005264] CPU: All CPU(s) started at EL1246builder # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns247builder # [ 0.000032] arm-pv: using stolen time PV248server # [ 0.005273] CPU features: detected: Branch Target Identification249server # [ 0.005282] CPU features: detected: ARMv8.4 Translation Table Level250builder # [ 0.000404] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)251builder # [ 0.000588] Console: colour dummy device 80x25252server # [ 0.005285] CPU features: detected: Instruction cache invalidation not required for I/D coherence253builder # [ 0.000596] printk: legacy console [tty0] enabled254server # [ 0.005289] CPU features: detected: Data cache clean to the PoU not required for I/D coherence255builder # [ 0.000782] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)256server # [ 0.005292] CPU features: detected: Common not Private translations257builder # [ 0.000789] pid_max: default: 32768 minimum: 301258server # [ 0.005296] CPU features: detected: CRC32 instructions259builder # [ 0.000872] LSM: initializing lsm=capability,landlock,yama,bpf,ima260server # [ 0.005298] CPU features: detected: Data cache clean to Point of Deep Persistence261builder # [ 0.001069] landlock: Up and running.262builder # [ 0.001073] Yama: becoming mindful.263server # [ 0.005302] CPU features: detected: Data cache clean to Point of Persistence264builder # [ 0.001521] LSM support for eBPF active265server # [ 0.005305] CPU features: detected: Data independent timing control (DIT)266builder # [ 0.001643] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)267server # [ 0.005307] CPU features: detected: E0PD268server # [ 0.005310] CPU features: detected: Enhanced Counter Virtualization269builder # [ 0.001666] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)270builder # [ 0.003656] rcu: Hierarchical SRCU implementation.271server # [ 0.005313] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)272builder # [ 0.003662] rcu: Max phase no-delay instances is 1000.273server # [ 0.005316] CPU features: detected: Enhanced Virtualization Traps274builder # [ 0.004950] fsl-mc MSI: its@8080000 domain created275server # [ 0.005319] CPU features: detected: Fine Grained Traps276builder # [ 0.005076] EFI services will not be available.277builder # [ 0.005163] smp: Bringing up secondary CPUs ...278server # [ 0.005323] CPU features: detected: Generic authentication (architected QARMA5 algorithm)279builder # [ 0.005172] smp: Brought up 1 node, 1 CPU280server # [ 0.005328] CPU features: detected: RCpc load-acquire (LDAPR)281builder # [ 0.005175] SMP: Total of 1 processors activated.282server # [ 0.005331] CPU features: detected: LSE atomic instructions283builder # [ 0.005178] CPU: All CPU(s) started at EL1284server # [ 0.005333] CPU features: detected: Privileged Access Never285server # [ 0.005336] CPU features: detected: PMUv3286builder # [ 0.005189] CPU features: detected: Branch Target Identification287server # [ 0.005339] CPU features: detected: RAS Extension Support288builder # [ 0.005197] CPU features: detected: ARMv8.4 Translation Table Level289server # [ 0.005342] CPU features: detected: RASv1p1 Extension Support290server # [ 0.005344] CPU features: detected: Random Number Generator291builder # [ 0.005200] CPU features: detected: Instruction cache invalidation not required for I/D coherence292server # [ 0.005347] CPU features: detected: Speculation barrier (SB)293builder # [ 0.005203] CPU features: detected: Data cache clean to the PoU not required for I/D coherence294server # [ 0.005349] CPU features: detected: Stage-2 Force Write-Back295builder # [ 0.005207] CPU features: detected: Common not Private translations296server # [ 0.005352] CPU features: detected: TLB range maintenance instructions297builder # [ 0.005211] CPU features: detected: CRC32 instructions298server # [ 0.005357] CPU features: detected: Speculative Store Bypassing Safe (SSBS)299builder # [ 0.005214] CPU features: detected: Data cache clean to Point of Deep Persistence300server # [ 0.005395] alternatives: applying system-wide alternatives301builder # [ 0.005217] CPU features: detected: Data cache clean to Point of Persistence302builder # [ 0.005220] CPU features: detected: Data independent timing control (DIT)303builder # [ 0.005223] CPU features: detected: E0PD304builder # [ 0.005226] CPU features: detected: Enhanced Counter Virtualization305builder # [ 0.005229] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)306builder # [ 0.005232] CPU features: detected: Enhanced Virtualization Traps307builder # [ 0.005235] CPU features: detected: Fine Grained Traps308builder # [ 0.005239] CPU features: detected: Generic authentication (architected QARMA5 algorithm)309builder # [ 0.005245] CPU features: detected: RCpc load-acquire (LDAPR)310builder # [ 0.005248] CPU features: detected: LSE atomic instructions311builder # [ 0.005251] CPU features: detected: Privileged Access Never312server # [ 0.008441] CPU features: detected: BBM Level 2 without TLB conflict abort313builder # [ 0.005253] CPU features: detected: PMUv3314builder # [ 0.005256] CPU features: detected: RAS Extension Support315builder # [ 0.005259] CPU features: detected: RASv1p1 Extension Support316server # [ 0.008614] Memory: 893128K/1048576K available (24512K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 114116K reserved, 32768K cma-reserved)317server # [ 0.008942] devtmpfs: initialized318builder # [ 0.005262] CPU features: detected: Random Number Generator319builder # [ 0.005264] CPU features: detected: Speculation barrier (SB)320server # [ 0.010764] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)321builder # [ 0.005267] CPU features: detected: Stage-2 Force Write-Back322server # [ 0.010787] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).323builder # [ 0.005270] CPU features: detected: TLB range maintenance instructions324server # [ 0.010998] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL325builder # [ 0.005275] CPU features: detected: Speculative Store Bypassing Safe (SSBS)326server # [ 0.011003] 0 pages in range for non-PLT usage327builder # [ 0.005313] alternatives: applying system-wide alternatives328server # [ 0.011004] 508208 pages in range for PLT usage329server # [ 0.011099] pinctrl core: initialized pinctrl subsystem330builder # [ 0.008318] CPU features: detected: BBM Level 2 without TLB conflict abort331server # [ 0.011912] DMI not present or invalid.332server # [ 0.015088] NET: Registered PF_NETLINK/PF_ROUTE protocol family333builder # [ 0.008536] Memory: 892880K/1048576K available (24512K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 114124K reserved, 32768K cma-reserved)334server # [ 0.017601] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations335builder # [ 0.008890] devtmpfs: initialized336server # [ 0.017757] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations337builder # [ 0.010791] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)338server # [ 0.017913] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations339builder # [ 0.010814] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).340server # [ 0.017936] audit: initializing netlink subsys (disabled)341builder # [ 0.011024] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL342server # [ 0.018567] thermal_sys: Registered thermal governor 'fair_share'343builder # [ 0.011028] 0 pages in range for non-PLT usage344server # [ 0.018569] thermal_sys: Registered thermal governor 'bang_bang'345builder # [ 0.011029] 508208 pages in range for PLT usage346builder # [ 0.011140] pinctrl core: initialized pinctrl subsystem347server # [ 0.018572] thermal_sys: Registered thermal governor 'step_wise'348builder # [ 0.011936] DMI not present or invalid.349server # [ 0.018575] thermal_sys: Registered thermal governor 'user_space'350builder # [ 0.015276] NET: Registered PF_NETLINK/PF_ROUTE protocol family351server # [ 0.018578] thermal_sys: Registered thermal governor 'power_allocator'352builder # [ 0.017534] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations353server # [ 0.018605] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1354server # [ 0.018614] cpuidle: using governor ladder355builder # [ 0.017679] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations356server # [ 0.018620] cpuidle: using governor menu357builder # [ 0.017834] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations358server # [ 0.018830] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.359builder # [ 0.017860] audit: initializing netlink subsys (disabled)360server # [ 0.018846] ASID allocator initialised with 65536 entries361builder # [ 0.018506] thermal_sys: Registered thermal governor 'fair_share'362server # [ 0.020060] Serial: AMBA PL011 UART driver363builder # [ 0.018508] thermal_sys: Registered thermal governor 'bang_bang'364server # [ 0.025705] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1365builder # [ 0.018511] thermal_sys: Registered thermal governor 'step_wise'366server # [ 0.025865] printk: console [ttyAMA0] enabled367builder # [ 0.018514] thermal_sys: Registered thermal governor 'user_space'368builder # [ 0.018517] thermal_sys: Registered thermal governor 'power_allocator'369builder # [ 0.018545] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1370builder # [ 0.018553] cpuidle: using governor ladder371builder # [ 0.018559] cpuidle: using governor menu372builder # [ 0.018755] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.373builder # [ 0.018770] ASID allocator initialised with 65536 entries374server # [ 0.155676] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages375builder # [ 0.020035] Serial: AMBA PL011 UART driver376server # [ 0.155686] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page377builder # [ 0.025621] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1378server # [ 0.155690] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages379builder # [ 0.025781] printk: console [ttyAMA0] enabled380server # [ 0.155692] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page381server # [ 0.155695] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages382server # [ 0.155698] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page383server # [ 0.155701] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages384server # [ 0.155704] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page385server # [ 0.157010] fbcon: Taking over console386server # [ 0.157020] ACPI: Interpreter disabled.387server # [ 0.157976] iommu: Default domain type: Translated388server # [ 0.157980] iommu: DMA domain TLB invalidation policy: strict mode389server # [ 0.158365] SCSI subsystem initialized390server # [ 0.158550] usbcore: registered new interface driver usbfs391server # [ 0.158586] usbcore: registered new interface driver hub392server # [ 0.158602] usbcore: registered new device driver usb393server # [ 0.158856] pps_core: LinuxPPS API ver. 1 registered394server # [ 0.158860] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>395server # [ 0.158868] PTP clock support registered396server # [ 0.158914] EDAC MC: Ver: 3.0.0397server # [ 0.159080] scmi_core: SCMI protocol bus registered398builder # [ 0.156677] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages399builder # [ 0.156686] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page400builder # [ 0.156690] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages401server # [ 0.174458] FPGA manager framework402builder # [ 0.156693] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page403builder # [ 0.156695] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages404builder # [ 0.156698] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page405builder # [ 0.156701] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages406builder # [ 0.156704] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page407server # [ 0.177313] vgaarb: loaded408builder # [ 0.157929] fbcon: Taking over console409builder # [ 0.157938] ACPI: Interpreter disabled.410server # [ 0.177608] clocksource: Switched to clocksource arch_sys_counter411builder # [ 0.168053] iommu: Default domain type: Translated412builder # [ 0.168059] iommu: DMA domain TLB invalidation policy: strict mode413builder # [ 0.168465] SCSI subsystem initialized414server # [ 0.185280] VFS: Disk quotas dquot_6.6.0415server # [ 0.185304] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)416builder # [ 0.170643] usbcore: registered new interface driver usbfs417builder # [ 0.170673] usbcore: registered new interface driver hub418builder # [ 0.170690] usbcore: registered new device driver usb419builder # [ 0.170968] pps_core: LinuxPPS API ver. 1 registered420server # [ 0.187248] netfs: FS-Cache loaded421builder # [ 0.170971] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>422server # [ 0.187373] pnp: PnP ACPI: disabled423builder # [ 0.170979] PTP clock support registered424builder # [ 0.171023] EDAC MC: Ver: 3.0.0425builder # [ 0.171206] scmi_core: SCMI protocol bus registered426builder # [ 0.178239] FPGA manager framework427builder # [ 0.178776] vgaarb: loaded428builder # [ 0.179061] clocksource: Switched to clocksource arch_sys_counter429server # [ 0.193713] NET: Registered PF_INET protocol family430server # [ 0.193866] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)431builder # [ 0.187553] VFS: Disk quotas dquot_6.6.0432builder # [ 0.187584] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)433builder # [ 0.189974] netfs: FS-Cache loaded434builder # [ 0.190094] pnp: PnP ACPI: disabled435builder # [ 0.195823] NET: Registered PF_INET protocol family436builder # [ 0.195978] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)437server # [ 0.223645] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)438server # [ 0.223676] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)439server # [ 0.223725] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)440server # [ 0.223766] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)441server # [ 0.223839] TCP: Hash tables configured (established 8192 bind 8192)442server # [ 0.223918] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)443server # [ 0.223974] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)444server # [ 0.224020] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)445server # [ 0.224109] NET: Registered PF_UNIX/PF_LOCAL protocol family446server # [ 0.224167] NET: Registered PF_XDP protocol family447server # [ 0.224184] PCI: CLS 0 bytes, default 64448server # [ 0.224438] Trying to unpack rootfs image as initramfs...449builder # [ 0.227561] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)450server # [ 0.243740] kvm [1]: HYP mode not available451builder # [ 0.227604] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)452builder # [ 0.227629] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)453builder # [ 0.227672] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)454builder # [ 0.227745] TCP: Hash tables configured (established 8192 bind 8192)455builder # [ 0.227832] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)456builder # [ 0.227888] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)457builder # [ 0.227936] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)458builder # [ 0.228012] NET: Registered PF_UNIX/PF_LOCAL protocol family459builder # [ 0.228062] NET: Registered PF_XDP protocol family460builder # [ 0.228078] PCI: CLS 0 bytes, default 64461builder # [ 0.228364] Trying to unpack rootfs image as initramfs...462builder # [ 0.241590] kvm [1]: HYP mode not available463server # [ 0.338190] Initialise system trusted keyrings464server # [ 0.338326] workingset: timestamp_bits=42 max_order=18 bucket_order=0465server # [ 0.338685] squashfs: version 4.0 (2009/01/31) Phillip Lougher466server # [ 0.338733] 9p: Installing v9fs 9p2000 file system support467builder # [ 0.338708] Initialise system trusted keyrings468builder # [ 0.347129] workingset: timestamp_bits=42 max_order=18 bucket_order=0469builder # [ 0.347577] squashfs: version 4.0 (2009/01/31) Phillip Lougher470builder # [ 0.347626] 9p: Installing v9fs 9p2000 file system support471server # [ 0.366724] Key type asymmetric registered472server # [ 0.366740] Asymmetric key parser 'x509' registered473server # [ 0.366803] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)474server # [ 0.366969] io scheduler mq-deadline registered475server # [ 0.366973] io scheduler kyber registered476server # [ 0.379114] pl061_gpio 9030000.pl061: PL061 GPIO chip registered477server # [ 0.379673] ledtrig-cpu: registered to indicate activity on CPUs478server # [ 0.380097] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:479server # [ 0.380112] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000480builder # [ 0.370028] Key type asymmetric registered481builder # [ 0.370038] Asymmetric key parser 'x509' registered482server # [ 0.380122] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000483builder # [ 0.370111] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)484server # [ 0.380127] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000485builder # [ 0.370265] io scheduler mq-deadline registered486builder # [ 0.370269] io scheduler kyber registered487server # [ 0.380146] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits488server # [ 0.380164] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]489server # [ 0.380234] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00490server # [ 0.380241] pci_bus 0000:00: root bus resource [bus 00-ff]491server # [ 0.380245] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]492server # [ 0.380248] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]493server # [ 0.380251] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]494server # [ 0.380306] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint495server # [ 0.380737] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint496server # [ 0.380919] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]497server # [ 0.380931] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]498server # [ 0.380956] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]499server # [ 0.380968] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]500server # [ 0.381418] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint501server # [ 0.381597] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]502builder # [ 0.383202] pl061_gpio 9030000.pl061: PL061 GPIO chip registered503builder # [ 0.383828] ledtrig-cpu: registered to indicate activity on CPUs504builder # [ 0.384190] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:505builder # [ 0.384204] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000506builder # [ 0.384214] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000507builder # [ 0.384219] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000508builder # [ 0.384238] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits509builder # [ 0.384261] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]510builder # [ 0.384333] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00511server # [ 0.405635] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]512builder # [ 0.384339] pci_bus 0000:00: root bus resource [bus 00-ff]513server # [ 0.405686] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]514builder # [ 0.384343] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]515server # [ 0.406180] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint516builder # [ 0.384346] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]517server # [ 0.406370] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]518builder # [ 0.384349] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]519server # [ 0.406382] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]520builder # [ 0.384417] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint521server # [ 0.406408] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]522builder # [ 0.384863] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint523server # [ 0.406865] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint524builder # [ 0.385050] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]525server # [ 0.407050] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]526builder # [ 0.385062] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]527server # [ 0.407061] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]528builder # [ 0.385087] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]529server # [ 0.407086] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]530builder # [ 0.385100] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]531server # [ 0.407098] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]532builder # [ 0.385569] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint533server # [ 0.407569] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint534builder # [ 0.385752] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]535server # [ 0.407758] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]536builder # [ 0.385764] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]537server # [ 0.407784] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]538builder # [ 0.385790] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]539server # [ 0.408252] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint540builder # [ 0.386319] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint541server # [ 0.408440] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]542builder # [ 0.386504] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]543server # [ 0.408465] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]544builder # [ 0.386515] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]545server # [ 0.408891] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint546builder # [ 0.386541] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]547server # [ 0.409075] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]548builder # [ 0.387001] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint549server # [ 0.409319] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint550server # [ 0.409507] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]551server # [ 0.409532] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]552builder # [ 0.419256] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]553builder # [ 0.419274] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]554server # [ 0.434086] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint555builder # [ 0.419300] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]556server # [ 0.434286] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]557builder # [ 0.419312] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]558server # [ 0.434312] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]559builder # [ 0.419833] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint560server # [ 0.434771] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint561builder # [ 0.420029] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]562server # [ 0.434956] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]563builder # [ 0.420055] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]564server # [ 0.434981] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]565builder # [ 0.420520] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint566server # [ 0.435451] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint567builder # [ 0.420707] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]568server # [ 0.435735] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]569server # [ 0.435748] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]570builder # [ 0.420733] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]571server # [ 0.435773] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]572builder # [ 0.421160] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint573builder # [ 0.421343] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]574server # [ 0.436247] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint575server # [ 0.436434] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]576builder # [ 0.421593] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint577server # [ 0.436445] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]578builder # [ 0.421784] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]579server # [ 0.436471] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]580builder # [ 0.421810] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]581server # [ 0.437069] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned582builder # [ 0.422280] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint583server # [ 0.437075] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned584builder # [ 0.422472] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]585server # [ 0.437079] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned586builder # [ 0.422497] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]587server # [ 0.437124] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned588builder # [ 0.422960] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint589server # [ 0.437168] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned590server # [ 0.437212] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned591server # [ 0.437256] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned592server # [ 0.437300] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned593builder # [ 0.440048] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]594builder # [ 0.440074] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]595server # [ 0.437345] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned596builder # [ 0.440560] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint597server # [ 0.437388] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned598builder # [ 0.440822] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]599server # [ 0.437431] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned600builder # [ 0.440834] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]601server # [ 0.437474] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned602builder # [ 0.440860] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]603server # [ 0.437536] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned604builder # [ 0.441327] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint605server # [ 0.437578] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned606builder # [ 0.441512] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]607server # [ 0.437596] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned608builder # [ 0.441524] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]609builder # [ 0.441549] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]610builder # [ 0.442166] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned611server # [ 0.464358] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned612builder # [ 0.442172] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned613server # [ 0.464378] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned614builder # [ 0.442176] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned615server # [ 0.464396] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned616builder # [ 0.442229] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned617server # [ 0.464414] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned618server # [ 0.464431] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned619builder # [ 0.442273] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned620server # [ 0.464450] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned621builder # [ 0.442317] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned622server # [ 0.464468] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned623builder # [ 0.442361] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned624server # [ 0.464490] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned625builder # [ 0.442404] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned626server # [ 0.464508] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned627server # [ 0.464526] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned628builder # [ 0.442447] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned629server # [ 0.464544] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned630builder # [ 0.442490] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned631server # [ 0.464561] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned632builder # [ 0.442533] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned633server # [ 0.464578] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned634server # [ 0.464596] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned635builder # [ 0.442577] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned636server # [ 0.464613] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned637builder # [ 0.442643] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned638server # [ 0.464630] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned639builder # [ 0.442693] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned640server # [ 0.464654] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]641builder # [ 0.442711] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned642server # [ 0.464659] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]643server # [ 0.464662] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]644builder # [ 0.442729] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned645server # [ 0.465526] pci 0000:00:07.0: enabling device (0000 -> 0002)646builder # [ 0.442746] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned647builder # [ 0.442763] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned648builder # [ 0.442781] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned649builder # [ 0.442798] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned650builder # [ 0.442816] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned651builder # [ 0.442834] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned652builder # [ 0.442854] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned653builder # [ 0.442872] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned654builder # [ 0.442891] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned655builder # [ 0.442908] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned656builder # [ 0.442925] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned657builder # [ 0.442942] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned658builder # [ 0.442960] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned659builder # [ 0.442977] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned660builder # [ 0.442994] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned661builder # [ 0.443016] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]662server # [ 0.489774] pci 0000:00:07.0: quirk_usb_early_handoff+0x0/0xa60 took 23685 usecs663builder # [ 0.443021] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]664builder # [ 0.443024] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]665builder # [ 0.487932] pci 0000:00:07.0: enabling device (0000 -> 0002)666server # [ 0.510491] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)667server # [ 0.512489] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)668server # [ 0.522285] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)669server # [ 0.524310] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)670server # [ 0.527934] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)671builder # [ 0.516680] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)672builder # [ 0.518846] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)673builder # [ 0.522660] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)674server # [ 0.537810] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)675server # [ 0.539563] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)676server # [ 0.541335] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)677builder # [ 0.532419] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)678builder # [ 0.534585] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)679builder # [ 0.538379] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)680server # [ 0.545640] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)681builder # [ 0.540873] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)682server # [ 0.547366] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)683builder # [ 0.542505] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)684server # [ 0.559845] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)685builder # [ 0.552507] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)686builder # [ 0.554420] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)687server # [ 0.572972] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled688server # [ 0.575549] msm_serial: driver initialized689server # [ 0.575708] SuperH (H)SCI(F) driver initialized690server # [ 0.575765] STM32 USART driver initialized691builder # [ 0.565018] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)692builder # [ 0.576440] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled693builder # [ 0.578373] msm_serial: driver initialized694builder # [ 0.578514] SuperH (H)SCI(F) driver initialized695builder # [ 0.578571] STM32 USART driver initialized696server # [ 0.614214] loop: module loaded697server # [ 0.614405] virtio_blk virtio2: 1/0/0 default/read/poll queues698server # [ 0.615242] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)699server # [ 0.620483] megasas: 07.734.00.00-rc1700server # [ 0.621239] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]701builder # [ 0.611911] loop: module loaded702builder # [ 0.612078] virtio_blk virtio2: 1/0/0 default/read/poll queues703server # [ 0.627082] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000704builder # [ 0.612868] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)705server # [ 0.627097] Intel/Sharp Extended Query Table at 0x0031706server # [ 0.628890] Using buffer write method707server # [ 0.628922] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]708server # [ 0.642260] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000709server # [ 0.642279] Intel/Sharp Extended Query Table at 0x0031710server # [ 0.644903] Using buffer write method711server # [ 0.644919] Concatenating MTD devices:712server # [ 0.644922] (0): "0.flash"713server # [ 0.644924] (1): "0.flash"714server # [ 0.644926] into device "0.flash"715builder # [ 0.627851] megasas: 07.734.00.00-rc1716builder # [ 0.628614] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]717builder # [ 0.630743] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000718builder # [ 0.630803] Intel/Sharp Extended Query Table at 0x0031719builder # [ 0.632578] Using buffer write method720builder # [ 0.632623] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]721builder # [ 0.634025] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000722builder # [ 0.634046] Intel/Sharp Extended Query Table at 0x0031723builder # [ 0.635439] Using buffer write method724builder # [ 0.635461] Concatenating MTD devices:725builder # [ 0.635463] (0): "0.flash"726builder # [ 0.635465] (1): "0.flash"727builder # [ 0.635467] into device "0.flash"728server # [ 0.904516] Freeing initrd memory: 26936K729server # [ 0.910693] tun: Universal TUN/TAP device driver, 1.6730server # [ 0.914525] thunder_xcv, ver 1.0731server # [ 0.914553] thunder_bgx, ver 1.0732server # [ 0.914572] nicpf, ver 1.0733server # [ 0.915147] e1000: Intel(R) PRO/1000 Network Driver734server # [ 0.915151] e1000: Copyright (c) 1999-2006 Intel Corporation.735server # [ 0.915178] e1000e: Intel(R) PRO/1000 Network Driver736server # [ 0.915183] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.737server # [ 0.915207] igb: Intel(R) Gigabit Ethernet Network Driver738server # [ 0.915210] igb: Copyright (c) 2007-2014 Intel Corporation.739server # [ 0.915229] igbvf: Intel(R) Gigabit Virtual Function Network Driver740server # [ 0.915233] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.741server # [ 0.915369] sky2: driver version 1.30742server # [ 0.917012] usbcore: registered new interface driver usb-storage743server # [ 0.917056] usbcore: registered new interface driver usbserial_generic744server # [ 0.917068] usbserial: USB Serial support registered for generic745server # [ 0.918247] ehci-pci 0000:00:07.0: EHCI Host Controller746builder # [ 0.911751] Freeing initrd memory: 26944K747server # [ 0.918264] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1748server # [ 0.918476] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000749builder # [ 0.917973] tun: Universal TUN/TAP device driver, 1.6750server # [ 0.930518] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00751server # [ 0.930884] hub 1-0:1.0: USB hub found752server # [ 0.930900] hub 1-0:1.0: 6 ports detected753server # [ 0.931377] hv_vmbus: registering driver hyperv_keyboard754server # [ 0.932261] rtc-pl031 9010000.pl031: registered as rtc0755builder # [ 0.921849] thunder_xcv, ver 1.0756server # [ 0.932283] rtc-pl031 9010000.pl031: setting system clock to 2026-10-08T12:58:11 UTC (1791464291)757builder # [ 0.921877] thunder_bgx, ver 1.0758builder # [ 0.921895] nicpf, ver 1.0759server # [ 0.932580] i2c_dev: i2c /dev entries driver760builder # [ 0.922520] e1000: Intel(R) PRO/1000 Network Driver761builder # [ 0.922525] e1000: Copyright (c) 1999-2006 Intel Corporation.762builder # [ 0.922549] e1000e: Intel(R) PRO/1000 Network Driver763builder # [ 0.922556] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.764builder # [ 0.922578] igb: Intel(R) Gigabit Ethernet Network Driver765builder # [ 0.922582] igb: Copyright (c) 2007-2014 Intel Corporation.766server # [ 0.940693] sdhci: Secure Digital Host Controller Interface driver767server # [ 0.940698] sdhci: Copyright(c) Pierre Ossman768builder # [ 0.922601] igbvf: Intel(R) Gigabit Virtual Function Network Driver769server # [ 0.940980] Synopsys Designware Multimedia Card Interface Driver770builder # [ 0.922605] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.771server # [ 0.941363] sdhci-pltfm: SDHCI platform and OF driver helper772builder # [ 0.922735] sky2: driver version 1.30773builder # [ 0.932064] usbcore: registered new interface driver usb-storage774server # [ 0.945830] hid: raw HID events driver (C) Jiri Kosina775builder # [ 0.932113] usbcore: registered new interface driver usbserial_generic776server # [ 0.946071] usbcore: registered new interface driver usbhid777builder # [ 0.932123] usbserial: USB Serial support registered for generic778server # [ 0.946075] usbhid: USB HID core driver779builder # [ 0.932724] hv_vmbus: registering driver hyperv_keyboard780server # [ 0.947225] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available781builder # [ 0.933980] ehci-pci 0000:00:07.0: EHCI Host Controller782server # [ 0.948800] drop_monitor: Initializing network drop monitor service783builder # [ 0.934001] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1784server # [ 0.948939] NET: Registered PF_INET6 protocol family785builder # [ 0.934210] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000786server # [ 0.949450] Segment Routing with IPv6787server # [ 0.949461] In-situ OAM (IOAM) with IPv6788builder # [ 0.934535] rtc-pl031 9010000.pl031: registered as rtc0789server # [ 0.949486] NET: Registered PF_PACKET protocol family790builder # [ 0.934558] rtc-pl031 9010000.pl031: setting system clock to 2026-10-08T12:58:11 UTC (1791464291)791server # [ 0.949540] 9pnet: Installing 9P2000 support792builder # [ 0.934902] i2c_dev: i2c /dev entries driver793server # [ 0.949576] Key type dns_resolver registered794builder # [ 0.943664] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00795builder # [ 0.943958] hub 1-0:1.0: USB hub found796builder # [ 0.943974] hub 1-0:1.0: 6 ports detected797builder # [ 0.946897] sdhci: Secure Digital Host Controller Interface driver798builder # [ 0.946903] sdhci: Copyright(c) Pierre Ossman799builder # [ 0.950344] Synopsys Designware Multimedia Card Interface Driver800server # [ 0.962762] registered taskstats version 1801builder # [ 0.950733] sdhci-pltfm: SDHCI platform and OF driver helper802server # [ 0.962896] Loading compiled-in X.509 certificates803builder # [ 0.953829] hid: raw HID events driver (C) Jiri Kosina804builder # [ 0.954062] usbcore: registered new interface driver usbhid805builder # [ 0.954066] usbhid: USB HID core driver806builder # [ 0.957179] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available807server # [ 0.971554] Demotion targets for Node 0: null808server # [ 0.971666] Key type .fscrypt registered809builder # [ 0.958745] drop_monitor: Initializing network drop monitor service810server # [ 0.971673] Key type fscrypt-provisioning registered811builder # [ 0.958911] NET: Registered PF_INET6 protocol family812server # [ 0.971764] ima: No TPM chip found, activating TPM-bypass!813server # [ 0.971780] ima: Allocated hash algorithm: sha1814server # [ 0.971800] ima: No architecture policies found815builder # [ 0.962083] Segment Routing with IPv6816builder # [ 0.962101] In-situ OAM (IOAM) with IPv6817server # [ 0.972403] input: gpio-keys as /devices/platform/gpio-keys/input/input0818builder # [ 0.962128] NET: Registered PF_PACKET protocol family819builder # [ 0.962257] 9pnet: Installing 9P2000 support820builder # [ 0.962302] Key type dns_resolver registered821builder # [ 0.971252] registered taskstats version 1822builder # [ 0.971398] Loading compiled-in X.509 certificates823builder # [ 0.979866] Demotion targets for Node 0: null824builder # [ 0.979979] Key type .fscrypt registered825server # [ 0.993694] clk: Disabling unused clocks826builder # [ 0.979983] Key type fscrypt-provisioning registered827server # [ 0.993706] PM: genpd: Disabling unused power domains828server # [ 0.996637] Freeing unused kernel memory: 4736K829builder # [ 0.980078] ima: No TPM chip found, activating TPM-bypass!830server # [ 0.996821] Run /init as init process831builder # [ 0.980094] ima: Allocated hash algorithm: sha1832builder # [ 0.980115] ima: No architecture policies found833builder # [ 0.980751] input: gpio-keys as /devices/platform/gpio-keys/input/input0834builder # [ 1.002503] clk: Disabling unused clocks835builder # [ 1.002520] PM: genpd: Disabling unused power domains836server # [ 1.015954] systemd[1]: Successfully made /usr/ read-only.837builder # [ 1.006652] Freeing unused kernel memory: 4736K838builder # [ 1.006861] Run /init as init process839builder # [ 1.021363] systemd[1]: Successfully made /usr/ read-only.840server # [ 1.177667] usb 1-1: new high-speed USB device number 2 using ehci-pci841builder # [ 1.195123] usb 1-1: new high-speed USB device number 2 using ehci-pci842server # [ 1.327340] 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/input1843server # [ 1.349926] systemd[1]: systemd 261.3 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)844server # [ 1.349951] systemd[1]: Detected virtualization qemu.845server # [ 1.349991] systemd[1]: Detected architecture arm64.846builder # [ 1.344767] 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/input1847server # [ 1.349999] systemd[1]: Running in initrd.848server # [ 1.350489] systemd[1]: Initializing machine ID from random generator.849server # [ 1.350635] systemd[1]: Hostname set to <server>.850builder # [ 1.355262] systemd[1]: systemd 261.3 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)851builder # [ 1.355287] systemd[1]: Detected virtualization qemu.852builder # [ 1.355326] systemd[1]: Detected architecture arm64.853builder # [ 1.355334] systemd[1]: Running in initrd.854builder # [ 1.355816] systemd[1]: Initializing machine ID from random generator.855builder # [ 1.356045] systemd[1]: Hostname set to <builder>.856server # [ 1.413819] 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.431320] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0858server # [ 1.476902] systemd[1]: bpf-restrict-fs: LSM BPF program attached859builder # [ 1.481715] systemd[1]: bpf-restrict-fs: LSM BPF program attached860server # [ 1.532595] systemd[1]: Queued start job for default target Initrd Default Target.861server # [ 1.533738] usb 1-2: new high-speed USB device number 3 using ehci-pci862server # [ 1.538039] systemd[1]: Created slice Slice /system/modprobe.863server # [ 1.538206] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.864server # [ 1.538231] systemd[1]: Expecting device /dev/disk/by-label/nixos...865server # [ 1.538250] systemd[1]: Reached target Path Units.866server # [ 1.538262] systemd[1]: Reached target Slice Units.867server # [ 1.538274] systemd[1]: Reached target Swaps.868server # [ 1.538287] systemd[1]: Reached target Timer Units.869server # [ 1.538408] systemd[1]: Listening on D-Bus System Message Bus Socket.870server # [ 1.538546] systemd[1]: Listening on Journal Socket (/dev/log).871server # [ 1.538660] systemd[1]: Listening on Journal Sockets.872server # [ 1.538761] systemd[1]: Listening on udev Control Socket.873server # [ 1.538848] systemd[1]: Listening on udev Kernel Socket.874server # [ 1.538861] systemd[1]: Reached target Socket Units.875server # [ 1.540143] systemd[1]: Starting Create List of Static Device Nodes...876builder # [ 1.538998] systemd[1]: Queued start job for default target Initrd Default Target.877server # [ 1.540197] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs878server # [ 1.557746] systemd[1]: Mounting Kernel Configuration File System...879builder # [ 1.549840] systemd[1]: Created slice Slice /system/modprobe.880builder # [ 1.550065] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.881builder # [ 1.550102] systemd[1]: Expecting device /dev/disk/by-label/nixos...882builder # [ 1.550131] systemd[1]: Reached target Path Units.883builder # [ 1.550153] systemd[1]: Reached target Slice Units.884builder # [ 1.550177] systemd[1]: Reached target Swaps.885server # [ 1.565760] systemd[1]: Starting Journal Service...886builder # [ 1.550319] systemd[1]: Reached target Timer Units.887builder # [ 1.550490] systemd[1]: Listening on D-Bus System Message Bus Socket.888builder # [ 1.550642] systemd[1]: Listening on Journal Socket (/dev/log).889builder # [ 1.550758] systemd[1]: Listening on Journal Sockets.890builder # [ 1.550860] systemd[1]: Listening on udev Control Socket.891server # [ 1.570489] systemd[1]: Starting Load Kernel Modules...892builder # [ 1.550951] systemd[1]: Listening on udev Kernel Socket.893builder # [ 1.550967] systemd[1]: Reached target Socket Units.894server # [ 1.570574] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os895builder # [ 1.551105] usb 1-2: new high-speed USB device number 3 using ehci-pci896builder # [ 1.563573] systemd[1]: Starting Create List of Static Device Nodes...897builder # [ 1.563628] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs898builder # [ 1.571202] systemd[1]: Mounting Kernel Configuration File System...899builder # [ 1.580309] systemd[1]: Starting Journal Service...900builder # [ 1.583873] systemd[1]: Starting Load Kernel Modules...901builder # [ 1.583962] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os902server # [ 1.621780] systemd[1]: Starting Coldplug All udev Devices...903server # [ 1.623148] systemd[1]: Finished Create List of Static Device Nodes.904builder # [ 1.619432] systemd[1]: Starting Coldplug All udev Devices...905builder # [ 1.620878] systemd[1]: Finished Create List of Static Device Nodes.906builder # [ 1.626483] systemd-journald[71]: Collecting audit messages is disabled.907server # [ 1.649391] systemd-journald[71]: Collecting audit messages is disabled.908server # [ 1.652993] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.909server # [ 1.656055] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...910builder # [ 1.644399] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...911builder # [ 1.646069] systemd[1]: Mounted Kernel Configuration File System.912server # [ 1.661994] systemd[1]: Mounted Kernel Configuration File System.913builder # [ 1.656141] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.914server # [ 1.669701] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev915server # [ 1.675781] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0916server # [ 1.676015] [drm] features: -virgl +edid -resource_blob -host_visible917server # [ 1.676018] [drm] features: -context_init918server # [ 1.676748] [drm] number of scanouts: 1919server # [ 1.676759] [drm] number of cap sets: 0920server # [ 1.689366] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.921server # [ 1.692113] systemd[1]: Starting Create Static Device Nodes in /dev...922builder # [ 1.679317] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev923server # [ 1.699632] 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/input2924server # [ 1.699769] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0925server # [ 1.705160] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic926server # [ 1.705172] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0927builder # [ 1.691691] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.928builder # [ 1.698757] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0929builder # [ 1.698982] [drm] features: -virgl +edid -resource_blob -host_visible930builder # [ 1.698984] [drm] features: -context_init931builder # [ 1.701620] systemd[1]: Starting Create Static Device Nodes in /dev...932builder # [ 1.707678] [drm] number of scanouts: 1933builder # [ 1.707692] [drm] number of cap sets: 0934builder # [ 1.710837] 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/input2935builder # [ 1.710979] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0936builder # [ 1.719446] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic937server # [ 1.733584] Console: switching to colour frame buffer device 160x50938builder # [ 1.719457] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0939server # [ 1.750437] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device940builder # [ 1.751038] Console: switching to colour frame buffer device 160x50941server # [ 1.762182] systemd[1]: Finished Load Kernel Modules.942server # [ 1.763708] systemd[1]: Starting Apply Kernel Variables...943builder # [ 1.763794] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device944builder # [ 1.764515] systemd[1]: Finished Create Static Device Nodes in /dev.945server # [ 1.778521] systemd[1]: Finished Create Static Device Nodes in /dev.946builder # [ 1.764740] systemd[1]: Reached target Preparation for Local File Systems.947server # [ 1.778931] systemd[1]: Reached target Preparation for Local File Systems.948builder # [ 1.764774] systemd[1]: Reached target Local File Systems.949server # [ 1.778959] systemd[1]: Reached target Local File Systems.950server # [ 1.780749] systemd[1]: Starting Rule-based Manager for Device Events and Files...951builder # [ 1.770868] systemd[1]: Starting Rule-based Manager for Device Events and Files...952builder # [ 1.787269] systemd[1]: Finished Load Kernel Modules.953builder # [ 1.789589] systemd[1]: Starting Apply Kernel Variables...954server # [ 1.814297] systemd[1]: Finished Apply Kernel Variables.955builder # [ 1.804748] systemd[1]: Started Journal Service.956builder # [ 1.793252] systemd-modules-load[72]: Inserted module 'dm_mod'957builder # [ 1.794505] systemd-modules-load[72]: Module 'virtio_balloon' is built in958server # [ 1.808466] systemd-modules-load[72]: Inserted module 'dm_mod'959server # [ 1.809592] systemd-modules-load[72]: Module 'virtio_balloon' is built in960server # [ 1.810795] systemd-modules-load[72]: Module 'virtio_console' is built in961server # [ 1.811888] systemd-modules-load[72]: Inserted module 'virtio_gpu'962builder # [ 1.808345] systemd-modules-load[72]: Module 'virtio_console' is built in963server # [ 1.830621] systemd[1]: Started Journal Service.964builder # [ 1.812392] systemd-modules-load[72]: Inserted module 'virtio_gpu'965builder # [ 1.816298] systemd-modules-load[72]: Module 'virtio_rng' is built in966server # [ 1.828422] systemd-modules-load[72]: Module 'virtio_rng' is built in967server # [ 1.829490] systemd[1]: Starting Create System Files and Directories...968builder # [ 1.820316] systemd[1]: Starting Create System Files and Directories...969builder # [ 1.845275] systemd[1]: Finished Apply Kernel Variables.970server # [ 1.862416] systemd-udevd[79]: Using default interface naming scheme 'v261'.971builder # [ 1.865328] systemd[1]: Finished Create System Files and Directories.972server # [ 1.878044] systemd[1]: Finished Create System Files and Directories.973builder # [ 1.877397] systemd-udevd[78]: Using default interface naming scheme 'v261'.974server # [ 1.904108] systemd[1]: Started Rule-based Manager for Device Events and Files.975builder # [ 1.905534] systemd[1]: Started Rule-based Manager for Device Events and Files.976server # [ 1.978583] systemd[1]: Starting Virtual Console Setup...977builder # [ 1.974494] systemd[1]: Starting Virtual Console Setup...978server # [ 2.046282] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones.979server # [ 2.050113] systemd[1]: Finished Virtual Console Setup.980builder # [ 2.052507] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones.981builder # [ 2.056075] systemd[1]: Finished Virtual Console Setup.982server # [ 2.636763] systemd[1]: Finished Coldplug All udev Devices.983server # [ 2.637727] systemd[1]: Reached target System Initialization.984server # [ 2.638581] systemd[1]: Reached target Basic System.985builder # [ 2.661243] systemd[1]: Finished Coldplug All udev Devices.986builder # [ 2.664131] systemd[1]: Reached target System Initialization.987builder # [ 2.668131] systemd[1]: Reached target Basic System.988server # [ 2.775944] (udev-worker)[90]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.989builder # [ 2.800209] (udev-worker)[95]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.990server # [ 2.815591] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.991server # [ 2.822502] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line.992builder # [ 2.823478] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.993builder # [ 2.831251] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.994server # [ 2.902078] systemd[1]: Found device /dev/disk/by-label/nixos.995server # [ 2.916502] systemd[1]: Reached target Initrd Root Device.996server # [ 2.921111] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...997builder # [ 2.927121] systemd[1]: Found device /dev/disk/by-label/nixos.998builder # [ 2.931305] systemd[1]: Reached target Initrd Root Device.999builder # [ 2.934922] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1000server # [ 2.969326] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks1001server # [ 2.975494] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1002server # [ 2.980656] systemd[1]: Mounting /sysroot...1003builder # [ 2.981303] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks1004builder # [ 2.988139] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1005builder # [ 2.992136] systemd[1]: Mounting /sysroot...1006server # [ 3.031386] EXT4-fs (vda): mounted filesystem 5efadf8a-aa45-4366-93da-ff2053f1b62e r/w with ordered data mode. Quota mode: none.1007server # [ 3.018539] systemd[1]: Mounted /sysroot.1008server # [ 3.020779] systemd[1]: Reached target Initrd Root File System.1009server # [ 3.025971] systemd[1]: Starting Mountpoints Configured in the Real Root...1010builder # [ 3.042994] EXT4-fs (vda): mounted filesystem 357a2f32-0c41-4c35-a6f4-197a593c432e r/w with ordered data mode. Quota mode: none.1011builder # [ 3.033497] systemd[1]: Mounted /sysroot.1012builder # [ 3.037152] systemd[1]: Reached target Initrd Root File System.1013builder # [ 3.041040] systemd[1]: Starting Mountpoints Configured in the Real Root...1014server # [ 3.055545] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.1015server # [ 3.063422] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...1016server # [ 3.066491] systemd[1]: Reloading...1017builder # [ 3.069041] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.1018builder # [ 3.076091] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...1019builder # [ 3.080078] systemd[1]: Reloading...1020server # [ 3.285920] systemd[1]: Reloading finished in 221 ms.1021builder # [ 3.299469] systemd[1]: Reloading finished in 220 ms.1022server # [ 3.320867] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...1023server # [ 3.325546] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...1024server # [ 3.329982] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1025server # [ 3.333250] systemd[1]: Finished Mountpoints Configured in the Real Root.1026server # [ 3.336135] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1027builder # [ 3.326284] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...1028builder # [ 3.330786] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...1029builder # [ 3.337575] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1030builder # [ 3.340614] systemd[1]: Finished Mountpoints Configured in the Real Root.1031builder # [ 3.342692] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1032server # [ 3.610095] systemd[1]: Mounting /sysroot/nix/.ro-store...1033builder # [ 3.610978] systemd[1]: Mounting /sysroot/nix/.ro-store...1034server # [ 3.627271] systemd[1]: Mounting /sysroot/nix/.rw-store...1035server # [ 3.632092] systemd[1]: Mounting /sysroot/run...1036builder # [ 3.627579] systemd[1]: Mounting /sysroot/nix/.rw-store...1037builder # [ 3.631791] systemd[1]: Mounting /sysroot/run...1038server # [ 3.645359] systemd[1]: Mounting /sysroot/tmp/shared...1039builder # [ 3.642232] systemd[1]: Mounting /sysroot/tmp/shared...1040server # [ 3.663041] systemd[1]: Mounting /sysroot/tmp/xchg...1041builder # [ 3.680297] systemd[1]: Mounting /sysroot/tmp/xchg...1042server # [ 3.715245] systemd[1]: Mounted /sysroot/nix/.rw-store.1043builder # [ 3.728382] systemd[1]: Mounted /sysroot/nix/.rw-store.1044server # [ 3.759316] fuse: init (API version 7.45)1045server # [ 3.768766] virtiofs virtio6: discovered new tag: nix-store1046server # [ 3.769572] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1047builder # [ 3.761621] fuse: init (API version 7.45)1048server # [ 3.761183] systemd[1]: Starting rw-sysroot-nix-store.service...1049server # [ 3.784584] virtiofs virtio7: discovered new tag: shared1050server # [ 3.785360] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1051builder # [ 3.762033] systemd[1]: Starting rw-sysroot-nix-store.service...1052server # [ 3.773622] systemd[1]: Mounted /sysroot/run.1053builder # [ 3.775187] virtiofs virtio6: discovered new tag: nix-store1054builder # [ 3.776006] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1055builder # [ 3.770363] systemd[1]: Mounted /sysroot/run.1056server # [ 3.799256] virtiofs virtio8: discovered new tag: xchg1057server # [ 3.800076] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1058builder # [ 3.792822] virtiofs virtio7: discovered new tag: shared1059builder # [ 3.793595] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1060builder # [ 3.799035] virtiofs virtio8: discovered new tag: xchg1061builder # [ 3.803664] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1062server # [ 3.805213] systemd[1]: Mounted /sysroot/nix/.ro-store.1063builder # [ 3.797951] systemd[1]: Mounted /sysroot/nix/.ro-store.1064server # [ 3.812838] systemd[1]: Mounted /sysroot/tmp/shared.1065server # [ 3.813712] systemd[1]: Mounted /sysroot/tmp/xchg.1066builder # [ 3.816171] systemd[1]: Mounted /sysroot/tmp/shared.1067server # [ 3.827292] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1068server # [ 3.828581] systemd[1]: Finished rw-sysroot-nix-store.service.1069server # [ 3.829860] systemd[1]: Mounting /sysroot/nix/store...1070builder # [ 3.820401] systemd[1]: Mounted /sysroot/tmp/xchg.1071builder # [ 3.829800] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1072builder # [ 3.831220] systemd[1]: Finished rw-sysroot-nix-store.service.1073server # [ 3.878983] systemd[1]: Mounted /sysroot/nix/store.1074server # [ 3.881021] systemd[1]: Reached target Initrd File Systems.1075server # [ 3.883095] systemd[1]: Starting Find NixOS closure...1076server # [ 3.894754] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1077server # [ 3.917206] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1078server # [ 3.939827] systemd[1]: Finished Find NixOS closure.1079server # [ 3.943443] systemd[1]: Reached target Initrd Default Target.1080server # [ 3.950098] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1081server # [ 3.981971] systemd[1]: Stopped target Initrd Default Target.1082server # [ 3.985703] systemd[1]: Stopped target Basic System.1083server # [ 3.986807] systemd[1]: Stopped target Initrd Root Device.1084server # [ 3.988288] systemd[1]: Stopped target Path Units.1085server # [ 3.993399] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1086server # [ 3.995240] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1087server # [ 3.998394] systemd[1]: Stopped target Slice Units.1088server # [ 4.004685] systemd[1]: Stopped target Socket Units.1089server # [ 4.006247] systemd[1]: Stopped target System Initialization.1090server # [ 4.008115] systemd[1]: Stopped target Swaps.1091server # [ 4.009647] systemd[1]: Stopped target Timer Units.1092server # [ 4.010944] systemd[1]: dbus.socket: Deactivated successfully.1093server # [ 4.020501] systemd[1]: Closed D-Bus System Message Bus Socket.1094server # [ 4.022576] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1095server # [ 4.026979] systemd[1]: Stopped Find NixOS closure.1096server # [ 4.031910] systemd[1]: Starting rw-sysroot-nix-store.service...1097server # [ 4.034708] systemd[1]: systemd-sysctl.service: Deactivated successfully.1098server # [ 4.035692] systemd[1]: Stopped Apply Kernel Variables.1099server # [ 4.039977] systemd[1]: systemd-modules-load.service: Deactivated successfully.1100server # [ 4.044938] systemd[1]: Stopped Load Kernel Modules.1101server # [ 4.046497] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1102server # [ 4.052294] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1103server # [ 4.053481] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1104server # [ 4.054607] systemd[1]: Stopped Create System Files and Directories.1105server # [ 4.057957] systemd[1]: Stopped target Local File Systems.1106server # [ 4.058811] systemd[1]: Stopped target Preparation for Local File Systems.1107server # [ 4.061580] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1108server # [ 4.062682] systemd[1]: Stopped Coldplug All udev Devices.1109server # [ 4.063486] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1110server # [ 4.064642] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1111server # [ 4.065686] systemd[1]: Stopped Virtual Console Setup.1112server # [ 4.066500] systemd[1]: initrd-cleanup.service: Deactivated successfully.1113server # [ 4.067455] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1114server # [ 4.073616] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1115server # [ 4.076232] systemd[1]: Finished rw-sysroot-nix-store.service.1116server # [ 4.079837] systemd[1]: systemd-udevd.service: Deactivated successfully.1117server # [ 4.081329] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1118server # [ 4.082456] systemd[1]: systemd-udevd.service: Consumed 1.123s CPU time over 2.307s wall clock time, 17.9M memory peak.1119server # [ 4.083898] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1120server # [ 4.085067] systemd[1]: Closed udev Control Socket.1121server # [ 4.085780] systemd[1]: Starting Cleanup udev Database...1122server # [ 4.086567] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1123server # [ 4.087628] systemd[1]: Stopped Create Static Device Nodes in /dev.1124server # [ 4.092182] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1125server # [ 4.093392] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1126server # [ 4.095295] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1127server # [ 4.100594] systemd[1]: Stopped Create List of Static Device Nodes.1128server # [ 4.126161] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1129server # [ 4.128524] systemd[1]: Finished Cleanup udev Database.1130server # [ 4.130389] systemd[1]: Reached target Switch Root.1131server # [ 4.132575] systemd[1]: Starting NixOS Activation...1132server # [ 4.208392] initrd-nixos-activation-start[169]: booting system configuration /nix/store/4ac77i24693xz8b1kw7ay9a87rnxg6f6-nixos-system-server-test1133server # [ 4.238513] initrd-nixos-activation-start[169]: running activation script...1134builder # [ 4.286776] (udev-worker)[94]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1135builder # [ 4.292551] (udev-worker)[94]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1136builder # [ 4.323317] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1137builder # [ 4.325834] systemd[1]: Stopped Virtual Console Setup.1138builder # [ 4.328159] systemd[1]: Stopping Virtual Console Setup...1139builder # [ 4.329014] systemd[1]: Starting Virtual Console Setup...1140builder # [ 4.358348] systemd-vconsole-setup[153]: Configuration of first virtual console was skipped, ignoring remaining ones.1141builder # [ 4.361814] systemd[1]: Finished Virtual Console Setup.1142server # [ 4.476503] initrd-nixos-activation-start[192]: setting up /etc...1143server # [ 4.591587] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1144server # [ 4.592944] systemd[1]: Finished NixOS Activation.1145server # [ 4.594030] systemd[1]: Starting Switch Root...1146server # [ 4.618447] systemd[1]: Switching root.1147builder # [ 4.608959] systemd[1]: Mounting /sysroot/nix/store...1148builder # [ 4.656279] systemd[1]: Mounted /sysroot/nix/store.1149builder # [ 4.659645] systemd[1]: Reached target Initrd File Systems.1150builder # [ 4.661706] systemd[1]: Starting Find NixOS closure...1151builder # [ 4.669037] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1152builder # [ 4.709035] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1153builder # [ 4.711739] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1154builder # [ 4.722730] systemd[1]: Finished Find NixOS closure.1155builder # [ 4.725337] systemd[1]: Reached target Initrd Default Target.1156builder # [ 4.728183] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1157builder # [ 4.753741] systemd[1]: Stopped target Initrd Default Target.1158builder # [ 4.755892] systemd[1]: Stopped target Basic System.1159builder # [ 4.757310] systemd[1]: Stopped target Initrd Root Device.1160builder # [ 4.760388] systemd[1]: Stopped target Path Units.1161builder # [ 4.761430] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1162builder # [ 4.764225] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1163builder # [ 4.768154] systemd[1]: Stopped target Slice Units.1164builder # [ 4.769048] systemd[1]: Stopped target Socket Units.1165builder # [ 4.770773] systemd[1]: Stopped target System Initialization.1166builder # [ 4.775622] systemd[1]: Stopped target Swaps.1167builder # [ 4.776487] systemd[1]: Stopped target Timer Units.1168builder # [ 4.777267] systemd[1]: dbus.socket: Deactivated successfully.1169builder # [ 4.778149] systemd[1]: Closed D-Bus System Message Bus Socket.1170builder # [ 4.779043] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1171server # [ 4.811171] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1172builder # [ 4.788656] systemd[1]: Stopped Find NixOS closure.1173builder # [ 4.789450] systemd[1]: Starting rw-sysroot-nix-store.service...1174builder # [ 4.790443] systemd[1]: systemd-sysctl.service: Deactivated successfully.1175builder # [ 4.791410] systemd[1]: Stopped Apply Kernel Variables.1176builder # [ 4.805491] systemd[1]: systemd-modules-load.service: Deactivated successfully.1177builder # [ 4.806617] systemd[1]: Stopped Load Kernel Modules.1178builder # [ 4.807372] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1179builder # [ 4.812510] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1180builder # [ 4.816160] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1181builder # [ 4.817840] systemd[1]: Stopped Create System Files and Directories.1182builder # [ 4.819663] systemd[1]: Stopped target Local File Systems.1183builder # [ 4.824237] systemd[1]: Stopped target Preparation for Local File Systems.1184builder # [ 4.825252] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1185builder # [ 4.826276] systemd[1]: Stopped Coldplug All udev Devices.1186builder # [ 4.827928] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1187builder # [ 4.829290] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1188builder # [ 4.830370] systemd[1]: Stopped Virtual Console Setup.1189builder # [ 4.831156] systemd[1]: initrd-cleanup.service: Deactivated successfully.1190builder # [ 4.832191] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1191builder # [ 4.833131] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1192builder # [ 4.834149] systemd[1]: Finished rw-sysroot-nix-store.service.1193builder # [ 4.834998] systemd[1]: systemd-udevd.service: Deactivated successfully.1194builder # [ 4.835954] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1195builder # [ 4.841494] systemd[1]: systemd-udevd.service: Consumed 1.493s CPU time over 3.058s wall clock time, 22.3M memory peak.1196builder # [ 4.848199] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1197builder # [ 4.849477] systemd[1]: Closed udev Control Socket.1198builder # [ 4.850365] systemd[1]: Starting Cleanup udev Database...1199builder # [ 4.851268] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1200builder # [ 4.856241] systemd[1]: Stopped Create Static Device Nodes in /dev.1201builder # [ 4.857147] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1202builder # [ 4.858303] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1203builder # [ 4.859393] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1204builder # [ 4.864160] systemd[1]: Stopped Create List of Static Device Nodes.1205builder # [ 4.889517] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1206builder # [ 4.892834] systemd[1]: Finished Cleanup udev Database.1207builder # [ 4.893683] systemd[1]: Reached target Switch Root.1208builder # [ 4.896422] systemd[1]: Starting NixOS Activation...1209builder # [ 4.974099] initrd-nixos-activation-start[176]: booting system configuration /nix/store/gwcipgaswac59x6rcnm9zqcjjm3mx039-nixos-system-builder-test1210builder # [ 5.004796] initrd-nixos-activation-start[176]: running activation script...1211builder # [ 5.231000] initrd-nixos-activation-start[199]: setting up /etc...1212server # [ 5.307604] systemd[1]: systemd 261.3 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)1213server # [ 5.307663] systemd[1]: Detected virtualization qemu.1214server # [ 5.307710] systemd[1]: Detected architecture arm64.1215server # [ 5.307820] systemd[1]: Detected first boot.1216server # [ 5.319187] systemd[1]: Initializing machine ID from random generator.1217builder # [ 5.347622] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1218builder # [ 5.350733] systemd[1]: Finished NixOS Activation.1219builder # [ 5.351887] systemd[1]: Starting Switch Root...1220builder # [ 5.376105] systemd[1]: Switching root.1221server # [ 5.466697] systemd[1]: bpf-restrict-fs: LSM BPF program attached1222builder # [ 5.560270] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1223server # [ 5.614692] systemd[1]: Applying preset policy.1224server # [ 5.821826] systemd[1]: Populated /etc with preset unit settings.1225server # [ 6.034943] systemd[1]: initrd-switch-root.service: Deactivated successfully.1226server # [ 6.035714] systemd[1]: Stopped initrd-switch-root.service.1227server # [ 6.040214] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1228server # [ 6.043015] systemd[1]: Created slice Slice /system/getty.1229server # [ 6.044286] systemd[1]: Created slice User and Session Slice.1230server # [ 6.044805] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1231server # [ 6.045374] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1232server # [ 6.049787] systemd[1]: Expecting device /dev/hvc0...1233server # [ 6.050168] systemd[1]: Expecting device /dev/ttyAMA0...1234server # [ 6.050744] systemd[1]: Reached target Local Encrypted Volumes.1235server # [ 6.051285] systemd[1]: Stopped target initrd-fs.target.1236server # [ 6.051796] systemd[1]: Stopped target initrd-root-fs.target.1237server # [ 6.052336] systemd[1]: Stopped target initrd-switch-root.target.1238server # [ 6.052869] systemd[1]: Reached target Virtual Machines and Containers.1239server # [ 6.053403] systemd[1]: Reached target Path Units.1240server # [ 6.060078] systemd[1]: Reached target Remote File Systems.1241server # [ 6.060448] systemd[1]: Reached target Slice Units.1242server # [ 6.061019] systemd[1]: Reached target Swaps.1243server # [ 6.065420] systemd[1]: Listening on Query the User Interactively for a Password.1244server # [ 6.068442] systemd[1]: Listening on Process Core Dump Socket.1245server # [ 6.070670] systemd[1]: Listening on Credential Encryption/Decryption.1246server # [ 6.072289] systemd[1]: Listening on Factory Reset Management.1247server # [ 6.072774] systemd[1]: Listening on Hostname Service Socket.1248server # [ 6.078612] systemd[1]: Starting Journal Log Access Socket...1249server # [ 6.080274] systemd[1]: Listening on Journal Audit Socket.1250builder # [ 6.059287] systemd[1]: systemd 261.3 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)1251builder # [ 6.059344] systemd[1]: Detected virtualization qemu.1252builder # [ 6.059400] systemd[1]: Detected architecture arm64.1253builder # [ 6.059504] systemd[1]: Detected first boot.1254server # [ 6.083927] systemd[1]: Listening on Console Output Muting Service Socket.1255builder # [ 6.061221] systemd[1]: Initializing machine ID from random generator.1256server # [ 6.084602] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1257server # [ 6.085060] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1258server # [ 6.089436] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1259server # [ 6.095769] systemd[1]: Listening on Disk Repartitioning Service Socket.1260server # [ 6.096327] systemd[1]: Listening on udev Control Socket.1261server # [ 6.096969] systemd[1]: Listening on udev Varlink Socket.1262server # [ 6.102277] systemd[1]: Mounting Huge Pages File System...1263server # [ 6.105028] systemd[1]: Mounting POSIX Message Queue File System...1264server # [ 6.121726] systemd[1]: Mounting Kernel Debug File System...1265server # [ 6.131402] systemd[1]: Mounting Kernel Trace File System...1266server # [ 6.146206] systemd[1]: Starting Create List of Static Device Nodes...1267server # [ 6.146725] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1268server # [ 6.159488] systemd[1]: Mounting Kernel Configuration File System...1269server # [ 6.162589] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1270server # [ 6.168198] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1271server # [ 6.169219] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1272server # [ 6.203022] systemd[1]: Mounting FUSE Control File System...1273server # [ 6.203490] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671274builder # [ 6.199604] systemd[1]: bpf-restrict-fs: LSM BPF program attached1275server # [ 6.231936] systemd[1]: Starting Journal Service...1276server # [ 6.248952] systemd[1]: Starting Load Kernel Modules...1277server # [ 6.274386] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1278server # [ 6.283838] systemd[1]: Starting Remount Root and Kernel File Systems...1279server # [ 6.284214] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1280server # [ 6.304677] systemd[1]: Starting Coldplug All udev Devices...1281server # [ 6.315674] systemd[1]: Listening on Journal Log Access Socket.1282server # [ 6.316146] systemd[1]: Mounted Huge Pages File System.1283server # [ 6.316535] systemd[1]: Mounted POSIX Message Queue File System.1284server # [ 6.316898] systemd[1]: Mounted Kernel Debug File System.1285server # [ 6.317255] systemd[1]: Mounted Kernel Trace File System.1286builder # [ 6.324994] systemd[1]: Applying preset policy.1287server # [ 6.350138] systemd[1]: Finished Create List of Static Device Nodes.1288server # [ 6.366218] systemd-journald[263]: Collecting audit messages is enabled.1289server # [ 6.374157] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1290server # [ 6.374751] systemd[1]: Queued start job for default target Multi-User System.1291server # [ 6.394512] systemd[1]: Started Journal Service.1292server # [ 6.381987] systemd[1]: systemd-journald.service: Deactivated successfully.1293server # [ 6.383414] systemd-modules-load[264]: Module 'atkbd' is built in1294server # [ 6.397932] systemd-modules-load[264]: Module 'loop' is built in1295server # [ 6.399004] systemd-modules-load[264]: Inserted module 'tls'1296server # [ 6.399944] systemd[1]: Finished Load Kernel Modules.1297server # [ 6.429805] EXT4-fs (vda): re-mounted 5efadf8a-aa45-4366-93da-ff2053f1b62e.1298server # [ 6.432159] systemd[1]: Finished Remount Root and Kernel File Systems.1299server # [ 6.441738] systemd[1]: Mounted Kernel Configuration File System.1300server # [ 6.444772] systemd[1]: Listening on Disk Image Download Service Socket.1301server # [ 6.457628] systemd[1]: Starting Firewall...1302server # [ 6.464419] systemd-oomd[265]: No swap; memory pressure usage will be degraded1303server # [ 6.470478] systemd[1]: Starting Flush Journal to Persistent Storage...1304server # [ 6.471470] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1305server # [ 6.483467] systemd[1]: Starting Load/Save OS Random Seed...1306server # [ 6.505275] systemd[1]: Starting Apply Kernel Variables...1307server # [ 6.506170] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1308server # [ 6.507410] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1309server # [ 6.518733] systemd[1]: Mounted FUSE Control File System.1310builder # [ 6.530684] systemd[1]: Populated /etc with preset unit settings.1311server # [ 6.569894] systemd-journald[263]: Received client request to flush runtime journal.1312server # [ 6.656421] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1313server # [ 6.657641] systemd[1]: Starting Create Static Device Nodes in /dev...1314server # [ 6.665941] systemd[1]: Finished Load/Save OS Random Seed.1315server # [ 6.676773] systemd[1]: Reached target First Boot Complete.1316server # [ 6.682944] systemd[1]: Finished Flush Journal to Persistent Storage.1317server # [ 6.696974] systemd[1]: Finished Apply Kernel Variables.1318builder # [ 6.740323] systemd[1]: initrd-switch-root.service: Deactivated successfully.1319builder # [ 6.741080] systemd[1]: Stopped initrd-switch-root.service.1320builder # [ 6.745546] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1321builder # [ 6.747007] systemd[1]: Created slice Slice /system/getty.1322builder # [ 6.750810] systemd[1]: Created slice User and Session Slice.1323builder # [ 6.752927] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1324builder # [ 6.753398] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1325builder # [ 6.753922] systemd[1]: Expecting device /dev/hvc0...1326builder # [ 6.754471] systemd[1]: Expecting device /dev/ttyAMA0...1327server # [ 6.755001] systemd[1]: Finished Create Static Device Nodes in /dev.1328builder # [ 6.755031] systemd[1]: Reached target Local Encrypted Volumes.1329builder # [ 6.760163] systemd[1]: Stopped target initrd-fs.target.1330server # [ 6.760132] systemd[1]: Reached target Preparation for Local File Systems.1331builder # [ 6.760538] systemd[1]: Stopped target initrd-root-fs.target.1332builder # [ 6.761117] systemd[1]: Stopped target initrd-switch-root.target.1333builder # [ 6.761666] systemd[1]: Reached target Virtual Machines and Containers.1334server # [ 6.762450] systemd[1]: Starting Rule-based Manager for Device Events and Files...1335builder # [ 6.762220] systemd[1]: Reached target Path Units.1336builder # [ 6.762829] systemd[1]: Reached target Remote File Systems.1337builder # [ 6.768274] systemd[1]: Reached target Slice Units.1338builder # [ 6.768643] systemd[1]: Reached target Swaps.1339builder # [ 6.770725] systemd[1]: Listening on Query the User Interactively for a Password.1340builder # [ 6.775488] systemd[1]: Listening on Process Core Dump Socket.1341builder # [ 6.776910] systemd[1]: Listening on Credential Encryption/Decryption.1342builder # [ 6.778473] systemd[1]: Listening on Factory Reset Management.1343builder # [ 6.778939] systemd[1]: Listening on Hostname Service Socket.1344builder # [ 6.785108] systemd[1]: Starting Journal Log Access Socket...1345builder # [ 6.786551] systemd[1]: Listening on Journal Audit Socket.1346builder # [ 6.790578] systemd[1]: Listening on Console Output Muting Service Socket.1347builder # [ 6.792726] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1348builder # [ 6.794044] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1349builder # [ 6.797319] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1350builder # [ 6.801909] systemd[1]: Listening on Disk Repartitioning Service Socket.1351builder # [ 6.802453] systemd[1]: Listening on udev Control Socket.1352builder # [ 6.802935] systemd[1]: Listening on udev Varlink Socket.1353builder # [ 6.807648] systemd[1]: Mounting Huge Pages File System...1354builder # [ 6.810490] systemd[1]: Mounting POSIX Message Queue File System...1355builder # [ 6.827176] systemd[1]: Mounting Kernel Debug File System...1356builder # [ 6.837270] systemd[1]: Mounting Kernel Trace File System...1357builder # [ 6.849887] systemd[1]: Starting Create List of Static Device Nodes...1358builder # [ 6.850415] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1359builder # [ 6.867539] systemd[1]: Mounting Kernel Configuration File System...1360builder # [ 6.868025] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1361builder # [ 6.874044] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1362builder # [ 6.874541] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1363builder # [ 6.901810] systemd[1]: Mounting FUSE Control File System...1364builder # [ 6.902257] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671365builder # [ 6.931407] systemd[1]: Starting Journal Service...1366builder # [ 6.947127] systemd[1]: Starting Load Kernel Modules...1367builder # [ 6.964267] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1368server # [ 6.983769] systemd-udevd[301]: Using default interface naming scheme 'v261'.1369builder # [ 6.992897] systemd[1]: Starting Remount Root and Kernel File Systems...1370builder # [ 6.993277] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1371builder # [ 7.020127] systemd[1]: Starting Coldplug All udev Devices...1372builder # [ 7.022845] systemd[1]: Listening on Journal Log Access Socket.1373server # [ 7.024919] systemd[1]: Mounting /run/wrappers...1374builder # [ 7.027647] systemd-journald[269]: Collecting audit messages is enabled.1375builder # [ 7.038402] systemd[1]: Mounted Huge Pages File System.1376builder # [ 7.038880] systemd[1]: Mounted POSIX Message Queue File System.1377builder # [ 7.032500] systemd[1]: Queued start job for default target Multi-User System.1378builder # [ 7.037968] systemd[1]: systemd-journald.service: Deactivated successfully.1379builder # [ 7.039387] systemd-modules-load[270]: Module 'atkbd' is built in[ 7.051494] systemd[1]: Started Journal Service.1380builder # 1381builder # [ 7.048491] systemd-modules-load[270]: Module 'loop' is built in1382builder # [ 7.049522] systemd[1]: Mounted Kernel Debug File System.1383builder # [ 7.050457] systemd[1]: Mounted Kernel Trace File System.1384builder # [ 7.051448] systemd[1]: Finished Create List of Static Device Nodes.1385builder # [ 7.063278] systemd[1]: Finished Load Kernel Modules.1386server # [ 7.088888] systemd[1]: Mounted /run/wrappers.1387builder # [ 7.078273] systemd[1]: Mounted Kernel Configuration File System.1388server # [ 7.089752] systemd[1]: Reached target Local File Systems.1389server # [ 7.094300] systemd[1]: Listening on Boot Loader Control Service Socket.1390server # [ 7.103628] systemd[1]: Starting register-nix-paths.service...1391server # [ 7.109375] systemd[1]: Starting Create SUID/SGID Wrappers...1392builder # [ 7.099526] systemd[1]: Starting Firewall...1393server # [ 7.110298] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1394builder # [ 7.115371] EXT4-fs (vda): re-mounted 357a2f32-0c41-4c35-a6f4-197a593c432e.1395server # [ 7.118646] systemd[1]: Starting Save Transient machine-id to Disk...1396builder # [ 7.114255] systemd[1]: Starting Apply Kernel Variables...1397builder # [ 7.117596] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1398server # [ 7.136229] systemd[1]: Starting Create System Files and Directories...1399builder # [ 7.127282] systemd-oomd[271]: No swap; memory pressure usage will be degraded1400builder # [ 7.129251] systemd[1]: Finished Remount Root and Kernel File Systems.1401builder # [ 7.130223] systemd[1]: Listening on Disk Image Download Service Socket.1402builder # [ 7.145511] systemd[1]: Starting Flush Journal to Persistent Storage...1403builder # [ 7.146565] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1404server # [ 7.174654] systemd[1]: Started Rule-based Manager for Device Events and Files.1405builder # [ 7.189011] systemd[1]: Starting Load/Save OS Random Seed...1406builder # [ 7.190065] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1407builder # [ 7.199558] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1408builder # [ 7.201270] systemd[1]: Mounted FUSE Control File System.1409builder # [ 7.295979] systemd-journald[269]: Received client request to flush runtime journal.1410server # [ 7.308469] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1411server # [ 7.327751] systemd[1]: Finished Save Transient machine-id to Disk.1412builder # [ 7.340593] systemd[1]: Finished Apply Kernel Variables.1413builder # [ 7.341628] systemd[1]: Finished Load/Save OS Random Seed.1414builder # [ 7.342446] systemd[1]: Reached target First Boot Complete.1415server # [ 7.364084] systemd[1]: Finished Create System Files and Directories.1416builder # [ 7.357428] systemd[1]: Finished Flush Journal to Persistent Storage.1417server # [ 7.381166] systemd[1]: Starting Rebuild Journal Catalog...1418server # [ 7.396180] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1419builder # [ 7.417808] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1420builder # [ 7.419939] systemd[1]: Starting Create Static Device Nodes in /dev...1421server # [ 7.544361] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1422builder # [ 7.546438] systemd[1]: Finished Create Static Device Nodes in /dev.1423builder # [ 7.547580] systemd[1]: Reached target Preparation for Local File Systems.1424builder # [ 7.552917] systemd[1]: Starting Rule-based Manager for Device Events and Files...1425server # [ 7.606825] systemd[1]: Finished Rebuild Journal Catalog.1426server # [ 7.621866] systemd[1]: Starting Update is Completed...1427builder # [ 7.695144] systemd-udevd[311]: Using default interface naming scheme 'v261'.1428server # [ 7.718204] systemd[1]: Finished Update is Completed.1429builder # [ 7.734333] systemd[1]: Mounting /run/wrappers...1430builder # [ 7.789163] systemd[1]: Mounted /run/wrappers.1431builder # [ 7.790005] systemd[1]: Reached target Local File Systems.1432builder # [ 7.796216] systemd[1]: Listening on Boot Loader Control Service Socket.1433builder # [ 7.800692] systemd[1]: Starting register-nix-paths.service...1434builder # [ 7.812333] systemd[1]: Starting Create SUID/SGID Wrappers...1435builder # [ 7.813265] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1436builder # [ 7.820194] systemd[1]: Starting Save Transient machine-id to Disk...1437builder # [ 7.874443] systemd[1]: Starting Create System Files and Directories...1438builder # [ 7.875593] systemd[1]: Started Rule-based Manager for Device Events and Files.1439builder # [ 7.972121] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1440builder # [ 7.985256] systemd[1]: Finished Save Transient machine-id to Disk.1441builder # [ 8.075261] systemd[1]: Finished Create System Files and Directories.1442builder # [ 8.079853] systemd[1]: Starting Rebuild Journal Catalog...1443builder # [ 8.089809] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1444builder # [ 8.217160] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1445builder # [ 8.259904] systemd[1]: Finished Rebuild Journal Catalog.1446builder # [ 8.276380] systemd[1]: Starting Update is Completed...1447builder # [ 8.364301] systemd[1]: Finished Update is Completed.1448server # [ 8.501282] systemd[1]: Finished Coldplug All udev Devices.1449server # [ 8.581517] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1450server # [ 8.586873] systemd[1]: Finished Create SUID/SGID Wrappers.1451server # [ 8.661476] systemd[1]: Finished register-nix-paths.service.1452server # [ 8.667256] systemd[1]: Reached target System Initialization.1453server # [ 8.672245] systemd[1]: Started Discard unused filesystem blocks once a week.1454server # [ 8.673292] systemd[1]: Started niks3 garbage collection timer.1455server # [ 8.674281] systemd[1]: Started Daily Cleanup of Temporary Directories.1456server # [ 8.675236] systemd[1]: Reached target Timer Units.1457server # [ 8.675963] systemd[1]: Listening on D-Bus System Message Bus Socket.1458server # [ 8.682979] systemd[1]: Starting niks3 server proxy socket...1459server # [ 8.689224] systemd[1]: Listening on niks3 server socket.1460server # [ 8.690110] systemd[1]: Listening on Nix Daemon Socket.1461server # [ 8.690911] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1462server # [ 8.704165] systemd[1]: Starting D-Bus System Message Bus...1463server # [ 8.705029] systemd[1]: Listening on niks3 server proxy socket.1464server # [ 8.705872] systemd[1]: Reached target Socket Units.1465server # [ 8.730510] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31466server # [ 8.803575] dbus-broker-launch[450]: Looking up NSS user entry for 'systemd-timesync'...1467server # [ 8.818403] dbus-broker-launch[450]: NSS returned no entry for 'systemd-timesync'1468server # [ 8.819539] dbus-broker-launch[450]: Invalid user-name in /nix/store/giz7hl6796gi93pnvz01dlsai95dj1mr-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1469server # [ 8.851926] systemd[1]: Started D-Bus System Message Bus.1470server # [ 8.853770] systemd[1]: Reached target Basic System.1471server # [ 8.858854] systemd[1]: Starting Import lastlog data into lastlog2 database...1472server # [ 8.865776] systemd[1]: Starting Generate test mTLS certs...1473server # [ 8.880109] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1474server # [ 8.885658] systemd[1]: Starting Post-Boot Actions...1475builder # [ 8.885067] systemd[1]: Finished Coldplug All udev Devices.1476server # [ 8.908699] dbus-broker-launch[450]: Ready1477server # [ 8.925926] systemd[1]: Started Reset console on configuration changes.1478server # [ 8.948350] systemd[1]: Starting resolvconf update...1479builder # [ 8.966148] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1480builder # [ 9.024613] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1481builder # [ 9.037425] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1482builder # [ 9.040864] systemd[1]: Finished Create SUID/SGID Wrappers.1483server # [ 9.070229] systemd[1]: Finished Post-Boot Actions.1484server # [ 9.093353] systemd[1]: Started Name Service Cache Daemon (nsncd).1485server # [ 9.107552] systemd[1]: Reached target Host and Network Name Lookups.1486server # [ 9.108991] systemd[1]: Reached target User and Group Name Lookups.1487builder # [ 9.101065] systemd[1]: Finished register-nix-paths.service.1488builder # [ 9.107474] systemd[1]: Reached target System Initialization.1489builder # [ 9.109512] systemd[1]: Started Discard unused filesystem blocks once a week.1490server # [ 9.121121] systemd[1]: Starting User Login Management...1491builder # [ 9.110590] systemd[1]: Started Daily Cleanup of Temporary Directories.1492builder # [ 9.111570] systemd[1]: Reached target Timer Units.1493builder # [ 9.117407] systemd[1]: Listening on D-Bus System Message Bus Socket.1494builder # [ 9.118402] systemd[1]: Starting niks3 auto-upload socket...1495builder # [ 9.119264] systemd[1]: Listening on Nix Daemon Socket.1496server # [ 9.129963] nsncd[466]: Oct 08 12:58:19.675 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1497builder # [ 9.128938] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1498builder # [ 9.130252] systemd[1]: Starting D-Bus System Message Bus...1499builder # [ 9.137094] systemd[1]: Listening on niks3 auto-upload socket.1500builder # [ 9.138065] systemd[1]: Reached target Socket Units.1501server # [ 9.160427] niks3-test-certs-start[484]: -----1502server # [ 9.194562] systemd[1]: Finished Import lastlog data into lastlog2 database.1503server # [ 9.218584] niks3-test-certs-start[506]: -----1504builder # [ 9.235240] dbus-broker-launch[459]: Looking up NSS user entry for 'systemd-timesync'...1505builder # [ 9.245179] dbus-broker-launch[459]: NSS returned no entry for 'systemd-timesync'1506builder # [ 9.246345] dbus-broker-launch[459]: Invalid user-name in /nix/store/zmckf4f4jvq3fy67p01c0ij4v0qggb3x-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1507builder # [ 9.264485] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1508builder # [ 9.275476] systemd[1]: Started D-Bus System Message Bus.1509builder # [ 9.281374] systemd[1]: Reached target Basic System.1510builder # [ 9.287206] systemd[1]: Starting Import lastlog data into lastlog2 database...1511builder # [ 9.297028] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1512builder # [ 9.315932] dbus-broker-launch[459]: Ready1513builder # [ 9.334385] systemd[1]: Starting Post-Boot Actions...1514server # [ 9.346043] niks3-test-certs-start[512]: Certificate request self-signature ok1515server # [ 9.347209] niks3-test-certs-start[512]: subject=CN=server1516builder # [ 9.347317] systemd[1]: Started Reset console on configuration changes.1517server # [ 9.357463] systemd-logind[495]: New seat seat0.1518server # [ 9.373263] systemd[1]: Started User Login Management.1519server # [ 9.383968] systemd[1]: Starting linger-users.service...1520builder # [ 9.373626] systemd[1]: Starting resolvconf update...1521builder # [ 9.380128] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1522server # [ 9.404926] niks3-test-certs-start[539]: -----1523builder # [ 9.455238] systemd[1]: Started backdoor.service.1524server # [ 9.487556] systemd[1]: Stopped target Host and Network Name Lookups.1525server # [ 9.490832] systemd[1]: Stopping Host and Network Name Lookups...1526server # [ 9.491812] systemd[1]: Stopped target User and Group Name Lookups.1527server # [ 9.500711] systemd[1]: Stopping User and Group Name Lookups...1528server # [ 9.501885] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1529server # [ 9.502884] systemd[1]: nscd.service: Deactivated successfully.1530server # [ 9.519447] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1531server # [ 9.524303] systemd[1]: linger-users.service: Deactivated successfully.1532server # [ 9.531721] systemd[1]: Finished linger-users.service.1533server # [ 9.536919] niks3-test-certs-start[551]: Certificate request self-signature ok1534server # [ 9.537967] niks3-test-certs-start[551]: subject=CN=niks3 test client1535builder # [ 9.527312] systemd[1]: Finished Post-Boot Actions.1536server # [ 9.558223] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1537builder # [ 9.568528] nsncd[475]: Oct 08 12:58:20.142 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1538builder # [ 9.580630] systemd[1]: Started Name Service Cache Daemon (nsncd).1539builder # [ 9.581652] systemd[1]: Finished Firewall.1540server # [ 9.600999] systemd[1]: Finished Generate test mTLS certs.1541builder # [ 9.591907] systemd[1]: Reached target Host and Network Name Lookups.1542builder # [ 9.600205] systemd[1]: Reached target User and Group Name Lookups.1543builder # [ 9.619303] systemd[1]: Starting User Login Management...1544server # [ 9.642487] systemd[1]: Started Name Service Cache Daemon (nsncd).1545server # [ 9.648804] systemd[1]: Reached target Host and Network Name Lookups.1546server # [ 9.649886] systemd[1]: Reached target User and Group Name Lookups.1547server # [ 9.656482] nsncd[567]: Oct 08 12:58:20.236 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1548server # [ 9.669706] systemd[1]: Finished resolvconf update.1549builder # [ 9.662965] systemd[1]: Finished Import lastlog data into lastlog2 database.1550server # [ 9.681151] systemd[1]: Starting DHCP Client...1551builder # connecting to host...1552server # [ 9.685076] systemd[1]: Finished Firewall.1553server # [ 9.688302] systemd[1]: Reached target Preparation for Network.1554server # [ 9.702826] systemd[1]: Starting Extra networking commands....1555builder # [ 9.748529] (udev-worker)[409]: Network interface NamePolicy= disabled on kernel command line.1556builder # [ 9.757854] (udev-worker)[338]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1557builder # [ 9.787295] (udev-worker)[338]: Network interface NamePolicy= disabled on kernel command line.1558builder # [ 9.797040] systemd-logind[509]: New seat seat0.1559builder # [ 9.802335] systemd[1]: Started User Login Management.1560builder # [ 9.810549] systemd[1]: Stopped target Host and Network Name Lookups.1561builder # [ 9.811618] systemd[1]: Stopping Host and Network Name Lookups...1562builder # [ 9.816884] systemd[1]: Stopped target User and Group Name Lookups.1563builder # [ 9.817911] systemd[1]: Stopping User and Group Name Lookups...1564builder # [ 9.818754] systemd[1]: Starting linger-users.service...1565builder # [ 9.825948] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1566builder # [ 9.837525] systemd[1]: nscd.service: Deactivated successfully.1567builder # [ 9.841285] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1568builder # [ 9.865916] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1569server # [ 9.922824] dhcpcd[603]: dhcpcd-10.3.2 starting1570server # [ 9.933596] systemd[1]: Starting Virtual Console Setup...1571builder # [ 9.927386] systemd[1]: linger-users.service: Deactivated successfully.1572server # [ 9.939707] dhcpcd[644]: dev: loaded udev1573server # [ 9.943368] dhcpcd[644]: no valid interfaces found1574builder # [ 9.932388] systemd[1]: Finished linger-users.service.1575server # [ 9.950181] dhcpcd[644]: no valid interfaces found1576builder # [ 9.946336] systemd[1]: Started Name Service Cache Daemon (nsncd).1577builder # [ 9.950170] systemd[1]: Reached target Host and Network Name Lookups.1578builder # [ 9.951465] nsncd[561]: Oct 08 12:58:20.528 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1579builder # [ 9.960209] systemd[1]: Reached target User and Group Name Lookups.1580server # [ 9.985384] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1581builder # [ 9.975297] systemd[1]: Finished resolvconf update.1582server # [ 9.989433] systemd[1]: Stopped Virtual Console Setup.1583builder # [ 9.979631] systemd[1]: Reached target Preparation for Network.1584builder # [ 9.984803] systemd[1]: Starting DHCP Client...1585builder # [ 9.996753] systemd[1]: Starting Extra networking commands....1586server # [ 10.016992] systemd[1]: Starting Virtual Console Setup...1587server # [ 10.033120] systemd[1]: Finished Extra networking commands..1588builder # [ 10.023367] systemd-logind[509]: Watching system buttons on /dev/input/event0 (gpio-keys)1589server # [ 10.041889] systemd[1]: Reached target Network.1590server # [ 10.045177] systemd[1]: Started Mock OIDC server for testing.1591server # [ 10.055340] systemd[1]: Starting Nginx Web Server...1592server # [ 10.073296] systemd[1]: Starting PostgreSQL Server...1593server # [ 10.085219] systemd[1]: Started RustFS S3-compatible object storage.1594server # [ 10.094148] systemd[1]: Starting Setup RustFS bucket...1595server # [ 10.101148] systemd[1]: Starting Permit User Sessions...1596server # [ 10.208986] systemd[1]: Finished Permit User Sessions.1597builder # [ 10.198527] dhcpcd[594]: dhcpcd-10.3.2 starting1598builder # [ 10.209359] dhcpcd[618]: dev: loaded udev1599builder # [ 10.210261] systemd[1]: Condition check resulted in Virtio network device being skipped.1600builder # [ 10.221402] systemd[1]: Starting Address configuration of eth1...1601server # [ 10.235955] systemd[1]: Started Getty on tty1.1602server # [ 10.261108] systemd[1]: Reached target Login Prompts.1603builder # [ 10.283779] 8021q: 802.1Q VLAN Support v1.81604builder # [ 10.388363] mousedev: PS/2 mouse device common for all mice1605builder # [ 10.379798] systemd[1]: Finished Extra networking commands..1606builder # [ 10.396785] 8021q: adding VLAN 0 to HW filter on device eth11607builder # [ 10.387215] systemd[1]: Reached target Network.1608builder # [ 10.400633] systemd[1]: Starting Permit User Sessions...1609builder # [ 10.441033] cfg80211: Loading compiled-in X.509 certificates for regulatory database1610builder # [ 10.434033] network-addresses-eth1-start[623]: adding address 192.168.1.1/24... done1611builder # [ 10.445751] network-addresses-eth1-start[623]: adding address 2001:db8:1::1/64... done1612server # [ 10.461314] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1613builder # [ 10.462703] systemd[1]: Finished Permit User Sessions.1614builder # [ 10.473739] systemd[1]: Started Getty on tty1.1615builder # [ 10.476698] systemd[1]: Reached target Login Prompts.1616builder # [ 10.480739] systemd[1]: Finished Address configuration of eth1.1617builder # [ 10.501126] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1618builder # [ 10.501611] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1619builder # [ 10.506087] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21620builder # [ 10.506404] cfg80211: failed to load regulatory.db1621builder # [ 10.497275] systemd-logind[509]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1622builder # [ 10.613676] 8021q: adding VLAN 0 to HW filter on device eth01623builder # [ 10.599997] dhcpcd[618]: eth0: waiting for carrier1624builder # [ 10.604105] dhcpcd[618]: eth0: carrier acquired1625builder # [ 10.616089] dhcpcd[618]: DUID 00:01:00:01:32:5a:4f:ed:52:54:00:12:34:561626builder # [ 10.617124] dhcpcd[618]: eth0: IAID 00:12:34:561627builder # [ 10.617785] dhcpcd[618]: eth0: adding address fe80::5054:ff:fe12:34561628builder # [ 10.627314] dhcpcd[618]: eth0: soliciting a DHCP lease1629builder # [ 10.632531] dhcpcd[618]: eth0: offered 10.0.2.15 from 10.0.2.21630builder # [ 10.640247] dhcpcd[618]: eth0: probing address 10.0.2.15/241631server # [ 10.723288] nginx-pre-start[694]: nginx: the configuration file /nix/store/pqnsqwbq81gxkalxaz4hqrrqzk3za9bm-nginx.conf syntax is ok1632server # [ 10.731325] nginx-pre-start[694]: nginx: configuration file /nix/store/pqnsqwbq81gxkalxaz4hqrrqzk3za9bm-nginx.conf test is successful1633server # [ 10.740850] mock-oidc-server[659]: Mock OIDC Server running1634server # [ 10.741728] mock-oidc-server[659]: OIDC Address: 127.0.0.1:80801635server # [ 10.742599] mock-oidc-server[659]: Issue Address: 127.0.0.1:80811636server # [ 10.743453] mock-oidc-server[659]: Issuer: http://127.0.0.1:8080/oidc1637server # [ 10.758183] mock-oidc-server[659]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json1638server # [ 10.759373] mock-oidc-server[659]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration1639server # [ 10.772460] mock-oidc-server[659]: Issue tokens: http://127.0.0.1:8081/issue?sub=...1640server # [ 10.773961] systemd[1]: Started Nginx Web Server.1641server # [ 10.799160] postgresql-pre-start[698]: The files belonging to this database system will be owned by user "postgres".1642server # [ 10.808713] postgresql-pre-start[698]: This user must also own the server process.1643server # [ 10.814909] postgresql-pre-start[698]: The database cluster will be initialized with locale "en_US.UTF-8".1644server # [ 10.821800] postgresql-pre-start[698]: The default database encoding has accordingly been set to "UTF8".1645server # [ 10.823214] postgresql-pre-start[698]: The default text search configuration will be set to "english".1646server # [ 10.833892] postgresql-pre-start[698]: Data page checksums are enabled.1647server # [ 10.834905] postgresql-pre-start[698]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok1648server # [ 10.846520] postgresql-pre-start[698]: creating subdirectories ... ok1649server # [ 10.847471] postgresql-pre-start[698]: selecting dynamic shared memory implementation ... posix1650server # [ 11.016057] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1651server # [ 11.066594] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1652server # [ 11.072258] systemd[1]: Started backdoor.service.1653server # [ 11.092474] postgresql-pre-start[698]: selecting default "max_connections" ... 1001654server # [ 11.142533] systemd-vconsole-setup[657]: Configuration of first virtual console was skipped, ignoring remaining ones.1655server # [ 11.153429] systemd[1]: Finished Virtual Console Setup.1656server # connecting to host...1657server: Guest shell says: b'Spawning backdoor root shell...\n'1658builder # [ 11.304025] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31659server: connected to guest root shell1660server # [ 11.324748] 8021q: 802.1Q VLAN Support v1.81661server: (connecting took 11.61 seconds)1662server: (finished: waiting for the VM to finish booting, in 11.61 seconds)1663server # [ 11.346368] postgresql-pre-start[698]: selecting default "shared_buffers" ... 128MB1664server # [ 11.359201] (udev-worker)[677]: Network interface NamePolicy= disabled on kernel command line.1665server # [ 11.368612] (udev-worker)[680]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1666server # [ 11.390599] (udev-worker)[680]: Network interface NamePolicy= disabled on kernel command line.1667server # [ 11.488906] cfg80211: Loading compiled-in X.509 certificates for regulatory database1668server # [ 11.534371] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1669server # [ 11.534861] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1670server # [ 11.537463] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21671server # [ 11.541037] cfg80211: failed to load regulatory.db1672builder # [ 11.609395] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1673builder # [ 11.620114] systemd[1]: Starting Virtual Console Setup...1674builder # [ 11.640664] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1675builder # [ 11.647261] systemd[1]: Stopped Virtual Console Setup.1676builder # [ 11.650972] systemd[1]: Starting Virtual Console Setup...1677builder # [ 11.702723] systemd-logind[509]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1678server # [ 11.756910] systemd[1]: Condition check resulted in Virtio network device being skipped.1679server # [ 11.770013] systemd[1]: Starting Address configuration of eth1...1680builder # [ 11.780503] systemd-vconsole-setup[687]: Configuration of first virtual console was skipped, ignoring remaining ones.1681builder # [ 11.784722] systemd[1]: Finished Virtual Console Setup.1682server # [ 11.801162] systemd-logind[495]: Watching system buttons on /dev/input/event0 (gpio-keys)1683server # [ 11.966228] dhcpcd[644]: eth0: waiting for carrier1684server # [ 11.982896] 8021q: adding VLAN 0 to HW filter on device eth01685server # [ 11.971196] dhcpcd[644]: eth0: carrier acquired1686server # [ 11.987028] mousedev: PS/2 mouse device common for all mice1687server # [ 11.992990] 8021q: adding VLAN 0 to HW filter on device eth11688server # [ 12.006268] dhcpcd[644]: DUID 00:01:00:01:32:5a:4f:ee:52:54:00:12:34:561689server # [ 12.007405] dhcpcd[644]: eth0: IAID 00:12:34:561690server # [ 12.012156] dhcpcd[644]: eth0: adding address fe80::5054:ff:fe12:34561691server # [ 12.047306] network-addresses-eth1-start[746]: adding address 192.168.1.2/24... done1692server # [ 12.067578] network-addresses-eth1-start[746]: adding address 2001:db8:1::2/64... done1693server # [ 12.106099] systemd[1]: Finished Address configuration of eth1.1694server # [ 12.116336] systemd-logind[495]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1695builder # [ 12.533813] dhcpcd[618]: eth0: soliciting an IPv6 router1696builder # [ 12.535926] dhcpcd[618]: eth0: Router Advertisement from fe80::21697builder # [ 12.537569] dhcpcd[618]: eth0: adding address fec0::5054:ff:fe12:3456/641698builder # [ 12.539085] dhcpcd[618]: eth0: adding route to fec0::/641699builder # [ 12.540318] dhcpcd[618]: eth0: adding default route via fe80::21700server # [ 13.565857] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1701server # [ 13.569668] systemd[1]: Stopped Virtual Console Setup.1702server # [ 13.570538] systemd[1]: Stopping Virtual Console Setup...1703server # [ 13.575777] systemd[1]: Starting Virtual Console Setup...1704server # [ 13.620179] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1705server # [ 13.639664] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1706server # [ 13.644472] systemd[1]: Stopped Virtual Console Setup.1707server # [ 13.654425] dhcpcd[644]: eth0: soliciting a DHCP lease1708server # [ 13.660538] dhcpcd[644]: eth0: offered 10.0.2.15 from 10.0.2.21709server # [ 13.669476] systemd[1]: Starting Virtual Console Setup...1710server # [ 13.670420] dhcpcd[644]: eth0: probing address 10.0.2.15/241711server # [ 13.717244] systemd-logind[495]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1712server # [ 13.859606] systemd-vconsole-setup[791]: Configuration of first virtual console was skipped, ignoring remaining ones.1713server # [ 13.864083] systemd[1]: Finished Virtual Console Setup.1714server # [ 13.952482] dhcpcd[644]: eth0: soliciting an IPv6 router1715server # [ 13.953816] dhcpcd[644]: eth0: Router Advertisement from fe80::21716server # [ 13.954675] dhcpcd[644]: eth0: adding address fec0::5054:ff:fe12:3456/641717server # [ 13.955572] dhcpcd[644]: eth0: adding route to fec0::/641718server # [ 13.956995] dhcpcd[644]: eth0: adding default route via fe80::21719server # [ 14.009957] postgresql-pre-start[698]: selecting default time zone ... UTC1720server # [ 14.012535] postgresql-pre-start[698]: creating configuration files ... ok1721server # [ 14.241788] postgresql-pre-start[698]: running bootstrap script ... ok1722server # [ 14.782937] postgresql-pre-start[698]: performing post-bootstrap initialization ... ok1723server # [ 14.914582] postgresql-pre-start[698]: syncing data to disk ... ok1724server # [ 14.915711] postgresql-pre-start[698]: initdb: warning: enabling "trust" authentication for local connections1725server # [ 14.918428] postgresql-pre-start[698]: 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.1726server # [ 14.920711] postgresql-pre-start[698]: Success. You can now start the database server using:1727server # [ 14.921831] postgresql-pre-start[698]: pg_ctl -D /var/lib/postgresql/18 -l logfile start1728server # [ 15.015965] postgres[805]: [805] LOG: starting PostgreSQL 18.6 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit1729server # [ 15.018824] postgres[805]: [805] LOG: listening on IPv6 address "::1", port 54321730server # [ 15.020059] postgres[805]: [805] LOG: listening on IPv4 address "127.0.0.1", port 54321731server # [ 15.021791] postgres[805]: [805] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1732server # [ 15.032425] postgres[814]: [814] LOG: database system was shut down at 2026-10-08 12:58:25 GMT1733server # [ 15.038303] postgres[805]: [805] LOG: database system is ready to accept connections1734server # [ 15.041629] systemd[1]: Started PostgreSQL Server.1735server # [ 15.048208] systemd[1]: Starting PostgreSQL Setup Scripts...1736builder # [ 15.046742] dhcpcd[618]: eth0: leased 10.0.2.15 for 86400 seconds1737builder # [ 15.048739] dhcpcd[618]: eth0: adding route to 10.0.2.0/241738builder # [ 15.048831] dhcpcd[618]: eth0: adding default route via 10.0.2.21739builder # [ 15.150053] systemd[1]: Started DHCP Client.1740builder # [ 15.154834] systemd[1]: Reached target Multi-User System.1741builder # [ 15.158416] systemd[1]: Startup finished in 996ms (kernel) + 4.635s (initrd) + 9.522s (userspace) = 15.153s.1742server # [ 15.228528] postgresql-setup-start[825]: CREATE DATABASE1743server # [ 15.270228] postgresql-setup-start[830]: CREATE ROLE1744server # [ 15.284247] postgresql-setup-start[832]: ALTER DATABASE1745server # [ 15.293048] systemd[1]: Finished PostgreSQL Setup Scripts.1746server # [ 15.293908] systemd[1]: Reached target PostgreSQL.1747server: (finished: waiting for unit postgresql.service, in 16.14 seconds)1748server: waiting for unit rustfs.service1749server: (finished: waiting for unit rustfs.service, in 0.04 seconds)1750server: waiting for unit rustfs-setup.service1751server # [ 18.911150] dhcpcd[644]: eth0: leased 10.0.2.15 for 86400 seconds1752server # [ 18.911339] dhcpcd[644]: eth0: adding route to 10.0.2.0/241753server # [ 18.911412] dhcpcd[644]: eth0: adding default route via 10.0.2.21754server # [ 19.041672] systemd[1]: Started DHCP Client.1755server # [ 29.142705] rustfs-setup-start[965]: mb s3://niks3-test1756server # [ 29.154875] systemd[1]: Finished Setup RustFS bucket.1757server # [ 29.163080] systemd[1]: Starting niks3 server...1758server # [ 29.320235] niks3-server[975]: 2026/10/08 12:58:39 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:8080/oidc1759server # [ 29.327787] niks3-server[975]: 2026/10/08 12:58:39 INFO OIDC authentication enabled config=/nix/store/f94czh0qddabanbdhbb264fc54xx8cyh-niks3-oidc.json1760server # [ 29.331489] niks3-server[975]: 2026/10/08 12:58:39 INFO Loaded signing key name=niks3-test-1 path=/nix/store/snw7kmczqsc6g53bbgqlmqjiq9xddgss-niks3-signing-key1761server # [ 29.349851] niks3-server[975]: 2026/10/08 12:58:39 INFO Using socket-activated listener address=0.0.0.0:57511762server # [ 29.351291] niks3-server[975]: 2026/10/08 12:58:39 INFO Using socket-activated listener address=/run/niks3/proxy.sock1763server # [ 29.357415] niks3-server[975]: 2026/10/08 12:58:39 INFO systemd watchdog enabled interval=15s1764server # [ 29.358864] niks3-server[975]: 2026/10/08 12:58:39 INFO Starting HTTP server address=0.0.0.0:57511765server # [ 29.360606] niks3-server[975]: 2026/10/08 12:58:39 INFO Starting HTTP server address=/run/niks3/proxy.sock1766server # [ 29.361921] systemd[1]: Started niks3 server.1767server # [ 29.362810] systemd[1]: Reached target Multi-User System.1768server # [ 29.364103] systemd[1]: Startup finished in 985ms (kernel) + 3.887s (initrd) + 24.484s (userspace) = 29.356s.1769server: (finished: waiting for unit rustfs-setup.service, in 13.54 seconds)1770server: waiting for unit mock-oidc.service1771server: (finished: waiting for unit mock-oidc.service, in 0.03 seconds)1772server: waiting for unit niks3.service1773server: (finished: waiting for unit niks3.service, in 0.02 seconds)1774server: waiting for TCP port 5751 on localhost1775server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded!1776server: (finished: waiting for TCP port 5751 on localhost, in 0.03 seconds)1777server: waiting for TCP port 8080 on localhost1778server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded!1779server: (finished: waiting for TCP port 8080 on localhost, in 0.02 seconds)1780server: waiting for TCP port 9000 on localhost1781server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded!1782server: (finished: waiting for TCP port 9000 on localhost, in 0.02 seconds)1783server: must succeed: mkdir -p /tmp/test-config1784server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.01 seconds)1785server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token1786server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.01 seconds)1787server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.31788server # [ 29.730061] niks3-server[975]: 2026/10/08 12:58:40 INFO Received push request method=POST path=/api/pushes1789server # time=2026-10-08T12:58:40.329Z level=INFO msg="Uploading 5 paths to server (0 already cached)"1790server # time=2026-10-08T12:58:40.330Z level=INFO msg="Uploading 0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3 (287.5KB)"1791server # time=2026-10-08T12:58:40.332Z level=INFO msg="Uploading ficwc08a9q1rigvr4jv9l3xsvmx3yfqn-xgcc-16.2.0-libgcc (150.2KB)"1792server # time=2026-10-08T12:58:40.335Z level=INFO msg="Uploading gxljc46qyjqzvhrnf5wk5ypyg73qv84n-libidn2-2.3.8 (366.1KB)"1793server # time=2026-10-08T12:58:40.338Z level=INFO msg="Uploading d3xvwgga6f7mchrbvc77g3gap2yw1pd4-libunistring-1.4.2 (2.0MB)"1794server # time=2026-10-08T12:58:40.340Z level=INFO msg="Uploading hx9wvdx2l59iahwf9nmrx7rwkm6cymgs-glibc-2.44-25 (44.8MB)"1795server # [ 29.883897] niks3-server[975]: 2026/10/08 12:58:40 INFO Registered completed upload object_key=nar/0ik4x86wi4hx5a19m0hrqb82v8pwywwk872j6xqs6cbsmirmckm4.nar.zst1796server # [ 29.904206] niks3-server[975]: 2026/10/08 12:58:40 INFO Registered completed upload object_key=ficwc08a9q1rigvr4jv9l3xsvmx3yfqn.ls1797server # [ 29.986285] niks3-server[975]: 2026/10/08 12:58:40 INFO Registered completed upload object_key=gxljc46qyjqzvhrnf5wk5ypyg73qv84n.ls1798server # [ 30.020679] niks3-server[975]: 2026/10/08 12:58:40 INFO Registered completed upload object_key=nar/18599cw0w0wm2wv1ad31rx00n0643i8z2zgra1j66ikfwcs5q8g0.nar.zst1799server # [ 30.048666] niks3-server[975]: 2026/10/08 12:58:40 INFO Registered completed upload object_key=d3xvwgga6f7mchrbvc77g3gap2yw1pd4.ls1800server # [ 30.076536] niks3-server[975]: 2026/10/08 12:58:40 INFO Registered completed upload object_key=nar/07qvg0nz70dkqx83m02p283r0jrv8d1schf7y6wkai65w85yzvv0.nar.zst1801server # [ 30.137749] niks3-server[975]: 2026/10/08 12:58:40 INFO Registered completed upload object_key=nar/18yshwpp5jc6x354ipdplqa3ylhpqlbzihvyv9h05j8hpn888058.nar.zst1802server # [ 30.150689] niks3-server[975]: 2026/10/08 12:58:40 INFO Registered completed upload object_key=0xy6jlccm8kfpwmhkxabz3638lchyi74.ls1803server # [ 30.714131] niks3-server[975]: 2026/10/08 12:58:41 INFO Registered completed upload object_key=hx9wvdx2l59iahwf9nmrx7rwkm6cymgs.ls1804server # [ 30.733420] niks3-server[975]: 2026/10/08 12:58:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1805server # [ 30.745722] niks3-server[975]: 2026/10/08 12:58:41 INFO Completed multipart upload object_key=nar/1jmx0pk58iv937afmb3f0la32bd0avaixkxq0cx99vzamxdxg5y0.nar.zst upload_id=ZjQyYmJiMWYtNzI3MS00NTJmLTg5MDMtYmYzOTVhYzVmZTk5LmZhYjY5NGE5LWUwZWQtNDhiMi1iOGM5LTIyNjk2ZWMxZWNlY3gxNzkxNDY0MzIwMzIzNzg0MDIw parts=11806server # time=2026-10-08T12:58:41.337Z level=INFO msg="Uploading 5 narinfos"1807server # [ 30.755952] niks3-server[975]: 2026/10/08 12:58:41 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign1808server # [ 30.760376] niks3-server[975]: 2026/10/08 12:58:41 INFO Signed narinfos id=1 count=51809server # [ 30.772386] niks3-server[975]: 2026/10/08 12:58:41 INFO Registered completed upload object_key=gxljc46qyjqzvhrnf5wk5ypyg73qv84n.narinfo1810server # [ 30.782587] niks3-server[975]: 2026/10/08 12:58:41 INFO Registered completed upload object_key=d3xvwgga6f7mchrbvc77g3gap2yw1pd4.narinfo1811server # [ 30.790010] niks3-server[975]: 2026/10/08 12:58:41 INFO Registered completed upload object_key=0xy6jlccm8kfpwmhkxabz3638lchyi74.narinfo1812server # [ 30.796148] niks3-server[975]: 2026/10/08 12:58:41 INFO Received complete push request method=POST path=/api/pushes/1/complete1813server # time=2026-10-08T12:58:41.393Z level=INFO msg="Upload complete. (1.132s)"1814server # [ 30.809430] niks3-server[975]: 2026/10/08 12:58:41 INFO Completed push id=1 objects=15 duration=15.0351ms1815server # [ 30.834660] niks3-server[975]: 2026/10/08 12:58:41 INFO Registered completed upload object_key=ficwc08a9q1rigvr4jv9l3xsvmx3yfqn.narinfo1816server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3, in 1.27 seconds)1817server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token1818server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.01 seconds)1819server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.31820server # [ 30.925673] niks3-server[975]: 2026/10/08 12:58:41 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]1821server # [ 30.974578] niks3-server[975]: 2026/10/08 12:58:41 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]1822server # time=2026-10-08T12:58:41.562Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"1823server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3, in 0.13 seconds)1824server: waiting for unit nginx.service1825server: (finished: waiting for unit nginx.service, in 0.03 seconds)1826server: waiting for TCP port 443 on localhost1827server # Connection to localhost (::1) 443 port [tcp/https] succeeded!1828server: (finished: waiting for TCP port 443 on localhost, in 0.02 seconds)1829server: must succeed: /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/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/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.31830server # time=2026-10-08T12:58:41.674Z 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.pem1831server # time=2026-10-08T12:58:41.686Z level=INFO msg="All 1 paths already cached"1832server: (finished: must succeed: /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/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/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3, in 0.08 seconds)1833server: 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_closures1834server: (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)1835server: must fail: /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.31836server # time=2026-10-08T12:58:41.730Z 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)"1837server: (finished: must fail: /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3, in 0.02 seconds)1838server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.31839server # time=2026-10-08T12:58:41.794Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem1840server # time=2026-10-08T12:58:41.802Z level=INFO msg="All 1 paths already cached"1841server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3, in 0.07 seconds)1842server: must succeed: cd /etc/niks3-test-certs && /nix/store/m9xpj129716c3dn7mcja91cb84mplpv9-openssl-3.5.8-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client'1843server # -----1844server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/m9xpj129716c3dn7mcja91cb84mplpv9-openssl-3.5.8-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)1845server: must succeed: cd /etc/niks3-test-certs && /nix/store/m9xpj129716c3dn7mcja91cb84mplpv9-openssl-3.5.8-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem1846server # Certificate request self-signature ok1847server # subject=CN=other client1848server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/m9xpj129716c3dn7mcja91cb84mplpv9-openssl-3.5.8-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem, in 0.04 seconds)1849server: must fail: /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/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/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.31850server # time=2026-10-08T12:58:41.923Z 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.pem1851server # [ 31.346096] niks3-server[975]: 2026/10/08 12:58:41 WARN mTLS auth: subject not in bound subjects subject="CN=other client"1852server # [ 31.392811] niks3-server[975]: 2026/10/08 12:58:41 WARN mTLS auth: subject not in bound subjects subject="CN=other client"1853server # time=2026-10-08T12:58:41.980Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"1854server: (finished: must fail: /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/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/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3, in 0.12 seconds)1855server: must succeed: mkdir -p /tmp/test-store1856server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.02 seconds)1857server: must succeed: 1858 export AWS_ACCESS_KEY_ID=rustfsadmin1859export AWS_SECRET_ACCESS_KEY=rustfsadmin1860 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.318611862server # copying 5 paths...1863server # copying path '/nix/store/d3xvwgga6f7mchrbvc77g3gap2yw1pd4-libunistring-1.4.2' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1864server # copying path '/nix/store/ficwc08a9q1rigvr4jv9l3xsvmx3yfqn-xgcc-16.2.0-libgcc' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1865server # copying path '/nix/store/gxljc46qyjqzvhrnf5wk5ypyg73qv84n-libidn2-2.3.8' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1866server # copying path '/nix/store/hx9wvdx2l59iahwf9nmrx7rwkm6cymgs-glibc-2.44-25' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1867server # copying path '/nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1868server: (finished: must succeed: 1869 export AWS_ACCESS_KEY_ID=rustfsadmin1870export AWS_SECRET_ACCESS_KEY=rustfsadmin1871 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.31872, in 0.57 seconds)1873server: must succeed: 1874cat > /tmp/test-drv.nix << 'EOF'1875derivation {1876 name = "test-build-log";1877 system = builtins.currentSystem;1878 builder = "/bin/sh";1879 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];1880}1881EOF18821883server: (finished: must succeed: 1884cat > /tmp/test-drv.nix << 'EOF'1885derivation {1886 name = "test-build-log";1887 system = builtins.currentSystem;1888 builder = "/bin/sh";1889 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];1890}1891EOF1892, in 0.02 seconds)1893server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix1894server # this derivation will be built:1895server # /nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv1896server # building '/nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv'...1897server # test-build-log> test build log output1898server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.18 seconds)1899server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1900server # [ 32.307366] niks3-server[975]: 2026/10/08 12:58:42 INFO Received push request method=POST path=/api/pushes1901server # time=2026-10-08T12:58:42.898Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1902server # time=2026-10-08T12:58:42.900Z level=INFO msg="Uploading z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log (128B)"1903server # [ 32.338817] niks3-server[975]: 2026/10/08 12:58:42 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign1904server # time=2026-10-08T12:58:42.925Z level=INFO msg="Uploading 1 narinfos"1905server # [ 32.345225] niks3-server[975]: 2026/10/08 12:58:42 INFO Signed narinfos id=2 count=11906server # [ 32.346353] niks3-server[975]: 2026/10/08 12:58:42 INFO Registered completed upload object_key=log/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv1907server # [ 32.355904] niks3-server[975]: 2026/10/08 12:58:42 INFO Registered completed upload object_key=nar/00zns3gj9hwz2a4b0i07y7nmxybq59lh24bl3xsxblcl6333mjil.nar.zst1908server # [ 32.360811] niks3-server[975]: 2026/10/08 12:58:42 INFO Registered completed upload object_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.ls1909server # [ 32.367334] niks3-server[975]: 2026/10/08 12:58:42 INFO Received complete push request method=POST path=/api/pushes/2/complete1910server # time=2026-10-08T12:58:42.955Z level=INFO msg="Upload complete. (112ms)"1911server # [ 32.371178] niks3-server[975]: 2026/10/08 12:58:42 INFO Completed push id=2 objects=4 duration=6.68694ms1912server # [ 32.375479] niks3-server[975]: 2026/10/08 12:58:42 INFO Registered completed upload object_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.narinfo1913server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log, in 0.20 seconds)1914server: must succeed: 1915 export AWS_ACCESS_KEY_ID=rustfsadmin1916export AWS_SECRET_ACCESS_KEY=rustfsadmin1917 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log19181919server # got build log for '/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'1920server: (finished: must succeed: 1921 export AWS_ACCESS_KEY_ID=rustfsadmin1922export AWS_SECRET_ACCESS_KEY=rustfsadmin1923 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1924, in 0.13 seconds)1925subtest: push --stdin streams paths and reports each one1926server: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }'1927server # this derivation will be built:1928server # /nix/store/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv1929server # building '/nix/store/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv'...1930server: (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)1931server: 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/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --stdin1932server # [ 32.784847] niks3-server[975]: 2026/10/08 12:58:43 INFO Received push request method=POST path=/api/pushes1933server # time=2026-10-08T12:58:43.375Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1934server # time=2026-10-08T12:58:43.376Z level=INFO msg="Uploading 7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test (120B)"1935server # [ 32.809498] niks3-server[975]: 2026/10/08 12:58:43 INFO Registered completed upload object_key=7j8147s8h4si9jmv6bxa86ql7z6j2m37.ls1936server # [ 32.819475] niks3-server[975]: 2026/10/08 12:58:43 INFO Received sign narinfos request method=POST path=/api/pushes/3/sign1937server # time=2026-10-08T12:58:43.409Z level=INFO msg="Uploading 1 narinfos"1938server # [ 32.826410] niks3-server[975]: 2026/10/08 12:58:43 INFO Signed narinfos id=3 count=11939server # [ 32.827512] niks3-server[975]: 2026/10/08 12:58:43 INFO Registered completed upload object_key=log/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv1940server # [ 32.834093] niks3-server[975]: 2026/10/08 12:58:43 INFO Registered completed upload object_key=nar/1hj1r0g515rfxjx685mfn3dgr6akqbn4gf64snlv6bxaxph69lna.nar.zst1941server # [ 32.841327] niks3-server[975]: 2026/10/08 12:58:43 INFO Received complete push request method=POST path=/api/pushes/3/complete1942server # time=2026-10-08T12:58:43.429Z level=INFO msg="Upload complete. (111ms)"1943server # [ 32.845385] niks3-server[975]: 2026/10/08 12:58:43 INFO Registered completed upload object_key=7j8147s8h4si9jmv6bxa86ql7z6j2m37.narinfo1944server # [ 32.847055] niks3-server[975]: 2026/10/08 12:58:43 INFO Completed push id=3 objects=4 duration=6.49798ms1945server: (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/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --stdin, in 0.19 seconds)1946server: must succeed: 1947 export AWS_ACCESS_KEY_ID=rustfsadmin1948export AWS_SECRET_ACCESS_KEY=rustfsadmin1949 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test1950 1951server # copying 1 paths...1952server # copying path '/nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1953server: (finished: must succeed: 1954 export AWS_ACCESS_KEY_ID=rustfsadmin1955export AWS_SECRET_ACCESS_KEY=rustfsadmin1956 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test1957 , in 0.15 seconds)1958(finished: subtest: push --stdin streams paths and reports each one, in 0.49 seconds)1959server: must succeed: 1960cat > /tmp/ca-test.nix << 'EOF'1961derivation {1962 name = "ca-test";1963 system = builtins.currentSystem;1964 builder = "/bin/sh";1965 args = [ "-c" "echo 'Hello from CA derivation' > $out" ];1966 __contentAddressed = true;1967 outputHashMode = "recursive";1968 outputHashAlgo = "sha256";1969}1970EOF19711972server: (finished: must succeed: 1973cat > /tmp/ca-test.nix << 'EOF'1974derivation {1975 name = "ca-test";1976 system = builtins.currentSystem;1977 builder = "/bin/sh";1978 args = [ "-c" "echo 'Hello from CA derivation' > $out" ];1979 __contentAddressed = true;1980 outputHashMode = "recursive";1981 outputHashAlgo = "sha256";1982}1983EOF1984, in 0.02 seconds)1985server: must succeed: nix-build --log-format bar-with-logs /tmp/ca-test.nix --no-out-link1986server # this derivation will be built:1987server # /nix/store/8j226mdc8yhan5fv0s31bbzx0iiny3pa-ca-test.drv1988server # building '/nix/store/8j226mdc8yhan5fv0s31bbzx0iiny3pa-ca-test.drv'...1989server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/ca-test.nix --no-out-link, in 0.17 seconds)1990server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test1991server # [ 33.344947] niks3-server[975]: 2026/10/08 12:58:43 INFO Received push request method=POST path=/api/pushes1992server # time=2026-10-08T12:58:43.934Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1993server # time=2026-10-08T12:58:43.935Z level=INFO msg="Uploading 5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test (144B)"1994server # [ 33.373895] niks3-server[975]: 2026/10/08 12:58:43 INFO Registered completed upload object_key=nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1995server # time=2026-10-08T12:58:43.963Z level=INFO msg="Uploading 1 narinfos"1996server # [ 33.380846] niks3-server[975]: 2026/10/08 12:58:43 INFO Registered completed upload object_key=5v8l1dc99hcg2ss10dgkivlhwbkpppjh.ls1997server # [ 33.384893] niks3-server[975]: 2026/10/08 12:58:43 INFO Received sign narinfos request method=POST path=/api/pushes/4/sign1998server # [ 33.388207] niks3-server[975]: 2026/10/08 12:58:43 INFO Signed narinfos id=4 count=11999server # [ 33.389326] niks3-server[975]: 2026/10/08 12:58:43 INFO Registered completed upload object_key=log/8j226mdc8yhan5fv0s31bbzx0iiny3pa-ca-test.drv2000server # [ 33.396379] niks3-server[975]: 2026/10/08 12:58:43 INFO Received complete push request method=POST path=/api/pushes/4/complete2001server # time=2026-10-08T12:58:43.984Z level=INFO msg="Upload complete. (150ms)"2002server # [ 33.400303] niks3-server[975]: 2026/10/08 12:58:43 INFO Completed push id=4 objects=4 duration=5.05224ms2003server # [ 33.404069] niks3-server[975]: 2026/10/08 12:58:43 INFO Registered completed upload object_key=5v8l1dc99hcg2ss10dgkivlhwbkpppjh.narinfo2004server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.22 seconds)2005server: must succeed: mkdir -p /tmp/chroot-store2006server: (finished: must succeed: mkdir -p /tmp/chroot-store, in 0.02 seconds)2007server: must succeed: 2008 export AWS_ACCESS_KEY_ID=rustfsadmin2009export AWS_SECRET_ACCESS_KEY=rustfsadmin2010 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/chroot-store /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test20112012server # copying 1 paths...2013server # copying path '/nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2014server: (finished: must succeed: 2015 export AWS_ACCESS_KEY_ID=rustfsadmin2016export AWS_SECRET_ACCESS_KEY=rustfsadmin2017 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/chroot-store /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2018, in 0.14 seconds)2019server: must succeed: nix --store /tmp/chroot-store store cat /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2020server: (finished: must succeed: nix --store /tmp/chroot-store store cat /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.06 seconds)2021server: must succeed: nix --store /tmp/chroot-store realisation info /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2022server # warning: 'realisation' is a deprecated alias for 'store build-trace'2023server: (finished: must succeed: nix --store /tmp/chroot-store realisation info /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.06 seconds)2024server: must succeed: readlink /etc/niks3-test/symlink-wrapper2025server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.02 seconds)2026server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper2027server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.01 seconds)2028server: must succeed: test -L /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper2029server: (finished: must succeed: test -L /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper, in 0.01 seconds)2030server: must succeed: readlink /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper2031server: (finished: must succeed: readlink /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper, in 0.01 seconds)2032server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper2033server # [ 33.853551] niks3-server[975]: 2026/10/08 12:58:44 INFO Received push request method=POST path=/api/pushes2034server # time=2026-10-08T12:58:44.442Z level=INFO msg="Uploading 2 paths to server (0 already cached)"2035server # time=2026-10-08T12:58:44.444Z level=INFO msg="Uploading qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper (192B)"2036server # time=2026-10-08T12:58:44.445Z level=INFO msg="Uploading l5aiz90gwfr0i2074qb0pk20v27p5v86-base-package (536B)"2037server # [ 33.891803] niks3-server[975]: 2026/10/08 12:58:44 INFO Registered completed upload object_key=qlhg6w8ghvjarrf438qw23mymapxfpdp.ls2038server # [ 33.899398] niks3-server[975]: 2026/10/08 12:58:44 INFO Received sign narinfos request method=POST path=/api/pushes/5/sign2039server # time=2026-10-08T12:58:44.482Z level=INFO msg="Uploading 2 narinfos"2040server # [ 33.904539] niks3-server[975]: 2026/10/08 12:58:44 INFO Signed narinfos id=5 count=22041server # [ 33.905631] niks3-server[975]: 2026/10/08 12:58:44 INFO Registered completed upload object_key=nar/0820av37plmfgrxw6pcbym6phk1sv14dilyrablrnkzb7z1fd5js.nar.zst2042server # [ 33.907496] niks3-server[975]: 2026/10/08 12:58:44 INFO Registered completed upload object_key=nar/0pls6byi1j0qx38i7wfm9a2mhgj2lwdyl5wmfdzfgvd67yhriq4z.nar.zst2043server # [ 33.915516] niks3-server[975]: 2026/10/08 12:58:44 INFO Registered completed upload object_key=l5aiz90gwfr0i2074qb0pk20v27p5v86.ls2044server # time=2026-10-08T12:58:44.506Z level=INFO msg="Upload complete. (118ms)"2045server # [ 33.922475] niks3-server[975]: 2026/10/08 12:58:44 INFO Received complete push request method=POST path=/api/pushes/5/complete2046server # [ 33.923969] niks3-server[975]: 2026/10/08 12:58:44 INFO Completed push id=5 objects=6 duration=2.63628ms2047server # [ 33.930497] niks3-server[975]: 2026/10/08 12:58:44 INFO Registered completed upload object_key=qlhg6w8ghvjarrf438qw23mymapxfpdp.narinfo2048server # [ 33.933366] niks3-server[975]: 2026/10/08 12:58:44 INFO Registered completed upload object_key=l5aiz90gwfr0i2074qb0pk20v27p5v86.narinfo2049server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper, in 0.20 seconds)2050server: must succeed: 2051 export AWS_ACCESS_KEY_ID=rustfsadmin2052export AWS_SECRET_ACCESS_KEY=rustfsadmin2053 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper20542055server # copying 2 paths...2056server # copying path '/nix/store/l5aiz90gwfr0i2074qb0pk20v27p5v86-base-package' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2057server # copying path '/nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2058server: (finished: must succeed: 2059 export AWS_ACCESS_KEY_ID=rustfsadmin2060export AWS_SECRET_ACCESS_KEY=rustfsadmin2061 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper2062, in 0.15 seconds)2063server: must succeed: 2064cat > /tmp/oidc-test.nix << 'EOF'2065derivation {2066 name = "oidc-test";2067 system = builtins.currentSystem;2068 builder = "/bin/sh";2069 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2070}2071EOF20722073server: (finished: must succeed: 2074cat > /tmp/oidc-test.nix << 'EOF'2075derivation {2076 name = "oidc-test";2077 system = builtins.currentSystem;2078 builder = "/bin/sh";2079 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2080}2081EOF2082, in 0.02 seconds)2083server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link2084server # this derivation will be built:2085server # /nix/store/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv2086server # building '/nix/store/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv'...2087server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link, in 0.16 seconds)2088server: 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'2089server: (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)2090server: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IkdBaFYwa0g3bzk4REQ1WVoyWExWdTdGRmFIWDlhb3RwZXo2XzJmOFZPTzAiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTE0Njc5MjQsImlhdCI6MTc5MTQ2NDMyNCwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.F7Wn1W7cg3klTJrlA91QRigBNgCqN75EiQJmp_peV2x72T2ZblgJEaQMOnC-PayYv30WC5oTtD67dyvAOsA4mMpU_lSzTmwsFNSOtGY-MJSJeGPuW2z1QMai-RSRsSYtka89KVuBDuR21gfgaza58CmmSDV5FTpPNZ29apRmLo7awfHbAucVRiwtE4EtF8weIwhbKyzB3QaWyL44ipwO4nslbXX0PxUl0RAamCt3w31MBjLUcuFpwqJLwb22LOeKsMBHZx7VXmKT5LEoRSmSykdhS6dQG17zgnL2dJYwfCMeTRCuBHx-aQszI4f_78qPv5_uiGedPsDQo87T1m3juA' /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test2091server # time=2026-10-08T12:58:44.889Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2092server # [ 34.407370] niks3-server[975]: 2026/10/08 12:58:44 INFO Received push request method=POST path=/api/pushes2093server # time=2026-10-08T12:58:44.996Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2094server # time=2026-10-08T12:58:44.998Z level=INFO msg="Uploading x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test (136B)"2095server # [ 34.429456] niks3-server[975]: 2026/10/08 12:58:45 INFO Registered completed upload object_key=nar/17h0qgwf5334wia961ibqy9hmpsyvfhrvj5ji9m031klp6sx4v0q.nar.zst2096server # [ 34.439991] niks3-server[975]: 2026/10/08 12:58:45 INFO Registered completed upload object_key=log/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv2097server # time=2026-10-08T12:58:45.031Z level=INFO msg="Uploading 1 narinfos"2098server # [ 34.448631] niks3-server[975]: 2026/10/08 12:58:45 INFO Received sign narinfos request method=POST path=/api/pushes/6/sign2099server # [ 34.450181] niks3-server[975]: 2026/10/08 12:58:45 INFO Signed narinfos id=6 count=12100server # [ 34.451218] niks3-server[975]: 2026/10/08 12:58:45 INFO Registered completed upload object_key=x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj.ls2101server # [ 34.459526] niks3-server[975]: 2026/10/08 12:58:45 INFO Received complete push request method=POST path=/api/pushes/6/complete2102server # time=2026-10-08T12:58:45.048Z level=INFO msg="Upload complete. (108ms)"2103server # [ 34.464474] niks3-server[975]: 2026/10/08 12:58:45 INFO Completed push id=6 objects=4 duration=5.0672ms2104server # [ 34.469338] niks3-server[975]: 2026/10/08 12:58:45 INFO Registered completed upload object_key=x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj.narinfo2105server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IkdBaFYwa0g3bzk4REQ1WVoyWExWdTdGRmFIWDlhb3RwZXo2XzJmOFZPTzAiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTE0Njc5MjQsImlhdCI6MTc5MTQ2NDMyNCwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.F7Wn1W7cg3klTJrlA91QRigBNgCqN75EiQJmp_peV2x72T2ZblgJEaQMOnC-PayYv30WC5oTtD67dyvAOsA4mMpU_lSzTmwsFNSOtGY-MJSJeGPuW2z1QMai-RSRsSYtka89KVuBDuR21gfgaza58CmmSDV5FTpPNZ29apRmLo7awfHbAucVRiwtE4EtF8weIwhbKyzB3QaWyL44ipwO4nslbXX0PxUl0RAamCt3w31MBjLUcuFpwqJLwb22LOeKsMBHZx7VXmKT5LEoRSmSykdhS6dQG17zgnL2dJYwfCMeTRCuBHx-aQszI4f_78qPv5_uiGedPsDQo87T1m3juA' /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test, in 0.18 seconds)2106server: must succeed: 2107cat > /tmp/oidc-test2.nix << 'EOF'2108derivation {2109 name = "oidc-test2";2110 system = builtins.currentSystem;2111 builder = "/bin/sh";2112 args = [ "-c" "echo 'OIDC test 2' > $out" ];2113}2114EOF21152116server: (finished: must succeed: 2117cat > /tmp/oidc-test2.nix << 'EOF'2118derivation {2119 name = "oidc-test2";2120 system = builtins.currentSystem;2121 builder = "/bin/sh";2122 args = [ "-c" "echo 'OIDC test 2' > $out" ];2123}2124EOF2125, in 0.02 seconds)2126server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link2127server # this derivation will be built:2128server # /nix/store/ldlfjwl576hkm4b5ag3b447nnn59cwiw-oidc-test2.drv2129server # building '/nix/store/ldlfjwl576hkm4b5ag3b447nnn59cwiw-oidc-test2.drv'...2130server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link, in 0.16 seconds)2131server: 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'2132server: (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)2133server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IkdBaFYwa0g3bzk4REQ1WVoyWExWdTdGRmFIWDlhb3RwZXo2XzJmOFZPTzAiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTE0Njc5MjUsImlhdCI6MTc5MTQ2NDMyNSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.TFcNJDWJZcEyHh0HIy7VesaSVOwm7VRYXY-AXKY161tg3jJp4X0Ff1VsuuJzFbhpofAUZuIC0N4UdYDNw-gg1ku0CsfdGD9c4FUJ2Q206E6aj8XyTcjJBUB0WzWauWnZ6waR78X506XHLUxtaaN8Wfr68QOsGG5OysCwTNROKt_jvESBts04lQauSW6SZr9kpgdho1ZbD2y-GIuhhEO4OkpTEmh-HpvgbbcEZzAEi41o4Cw3Mw9oKo1LnJQpJ5gfbFfE3QWk8WjZ4uZi0e7AjAG3KmpsexFI4LVVyq9P0LiOqNiVka52Ie9hraDsvIVpymia_GMR2ROkKgJwLajx8g' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22134server # time=2026-10-08T12:58:45.277Z 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 # [ 34.746540] niks3-server[975]: 2026/10/08 12:58:45 WARN Authentication failed token_preview=eyJhbGciOi...KgJwLajx8g token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2136server # [ 34.817362] niks3-server[975]: 2026/10/08 12:58:45 WARN Authentication failed token_preview=eyJhbGciOi...KgJwLajx8g token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2137server # time=2026-10-08T12:58:45.406Z 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/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IkdBaFYwa0g3bzk4REQ1WVoyWExWdTdGRmFIWDlhb3RwZXo2XzJmOFZPTzAiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTE0Njc5MjUsImlhdCI6MTc5MTQ2NDMyNSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.TFcNJDWJZcEyHh0HIy7VesaSVOwm7VRYXY-AXKY161tg3jJp4X0Ff1VsuuJzFbhpofAUZuIC0N4UdYDNw-gg1ku0CsfdGD9c4FUJ2Q206E6aj8XyTcjJBUB0WzWauWnZ6waR78X506XHLUxtaaN8Wfr68QOsGG5OysCwTNROKt_jvESBts04lQauSW6SZr9kpgdho1ZbD2y-GIuhhEO4OkpTEmh-HpvgbbcEZzAEi41o4Cw3Mw9oKo1LnJQpJ5gfbFfE3QWk8WjZ4uZi0e7AjAG3KmpsexFI4LVVyq9P0LiOqNiVka52Ie9hraDsvIVpymia_GMR2ROkKgJwLajx8g' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.15 seconds)2139server: 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'2140server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://wrong:5751&repository_owner=myorg', in 0.05 seconds)2141server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IkdBaFYwa0g3bzk4REQ1WVoyWExWdTdGRmFIWDlhb3RwZXo2XzJmOFZPTzAiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MTQ2NzkyNSwiaWF0IjoxNzkxNDY0MzI1LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.cUolTogfLkvVGh7Bt_T4nB8K7GArwFRbP7DVKoCYkfE7nYZBMRMmoKxOzIrrfWejFPjr1m9LXzYPaa3K3lfMPLwpPL9rRqgvaN4PFy8CYezh1-_Z13QgZFhXX2X6N48aKWma3sorm0zH6MRya0PaG_BfCnm7hr7KB2LDamJor3Yep52c5PpK9auHvUI8W8w5lrkl87AsmjGfRAJflWCeKT_XUpfUbu_DUwMfIH6iB45fhBOE_68PZlFjGLmUMRk57ihujC6Innp88YdT8JfmJEbpqRr9Q-UD1PmNOBTdyURfpUqcbqfHS7-z_EwoBbSG-6LSs6qaPLHMcW1Bt3tOCA' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22142server # time=2026-10-08T12:58:45.475Z 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"2143server # [ 34.944186] niks3-server[975]: 2026/10/08 12:58:45 WARN Authentication failed token_preview=eyJhbGciOi...cW1Bt3tOCA token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2144server # [ 34.993489] niks3-server[975]: 2026/10/08 12:58:45 WARN Authentication failed token_preview=eyJhbGciOi...cW1Bt3tOCA token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2145server # time=2026-10-08T12:58:45.581Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2146server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IkdBaFYwa0g3bzk4REQ1WVoyWExWdTdGRmFIWDlhb3RwZXo2XzJmOFZPTzAiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MTQ2NzkyNSwiaWF0IjoxNzkxNDY0MzI1LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.cUolTogfLkvVGh7Bt_T4nB8K7GArwFRbP7DVKoCYkfE7nYZBMRMmoKxOzIrrfWejFPjr1m9LXzYPaa3K3lfMPLwpPL9rRqgvaN4PFy8CYezh1-_Z13QgZFhXX2X6N48aKWma3sorm0zH6MRya0PaG_BfCnm7hr7KB2LDamJor3Yep52c5PpK9auHvUI8W8w5lrkl87AsmjGfRAJflWCeKT_XUpfUbu_DUwMfIH6iB45fhBOE_68PZlFjGLmUMRk57ihujC6Innp88YdT8JfmJEbpqRr9Q-UD1PmNOBTdyURfpUqcbqfHS7-z_EwoBbSG-6LSs6qaPLHMcW1Bt3tOCA' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.13 seconds)2147server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22148server # time=2026-10-08T12:58:45.601Z 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"2149server # [ 35.069715] niks3-server[975]: 2026/10/08 12:58:45 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]2150server # [ 35.124117] niks3-server[975]: 2026/10/08 12:58:45 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]2151server # time=2026-10-08T12:58:45.712Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2152server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.13 seconds)2153server: must succeed: 2154 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins create hello-pin /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.321552156server # [ 35.206261] niks3-server[975]: 2026/10/08 12:58:45 INFO Received create pin request method=POST path=/api/pins/hello-pin2157server # time=2026-10-08T12:58:45.799Z level=INFO msg="Created pin" name=hello-pin store_path=/nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.32158server # [ 35.215859] niks3-server[975]: 2026/10/08 12:58:45 INFO Created/updated pin name=hello-pin store_path=/nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3 narinfo_key=0xy6jlccm8kfpwmhkxabz3638lchyi74.narinfo2159server: (finished: must succeed: 2160 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins create hello-pin /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.32161, 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/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins list21642165server # [ 35.295393] niks3-server[975]: 2026/10/08 12:58:45 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/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins list2168, in 0.08 seconds)2169server: must succeed: 2170 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins list --names-only21712172server # [ 35.370543] niks3-server[975]: 2026/10/08 12:58:45 INFO Received list pins request method=GET path=/api/pins2173server: (finished: must succeed: 2174 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins list --names-only2175, in 0.08 seconds)2176server: must succeed: 2177 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins list --json21782179server # [ 35.471399] niks3-server[975]: 2026/10/08 12:58:46 INFO Received list pins request method=GET path=/api/pins2180server: (finished: must succeed: 2181 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins list --json2182, in 0.10 seconds)2183server: must succeed: 2184 export S3_ENDPOINT_URL=http://localhost:90002185 export AWS_ACCESS_KEY_ID=rustfsadmin2186 export AWS_SECRET_ACCESS_KEY=rustfsadmin2187 /nix/store/ilah8j764imsc5p7912a20hyh1lrgzmq-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin21882189server: (finished: must succeed: 2190 export S3_ENDPOINT_URL=http://localhost:90002191 export AWS_ACCESS_KEY_ID=rustfsadmin2192 export AWS_SECRET_ACCESS_KEY=rustfsadmin2193 /nix/store/ilah8j764imsc5p7912a20hyh1lrgzmq-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin2194, in 0.03 seconds)2195server: must succeed: 2196 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --pin ca-pin /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log21972198server # time=2026-10-08T12:58:46.159Z level=INFO msg="All 1 paths already cached"2199server # [ 35.575246] niks3-server[975]: 2026/10/08 12:58:46 INFO Received create pin request method=POST path=/api/pins/ca-pin2200server # time=2026-10-08T12:58:46.168Z level=INFO msg="Created pin" name=ca-pin store_path=/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2201server # [ 35.585349] niks3-server[975]: 2026/10/08 12:58:46 INFO Created/updated pin name=ca-pin store_path=/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log narinfo_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.narinfo2202server: (finished: must succeed: 2203 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 push --pin ca-pin /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2204, in 0.09 seconds)2205server: must succeed: 2206 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins list --names-only22072208server # [ 35.658674] niks3-server[975]: 2026/10/08 12:58:46 INFO Received list pins request method=GET path=/api/pins2209server: (finished: must succeed: 2210 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins list --names-only2211, in 0.07 seconds)2212server: must succeed: 2213 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins delete hello-pin22142215server # [ 35.727693] niks3-server[975]: 2026/10/08 12:58:46 INFO Received delete pin request method=DELETE path=/api/pins/hello-pin2216server # time=2026-10-08T12:58:46.319Z level=INFO msg="Deleted pin" name=hello-pin2217server # [ 35.735146] niks3-server[975]: 2026/10/08 12:58:46 INFO Deleted pin name=hello-pin2218server: (finished: must succeed: 2219 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins delete hello-pin2220, in 0.08 seconds)2221server: must succeed: 2222 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins list --names-only22232224server # [ 35.806405] niks3-server[975]: 2026/10/08 12:58:46 INFO Received list pins request method=GET path=/api/pins2225server: (finished: must succeed: 2226 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins list --names-only2227, in 0.07 seconds)2228server: must fail: 2229 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent22302231server # [ 35.874104] niks3-server[975]: 2026/10/08 12:58:46 INFO Received create pin request method=POST path=/api/pins/bad-pin2232server # time=2026-10-08T12:58:46.462Z level=ERROR msg="Fatal error" error="creating pin: server returned 404: closure not found: store path must be pushed before pinning\n"2233server # [ 35.878705] niks3-server[975]: 2026/10/08 12:58:46 ERROR Failed to get closure for pin narinfo_key=aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.narinfo error="no rows in result set"2234server: (finished: must fail: 2235 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/579kq8qm07jwa55l9mnc544qcgriw9m8-niks3-1.14.2/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent2236, in 0.07 seconds)2237server: must succeed: systemctl start niks3-gc.service2238server # [ 35.907542] systemd[1]: Starting niks3 garbage collection...2239server # [ 35.954803] niks3[1544]: time=2026-10-08T12:58:46.539Z level=INFO msg="Starting garbage collection" older-than=720h failed-uploads-older-than=6h force=false2240server # [ 35.958728] niks3-server[975]: 2026/10/08 12:58:46 INFO Starting cleanup of old closures method=DELETE path=/api/closures2241server # [ 35.961833] niks3[1544]: time=2026-10-08T12:58:46.544Z level=INFO msg="Garbage collection started"2242server # [ 35.963082] niks3-server[975]: 2026/10/08 12:58:46 INFO Aborted multipart uploads count=0 kept=02243server # [ 35.971325] niks3-server[975]: 2026/10/08 12:58:46 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=4 objects-deleted-after-grace-period=0 objects-failed-to-delete=02244server # [ 35.977293] niks3-server[975]: 2026/10/08 12:58:46 INFO Vacuumed table table=pending_closures2245server # [ 35.980524] niks3-server[975]: 2026/10/08 12:58:46 INFO Vacuumed table table=pending_objects2246server # [ 35.983862] niks3-server[975]: 2026/10/08 12:58:46 INFO Vacuumed table table=multipart_uploads2247server # [ 35.986775] niks3-server[975]: 2026/10/08 12:58:46 INFO Vacuumed table table=closures2248server # [ 35.991117] niks3-server[975]: 2026/10/08 12:58:46 INFO Vacuumed table table=objects2249server # [ 37.960533] niks3[1544]: time=2026-10-08T12:58:48.545Z level=INFO msg="Garbage collection progress" phase="" failed_uploads_deleted=0 old_closures_deleted=0 objects_marked=4 objects_deleted=0 objects_failed=02250server # [ 37.966203] niks3[1544]: time=2026-10-08T12:58:48.545Z level=INFO msg="Garbage collection completed successfully" failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=4 objects-deleted-after-grace-period=0 objects-failed-to-delete=02251server # [ 37.974882] systemd[1]: niks3-gc.service: Deactivated successfully.2252server # [ 37.979373] systemd[1]: Finished niks3 garbage collection.2253server # [ 37.983898] systemd[1]: niks3-gc.service: Consumed 40ms CPU time over 2.066s wall clock time, 11.6M memory peak, 1.2K incoming IP traffic, 823B outgoing IP traffic.2254server: (finished: must succeed: systemctl start niks3-gc.service, in 2.11 seconds)2255builder: waiting for unit niks3-auto-upload.socket2256builder: waiting for the VM to finish booting2257builder: Guest shell says: b'Spawning backdoor root shell...\n'2258builder: connected to guest root shell2259builder: (connecting took 0.00 seconds)2260builder: (finished: waiting for the VM to finish booting, in 0.00 seconds)2261builder: (finished: waiting for unit niks3-auto-upload.socket, in 0.04 seconds)2262builder: must succeed: test -S /run/niks3/upload-to-cache.sock2263builder: (finished: must succeed: test -S /run/niks3/upload-to-cache.sock, in 0.02 seconds)2264builder: must succeed: grep post-build-hook /etc/nix/nix.conf2265builder: (finished: must succeed: grep post-build-hook /etc/nix/nix.conf, in 0.02 seconds)2266builder: must succeed: 2267cat > /tmp/test-drv.nix << 'EOF'2268derivation {2269 name = "post-build-hook-test";2270 system = builtins.currentSystem;2271 builder = "/bin/sh";2272 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2273}2274EOF22752276builder: (finished: must succeed: 2277cat > /tmp/test-drv.nix << 'EOF'2278derivation {2279 name = "post-build-hook-test";2280 system = builtins.currentSystem;2281 builder = "/bin/sh";2282 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2283}2284EOF2285, in 0.02 seconds)2286builder: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link2287builder # 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 63 ms (attempt 1/5)2288builder # 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 70 ms (attempt 2/5)2289builder # 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 308 ms (attempt 3/5)2290builder # 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 691 ms (attempt 4/5)2291builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org2292builder # this derivation will be built:2293builder # /nix/store/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv2294builder # building '/nix/store/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv'...2295builder # [ 39.503430] systemd[1]: Started niks3 auto-upload daemon.2296builder # [ 39.646682] niks3-hook[793]: time=2026-10-08T12:58:50.226Z 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=0s2297builder # [ 39.653841] niks3-hook[793]: time=2026-10-08T12:58:50.234Z level=INFO msg="Upload queue status" pending=12298builder # [ 39.657323] niks3-hook[793]: time=2026-10-08T12:58:50.234Z level=INFO msg="Uploading batch" count=12299builder: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link, in 1.59 seconds)2300builder: waiting for unit niks3-auto-upload.service2301builder: (finished: waiting for unit niks3-auto-upload.service, in 0.08 seconds)2302??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2303 File "/nix/store/zfxjk31pd817qjzxi1374rlf30i2qqni-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392304builder: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive2305??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2306 File "/nix/store/zfxjk31pd817qjzxi1374rlf30i2qqni-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392307builder # [ 39.750970] systemd[1]: Started Nix Daemon.2308builder # [ 39.819437] nix-daemon[812]: accepted connection from pid 805, user root (trusted)2309builder # [ 39.832515] nix-daemon[812]: reaped child process 819, status = succeeded2310server # [ 39.849106] niks3-server[975]: 2026/10/08 12:58:50 INFO Received push request method=POST path=/api/pushes2311builder # [ 39.848077] niks3-hook[793]: time=2026-10-08T12:58:50.427Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2312builder # [ 39.849601] niks3-hook[793]: time=2026-10-08T12:58:50.427Z level=INFO msg="Uploading 1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test (144B)"2313server # [ 39.881874] niks3-server[975]: 2026/10/08 12:58:50 INFO Registered completed upload object_key=nar/1a0vfw93shmvlz6lac7d1lv82jxzfdswigh5s32hpvncf74i1i0y.nar.zst2314server # [ 39.890790] niks3-server[975]: 2026/10/08 12:58:50 INFO Registered completed upload object_key=1qlca7drab1x7rccc3h1pw1fl9c0j7yx.ls2315builder # [ 39.886463] niks3-hook[793]: time=2026-10-08T12:58:50.465Z level=INFO msg="Uploading 1 narinfos"2316server # [ 39.897072] niks3-server[975]: 2026/10/08 12:58:50 INFO Received sign narinfos request method=POST path=/api/pushes/7/sign2317server # [ 39.900868] niks3-server[975]: 2026/10/08 12:58:50 INFO Signed narinfos id=7 count=12318server # [ 39.908210] niks3-server[975]: 2026/10/08 12:58:50 INFO Registered completed upload object_key=log/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv2319server # [ 39.917790] niks3-server[975]: 2026/10/08 12:58:50 INFO Received complete push request method=POST path=/api/pushes/7/complete2320builder # [ 39.913863] niks3-hook[793]: time=2026-10-08T12:58:50.493Z level=INFO msg="Upload complete. (258ms)"2321server # [ 39.925989] niks3-server[975]: 2026/10/08 12:58:50 INFO Completed push id=7 objects=4 duration=10.81158ms2322server # [ 39.928966] niks3-server[975]: 2026/10/08 12:58:50 INFO Registered completed upload object_key=1qlca7drab1x7rccc3h1pw1fl9c0j7yx.narinfo2323builder # [ 44.654937] niks3-hook[793]: time=2026-10-08T12:58:55.234Z level=INFO msg="Idle timeout reached and queue is empty, shutting down"2324builder # [ 44.658959] niks3-hook[793]: time=2026-10-08T12:58:55.235Z level=INFO msg="niks3-hook serve stopped"2325builder # [ 44.667864] systemd[1]: niks3-auto-upload.service: Deactivated successfully.2326builder # [ 44.675702] systemd[1]: niks3-auto-upload.service: Consumed 173ms CPU time over 5.168s wall clock time, 31.3M memory peak, 68K written to disk, 5.5K incoming IP traffic, 8.1K outgoing IP traffic.2327builder: (finished: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive, in 5.21 seconds)2328server: must succeed: 2329 export AWS_ACCESS_KEY_ID=rustfsadmin2330export AWS_SECRET_ACCESS_KEY=rustfsadmin2331 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-test23322333server # copying 1 paths...2334server # copying path '/nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2335server: (finished: must succeed: 2336 export AWS_ACCESS_KEY_ID=rustfsadmin2337export AWS_SECRET_ACCESS_KEY=rustfsadmin2338 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-test2339, in 0.17 seconds)2340server: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test2341server: (finished: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test, in 0.06 seconds)2342(finished: run the VM test script, in 45.87 seconds)2343test script finished in 45.95s2344cleanup2345kill QemuMachine (pid 47)2346builder # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2347builder # [2026-10-08T12:58:55Z INFO virtiofsd] Client disconnected, shutting down2348builder # [2026-10-08T12:58:55Z INFO virtiofsd] Client disconnected, shutting down2349builder # [2026-10-08T12:58:55Z INFO virtiofsd] Client disconnected, shutting down2350kill QemuMachine (pid 48)2351server # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2352server # [2026-10-08T12:58:55Z INFO virtiofsd] Client disconnected, shutting down2353server # [2026-10-08T12:58:55Z INFO virtiofsd] Client disconnected, shutting down2354server # [2026-10-08T12:58:55Z INFO virtiofsd] Client disconnected, shutting down2355(finished: cleanup, in 0.49 seconds)2356additionally exposed symbols:2357 builder, server,2358 vlan1,2359 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_ssh2360Hello store path: /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.32361Test output path: /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2362CA derivation output path: /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2363Realisation info in chroot store: /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test23642365Symlink wrapper store path: /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper2366Symlink wrapper points to: /nix/store/l5aiz90gwfr0i2074qb0pk20v27p5v86-base-package/bin/test-program2367OIDC test store path: /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test2368Valid OIDC token obtained (length=677)2369OIDC push with valid token: SUCCESS2370Invalid OIDC token obtained (wrong org)2371OIDC push with wrong org: correctly rejected2372Wrong audience OIDC token obtained2373OIDC push with wrong audience: correctly rejected2374OIDC push with malformed token: correctly rejected2375All OIDC tests passed!2376All pin tests passed!2377Built store path: /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test2378Post-build-hook pipeline test passed!