tribuchet: building on eliza Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script start all VMs builder: starting vm server: starting vm server # Disk image does not exist, creating the virtualisation disk image... server: QEMU running (pid 48) builder: QEMU running (pid 47) builder # Disk image does not exist, creating the virtualisation disk image... server # Formatting '/build/vm-state-server/tmp.i34u0Y3Gc8', fmt=raw size=1073741824 builder # Formatting '/build/vm-state-builder/tmp.xbZFZLrySk', fmt=raw size=1073741824 server # mke2fs 1.47.4 (6-Mar-2025) builder # mke2fs 1.47.4 (6-Mar-2025) server # Discarding device blocks: 0/262144 done builder # Discarding device blocks: 0/262144 done server # Creating filesystem with 262144 4k blocks and 65536 inodes builder # Creating filesystem with 262144 4k blocks and 65536 inodes server # Filesystem UUID: 811665c3-75b4-4a57-b03f-3960e0c00077 builder # Filesystem UUID: d6d8dda6-7ed0-469e-977e-297a9d80bfeb server # Superblock backups stored on blocks: builder # Superblock backups stored on blocks: server # 32768, 98304, 163840, 229376 builder # 32768, 98304, 163840, 229376 server # builder # server # Allocating group tables: 0/8 done builder # Allocating group tables: 0/8 done server # Writing inode tables: 0/8 done builder # Writing inode tables: 0/8 done server # Creating journal (8192 blocks): done builder # Creating journal (8192 blocks): done server # Writing superblocks and filesystem accounting information: 0/8 done builder # Writing superblocks and filesystem accounting information: 0/8 done server # builder # server # Virtualisation disk image created. builder # Virtualisation disk image created. server # Starting virtiofs daemons... builder # Starting virtiofs daemons... server # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) builder # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) server # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether builder # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether server # [2026-10-01T10:33:28Z INFO virtiofsd] Waiting for vhost-user socket connection... builder # [2026-10-01T10:33:28Z INFO virtiofsd] Waiting for vhost-user socket connection... server # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) builder # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) server # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether builder # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether server # [2026-10-01T10:33:28Z INFO virtiofsd] Waiting for vhost-user socket connection... builder # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) server # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) builder # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether server # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether builder # [2026-10-01T10:33:28Z INFO virtiofsd] Waiting for vhost-user socket connection... server # [2026-10-01T10:33:28Z INFO virtiofsd] Waiting for vhost-user socket connection... builder # [2026-10-01T10:33:28Z INFO virtiofsd] Waiting for vhost-user socket connection... server # [2026-10-01T10:33:28Z INFO virtiofsd] Client connected, servicing requests builder # [2026-10-01T10:33:28Z INFO virtiofsd] Client connected, servicing requests server # [2026-10-01T10:33:28Z INFO virtiofsd] Client connected, servicing requests builder # [2026-10-01T10:33:28Z INFO virtiofsd] Client connected, servicing requests server # [2026-10-01T10:33:28Z INFO virtiofsd] Client connected, servicing requests builder # [2026-10-01T10:33:28Z INFO virtiofsd] Client connected, servicing requests (finished: start all VMs, in 0.38 seconds) server: waiting for unit postgresql.service server: waiting for the VM to finish booting builder # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] builder # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026 builder # [ 0.000000] KASLR enabled server # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026 builder # [ 0.000000] random: crng init done server # [ 0.000000] KASLR enabled builder # [ 0.000000] Machine model: linux,dummy-virt server # [ 0.000000] random: crng init done builder # [ 0.000000] efi: UEFI not found. server # [ 0.000000] Machine model: linux,dummy-virt server # [ 0.000000] efi: UEFI not found. builder # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT builder # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] builder # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f] builder # [ 0.000000] Zone ranges: server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f] server # [ 0.000000] Zone ranges: builder # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] builder # [ 0.000000] DMA32 empty server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] builder # [ 0.000000] Normal empty server # [ 0.000000] DMA32 empty builder # [ 0.000000] Device empty server # [ 0.000000] Normal empty builder # [ 0.000000] Movable zone start for each node server # [ 0.000000] Device empty builder # [ 0.000000] Early memory node ranges server # [ 0.000000] Movable zone start for each node server # [ 0.000000] Early memory node ranges builder # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] builder # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] builder # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 builder # [ 0.000000] psci: probing for conduit method from DT. server # [ 0.000000] psci: probing for conduit method from DT. builder # [ 0.000000] psci: PSCIv1.3 detected in firmware. server # [ 0.000000] psci: PSCIv1.3 detected in firmware. builder # [ 0.000000] psci: Using standard PSCI v0.2 function IDs server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs builder # [ 0.000000] psci: Trusted OS migration not required server # [ 0.000000] psci: Trusted OS migration not required builder # [ 0.000000] psci: SMC Calling Convention v1.1 server # [ 0.000000] psci: SMC Calling Convention v1.1 builder # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) server # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) builder # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 server # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 builder # [ 0.000000] Detected PIPT I-cache on CPU0 server # [ 0.000000] Detected PIPT I-cache on CPU0 builder # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) server # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) builder # [ 0.000000] CPU features: detected: GICv3 CPU interface server # [ 0.000000] CPU features: detected: GICv3 CPU interface builder # [ 0.000000] CPU features: detected: Spectre-v4 server # [ 0.000000] CPU features: detected: Spectre-v4 builder # [ 0.000000] CPU features: detected: Spectre-BHB server # [ 0.000000] CPU features: detected: Spectre-BHB builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 server # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 server # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 builder # [ 0.000000] alternatives: applying boot alternatives server # [ 0.000000] alternatives: applying boot alternatives builder # [ 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/ps81lgv9798c7qwl9q77am5i39ab1lc1-nixos-system-builder-test/init regInfo=/nix/.ro-store/cpdccvwwvkpas0hhmz1zkc20ykvghx6y-closure-info/registration console=ttyAMA0,115200n8 console=tty0 server # [ 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/k66cp5bym2y2x0gahgxdnb3hdjs131vh-nixos-system-server-test/init regInfo=/nix/.ro-store/cvzlm8dfx27rdkmm3w7ng4adlvk0fkb8-closure-info/registration console=ttyAMA0,115200n8 console=tty0 builder # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/cpdccvwwvkpas0hhmz1zkc20ykvghx6y-closure-info/registration", will be passed to user space. builder # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/cvzlm8dfx27rdkmm3w7ng4adlvk0fkb8-closure-info/registration", will be passed to user space. builder # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes builder # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) builder # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) builder # [ 0.000000] software IO TLB: area num 1. server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB builder # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) server # [ 0.000000] software IO TLB: area num 1. builder # [ 0.000000] Fallback order for Node 0: 0 server # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) builder # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 server # [ 0.000000] Fallback order for Node 0: 0 builder # [ 0.000000] Policy zone: DMA server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 builder # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off server # [ 0.000000] Policy zone: DMA builder # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off builder # [ 0.000000] allocated 2097152 bytes of page_ext server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 builder # [ 0.000000] ftrace: allocating 74966 entries in 294 pages server # [ 0.000000] allocated 2097152 bytes of page_ext builder # [ 0.000000] ftrace: allocated 294 pages with 4 groups server # [ 0.000000] ftrace: allocating 74966 entries in 294 pages builder # [ 0.000000] rcu: Hierarchical RCU implementation. server # [ 0.000000] ftrace: allocated 294 pages with 4 groups builder # [ 0.000000] rcu: RCU event tracing is enabled. server # [ 0.000000] rcu: Hierarchical RCU implementation. builder # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. server # [ 0.000000] rcu: RCU event tracing is enabled. builder # [ 0.000000] Trampoline variant of Tasks RCU enabled. server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. builder # [ 0.000000] Rude variant of Tasks RCU enabled. server # [ 0.000000] Trampoline variant of Tasks RCU enabled. builder # [ 0.000000] Tracing variant of Tasks RCU enabled. server # [ 0.000000] Rude variant of Tasks RCU enabled. server # [ 0.000000] Tracing variant of Tasks RCU enabled. builder # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. builder # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 builder # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. server # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. builder # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. builder # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. builder # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. builder # [ 0.000000] GICv3: 256 SPIs implemented server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 builder # [ 0.000000] GICv3: 0 Extended SPIs implemented server # [ 0.000000] GICv3: 256 SPIs implemented builder # [ 0.000000] Root IRQ handler: gic_handle_irq server # [ 0.000000] GICv3: 0 Extended SPIs implemented builder # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI server # [ 0.000000] Root IRQ handler: gic_handle_irq builder # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 server # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 builder # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 builder # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) builder # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 server # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 builder # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 builder # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. builder # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns server # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns builder # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). builder # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns builder # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns builder # [ 0.000031] arm-pv: using stolen time PV server # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns server # [ 0.000031] arm-pv: using stolen time PV builder # [ 0.000418] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) server # [ 0.000430] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) builder # [ 0.000616] Console: colour dummy device 80x25 server # [ 0.000628] Console: colour dummy device 80x25 builder # [ 0.000624] printk: legacy console [tty0] enabled server # [ 0.000636] printk: legacy console [tty0] enabled builder # [ 0.000820] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) server # [ 0.000824] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) builder # [ 0.000826] pid_max: default: 32768 minimum: 301 server # [ 0.000830] pid_max: default: 32768 minimum: 301 builder # [ 0.000901] LSM: initializing lsm=capability,landlock,yama,bpf,ima builder # [ 0.001039] landlock: Up and running. server # [ 0.000913] LSM: initializing lsm=capability,landlock,yama,bpf,ima builder # [ 0.001043] Yama: becoming mindful. server # [ 0.001057] landlock: Up and running. builder # [ 0.001542] LSM support for eBPF active server # [ 0.001060] Yama: becoming mindful. server # [ 0.001496] LSM support for eBPF active builder # [ 0.001660] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) server # [ 0.001606] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) builder # [ 0.001681] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) server # [ 0.001628] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) builder # [ 0.003628] rcu: Hierarchical SRCU implementation. server # [ 0.003575] rcu: Hierarchical SRCU implementation. builder # [ 0.003633] rcu: Max phase no-delay instances is 1000. server # [ 0.003581] rcu: Max phase no-delay instances is 1000. builder # [ 0.005020] fsl-mc MSI: its@8080000 domain created server # [ 0.004893] fsl-mc MSI: its@8080000 domain created builder # [ 0.005143] EFI services will not be available. server # [ 0.005015] EFI services will not be available. server # [ 0.005138] smp: Bringing up secondary CPUs ... builder # [ 0.005221] smp: Bringing up secondary CPUs ... server # [ 0.005147] smp: Brought up 1 node, 1 CPU builder # [ 0.005230] smp: Brought up 1 node, 1 CPU server # [ 0.005150] SMP: Total of 1 processors activated. builder # [ 0.005233] SMP: Total of 1 processors activated. server # [ 0.005153] CPU: All CPU(s) started at EL1 builder # [ 0.005236] CPU: All CPU(s) started at EL1 server # [ 0.005166] CPU features: detected: Branch Target Identification builder # [ 0.005248] CPU features: detected: Branch Target Identification server # [ 0.005171] CPU features: detected: ARMv8.4 Translation Table Level builder # [ 0.005253] CPU features: detected: ARMv8.4 Translation Table Level server # [ 0.005174] CPU features: detected: Instruction cache invalidation not required for I/D coherence builder # [ 0.005256] CPU features: detected: Instruction cache invalidation not required for I/D coherence server # [ 0.005178] CPU features: detected: Data cache clean to the PoU not required for I/D coherence builder # [ 0.005260] CPU features: detected: Data cache clean to the PoU not required for I/D coherence server # [ 0.005181] CPU features: detected: Common not Private translations builder # [ 0.005264] CPU features: detected: Common not Private translations server # [ 0.005185] CPU features: detected: CRC32 instructions builder # [ 0.005267] CPU features: detected: CRC32 instructions server # [ 0.005188] CPU features: detected: Data cache clean to Point of Deep Persistence builder # [ 0.005270] CPU features: detected: Data cache clean to Point of Deep Persistence server # [ 0.005191] CPU features: detected: Data cache clean to Point of Persistence builder # [ 0.005273] CPU features: detected: Data cache clean to Point of Persistence server # [ 0.005194] CPU features: detected: Data independent timing control (DIT) builder # [ 0.005276] CPU features: detected: Data independent timing control (DIT) server # [ 0.005197] CPU features: detected: E0PD builder # [ 0.005279] CPU features: detected: E0PD server # [ 0.005199] CPU features: detected: Enhanced Counter Virtualization builder # [ 0.005281] CPU features: detected: Enhanced Counter Virtualization server # [ 0.005203] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) builder # [ 0.005285] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) server # [ 0.005206] CPU features: detected: Enhanced Virtualization Traps builder # [ 0.005288] CPU features: detected: Enhanced Virtualization Traps server # [ 0.005210] CPU features: detected: Fine Grained Traps builder # [ 0.005291] CPU features: detected: Fine Grained Traps server # [ 0.005214] CPU features: detected: Generic authentication (architected QARMA5 algorithm) builder # [ 0.005295] CPU features: detected: Generic authentication (architected QARMA5 algorithm) server # [ 0.005220] CPU features: detected: RCpc load-acquire (LDAPR) builder # [ 0.005300] CPU features: detected: RCpc load-acquire (LDAPR) server # [ 0.005223] CPU features: detected: LSE atomic instructions builder # [ 0.005303] CPU features: detected: LSE atomic instructions server # [ 0.005226] CPU features: detected: Privileged Access Never builder # [ 0.005306] CPU features: detected: Privileged Access Never server # [ 0.005228] CPU features: detected: PMUv3 builder # [ 0.005309] CPU features: detected: PMUv3 server # [ 0.005232] CPU features: detected: RAS Extension Support builder # [ 0.005311] CPU features: detected: RAS Extension Support server # [ 0.005235] CPU features: detected: RASv1p1 Extension Support builder # [ 0.005314] CPU features: detected: RASv1p1 Extension Support server # [ 0.005237] CPU features: detected: Random Number Generator builder # [ 0.005317] CPU features: detected: Random Number Generator server # [ 0.005240] CPU features: detected: Speculation barrier (SB) builder # [ 0.005320] CPU features: detected: Speculation barrier (SB) server # [ 0.005243] CPU features: detected: Stage-2 Force Write-Back builder # [ 0.005322] CPU features: detected: Stage-2 Force Write-Back server # [ 0.005246] CPU features: detected: TLB range maintenance instructions builder # [ 0.005325] CPU features: detected: TLB range maintenance instructions server # [ 0.005250] CPU features: detected: Speculative Store Bypassing Safe (SSBS) builder # [ 0.005330] CPU features: detected: Speculative Store Bypassing Safe (SSBS) server # [ 0.005287] alternatives: applying system-wide alternatives builder # [ 0.005368] alternatives: applying system-wide alternatives server # [ 0.008403] CPU features: detected: BBM Level 2 without TLB conflict abort builder # [ 0.008381] CPU features: detected: BBM Level 2 without TLB conflict abort server # [ 0.008559] Memory: 893216K/1048576K available (24448K kernel code, 7094K rwdata, 26596K rodata, 4736K init, 1107K bss, 114056K reserved, 32768K cma-reserved) builder # [ 0.008566] Memory: 893184K/1048576K available (24448K kernel code, 7094K rwdata, 26596K rodata, 4736K init, 1107K bss, 114084K reserved, 32768K cma-reserved) builder # [ 0.008918] devtmpfs: initialized builder # [ 0.010678] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) builder # [ 0.010702] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). builder # [ 0.010899] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL builder # [ 0.010909] 0 pages in range for non-PLT usage builder # [ 0.010910] 508224 pages in range for PLT usage builder # [ 0.011026] pinctrl core: initialized pinctrl subsystem builder # [ 0.011840] DMI not present or invalid. builder # [ 0.015262] NET: Registered PF_NETLINK/PF_ROUTE protocol family builder # [ 0.017670] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations builder # [ 0.017812] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations server # [ 0.008920] devtmpfs: initialized server # [ 0.010689] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) builder # [ 0.017972] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations builder # [ 0.017993] audit: initializing netlink subsys (disabled) server # [ 0.010712] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). builder # [ 0.018575] thermal_sys: Registered thermal governor 'fair_share' server # [ 0.010948] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL builder # [ 0.018577] thermal_sys: Registered thermal governor 'bang_bang' server # [ 0.010955] 0 pages in range for non-PLT usage server # [ 0.010957] 508224 pages in range for PLT usage builder # [ 0.018581] thermal_sys: Registered thermal governor 'step_wise' server # [ 0.011077] pinctrl core: initialized pinctrl subsystem builder # [ 0.018584] thermal_sys: Registered thermal governor 'user_space' server # [ 0.011844] DMI not present or invalid. builder # [ 0.018586] thermal_sys: Registered thermal governor 'power_allocator' server # [ 0.015081] NET: Registered PF_NETLINK/PF_ROUTE protocol family builder # [ 0.018621] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 server # [ 0.017446] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations builder # [ 0.018629] cpuidle: using governor ladder builder # [ 0.018635] cpuidle: using governor menu server # [ 0.017604] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations builder # [ 0.018865] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. server # [ 0.017759] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations builder # [ 0.018882] ASID allocator initialised with 65536 entries server # [ 0.017781] audit: initializing netlink subsys (disabled) builder # [ 0.020233] Serial: AMBA PL011 UART driver server # [ 0.018366] thermal_sys: Registered thermal governor 'fair_share' builder # [ 0.025822] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 server # [ 0.018368] thermal_sys: Registered thermal governor 'bang_bang' builder # [ 0.025983] printk: console [ttyAMA0] enabled server # [ 0.018371] thermal_sys: Registered thermal governor 'step_wise' server # [ 0.018374] thermal_sys: Registered thermal governor 'user_space' server # [ 0.018377] thermal_sys: Registered thermal governor 'power_allocator' server # [ 0.018403] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 server # [ 0.018411] cpuidle: using governor ladder server # [ 0.018416] cpuidle: using governor menu server # [ 0.018629] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. server # [ 0.018643] ASID allocator initialised with 65536 entries server # [ 0.019874] Serial: AMBA PL011 UART driver server # [ 0.025443] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 server # [ 0.025596] printk: console [ttyAMA0] enabled server # [ 0.158119] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages builder # [ 0.157809] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages server # [ 0.158129] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page builder # [ 0.157818] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page server # [ 0.158132] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages builder # [ 0.157821] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages server # [ 0.158135] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page builder # [ 0.157824] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page server # [ 0.158137] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages builder # [ 0.157826] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages server # [ 0.158140] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page builder # [ 0.157829] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page server # [ 0.158143] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages builder # [ 0.157832] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages server # [ 0.158145] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page builder # [ 0.157835] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page server # [ 0.159398] fbcon: Taking over console builder # [ 0.159006] fbcon: Taking over console server # [ 0.159408] ACPI: Interpreter disabled. builder # [ 0.159015] ACPI: Interpreter disabled. server # [ 0.168208] iommu: Default domain type: Translated builder # [ 0.169437] iommu: Default domain type: Translated server # [ 0.168213] iommu: DMA domain TLB invalidation policy: strict mode builder # [ 0.169442] iommu: DMA domain TLB invalidation policy: strict mode builder # [ 0.169835] SCSI subsystem initialized server # [ 0.170011] SCSI subsystem initialized builder # [ 0.172001] usbcore: registered new interface driver usbfs server # [ 0.170191] usbcore: registered new interface driver usbfs builder # [ 0.172037] usbcore: registered new interface driver hub server # [ 0.170220] usbcore: registered new interface driver hub builder # [ 0.172053] usbcore: registered new device driver usb server # [ 0.170235] usbcore: registered new device driver usb builder # [ 0.172347] pps_core: LinuxPPS API ver. 1 registered server # [ 0.170543] pps_core: LinuxPPS API ver. 1 registered builder # [ 0.172351] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti server # [ 0.170547] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti builder # [ 0.172359] PTP clock support registered server # [ 0.170556] PTP clock support registered builder # [ 0.172407] EDAC MC: Ver: 3.0.0 server # [ 0.170595] EDAC MC: Ver: 3.0.0 builder # [ 0.172579] scmi_core: SCMI protocol bus registered server # [ 0.177948] scmi_core: SCMI protocol bus registered builder # [ 0.179533] FPGA manager framework server # [ 0.178316] FPGA manager framework builder # [ 0.180149] vgaarb: loaded server # [ 0.178865] vgaarb: loaded builder # [ 0.182384] clocksource: Switched to clocksource arch_sys_counter server # [ 0.179129] clocksource: Switched to clocksource arch_sys_counter server # [ 0.187256] VFS: Disk quotas dquot_6.6.0 builder # [ 0.189237] VFS: Disk quotas dquot_6.6.0 server # [ 0.187281] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) builder # [ 0.189268] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) server # [ 0.189756] netfs: FS-Cache loaded builder # [ 0.191679] netfs: FS-Cache loaded server # [ 0.189873] pnp: PnP ACPI: disabled builder # [ 0.191802] pnp: PnP ACPI: disabled server # [ 0.195600] NET: Registered PF_INET protocol family builder # [ 0.197369] NET: Registered PF_INET protocol family server # [ 0.195752] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) builder # [ 0.197533] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) builder # [ 0.228951] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) builder # [ 0.228992] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) builder # [ 0.229016] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) builder # [ 0.229058] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) server # [ 0.225444] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) builder # [ 0.229130] TCP: Hash tables configured (established 8192 bind 8192) server # [ 0.225480] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) builder # [ 0.229214] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) server # [ 0.225503] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) builder # [ 0.229243] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) builder # [ 0.229301] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) server # [ 0.225544] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) builder # [ 0.229391] NET: Registered PF_UNIX/PF_LOCAL protocol family server # [ 0.225616] TCP: Hash tables configured (established 8192 bind 8192) builder # [ 0.229413] NET: Registered PF_XDP protocol family server # [ 0.225696] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) builder # [ 0.229429] PCI: CLS 0 bytes, default 64 builder # [ 0.229762] Trying to unpack rootfs image as initramfs... server # [ 0.225723] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) server # [ 0.225746] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) server # [ 0.225820] NET: Registered PF_UNIX/PF_LOCAL protocol family builder # [ 0.243322] kvm [1]: HYP mode not available server # [ 0.225842] NET: Registered PF_XDP protocol family server # [ 0.225857] PCI: CLS 0 bytes, default 64 server # [ 0.226127] Trying to unpack rootfs image as initramfs... server # [ 0.245914] kvm [1]: HYP mode not available builder # [ 0.339775] Initialise system trusted keyrings server # [ 0.339632] Initialise system trusted keyrings builder # [ 0.341105] workingset: timestamp_bits=42 max_order=18 bucket_order=0 server # [ 0.339773] workingset: timestamp_bits=42 max_order=18 bucket_order=0 server # [ 0.340177] squashfs: version 4.0 (2009/01/31) Phillip Lougher server # [ 0.340239] 9p: Installing v9fs 9p2000 file system support builder # [ 0.345616] squashfs: version 4.0 (2009/01/31) Phillip Lougher builder # [ 0.345697] 9p: Installing v9fs 9p2000 file system support server # [ 0.368299] Key type asymmetric registered server # [ 0.368315] Asymmetric key parser 'x509' registered server # [ 0.368382] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) server # [ 0.368513] io scheduler mq-deadline registered server # [ 0.368517] io scheduler kyber registered builder # [ 0.373668] Key type asymmetric registered builder # [ 0.373683] Asymmetric key parser 'x509' registered builder # [ 0.373751] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) builder # [ 0.373910] io scheduler mq-deadline registered builder # [ 0.373915] io scheduler kyber registered server # [ 0.380809] pl061_gpio 9030000.pl061: PL061 GPIO chip registered server # [ 0.381381] ledtrig-cpu: registered to indicate activity on CPUs server # [ 0.381771] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: server # [ 0.381785] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 server # [ 0.381794] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 server # [ 0.381800] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 server # [ 0.381824] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits server # [ 0.381844] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] builder # [ 0.388592] pl061_gpio 9030000.pl061: PL061 GPIO chip registered server # [ 0.381917] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 builder # [ 0.389282] ledtrig-cpu: registered to indicate activity on CPUs server # [ 0.381923] pci_bus 0000:00: root bus resource [bus 00-ff] server # [ 0.381928] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] builder # [ 0.389660] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: server # [ 0.381931] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] builder # [ 0.389673] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 server # [ 0.381934] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] builder # [ 0.389683] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 server # [ 0.381999] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint builder # [ 0.389689] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 server # [ 0.382456] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint server # [ 0.382645] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] builder # [ 0.389719] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits server # [ 0.382657] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] builder # [ 0.389743] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] server # [ 0.382684] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] builder # [ 0.389854] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 server # [ 0.382696] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] builder # [ 0.389861] pci_bus 0000:00: root bus resource [bus 00-ff] builder # [ 0.389866] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] builder # [ 0.389869] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] builder # [ 0.389872] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] builder # [ 0.389959] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint builder # [ 0.390423] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint builder # [ 0.390614] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] builder # [ 0.390626] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] builder # [ 0.390653] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] builder # [ 0.390665] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] builder # [ 0.391166] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint builder # [ 0.391370] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] builder # [ 0.391381] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 0.407384] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint server # [ 0.407593] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] builder # [ 0.391407] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 0.407606] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] builder # [ 0.391912] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint server # [ 0.407633] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] builder # [ 0.392101] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] builder # [ 0.392112] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 0.408146] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint server # [ 0.408336] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] builder # [ 0.392138] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 0.408348] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 0.408374] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 0.408847] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint server # [ 0.409039] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] builder # [ 0.417855] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint server # [ 0.409051] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] builder # [ 0.418047] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] server # [ 0.409077] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] builder # [ 0.418058] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 0.409089] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] builder # [ 0.418085] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 0.409567] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint builder # [ 0.418096] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] server # [ 0.409763] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] builder # [ 0.418593] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint server # [ 0.409789] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] builder # [ 0.418786] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 0.410264] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint builder # [ 0.418813] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 0.410459] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 0.410485] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] builder # [ 0.419299] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint builder # [ 0.419493] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 0.410900] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint builder # [ 0.419519] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 0.411087] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] builder # [ 0.419984] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint builder # [ 0.420172] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] server # [ 0.429554] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint server # [ 0.429749] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 0.429775] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 0.430254] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint server # [ 0.430447] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 0.430473] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 0.431001] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint builder # [ 0.436551] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint builder # [ 0.436761] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] builder # [ 0.436788] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] builder # [ 0.437338] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint builder # [ 0.437533] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] builder # [ 0.437560] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] builder # [ 0.438060] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint builder # [ 0.438254] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] builder # [ 0.438280] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 0.443255] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] builder # [ 0.438781] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint server # [ 0.443288] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] builder # [ 0.439071] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] server # [ 0.443817] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint builder # [ 0.439083] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 0.444085] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] builder # [ 0.439109] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 0.444097] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 0.444124] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] builder # [ 0.439595] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint builder # [ 0.439781] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] server # [ 0.444639] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint builder # [ 0.439793] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 0.444833] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] builder # [ 0.439819] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] server # [ 0.444845] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] server # [ 0.444872] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] builder # [ 0.455772] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned server # [ 0.445503] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned builder # [ 0.455779] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned server # [ 0.445509] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned builder # [ 0.455783] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned server # [ 0.445512] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned builder # [ 0.455831] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned server # [ 0.445559] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned builder # [ 0.455881] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned server # [ 0.445605] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned builder # [ 0.455930] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned server # [ 0.445652] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned builder # [ 0.455977] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned server # [ 0.445698] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned builder # [ 0.456023] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned server # [ 0.445743] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned builder # [ 0.456070] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned server # [ 0.445790] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned builder # [ 0.456114] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned server # [ 0.445835] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned builder # [ 0.456162] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned server # [ 0.445881] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned builder # [ 0.456211] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned server # [ 0.445926] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned builder # [ 0.456293] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned server # [ 0.445992] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned builder # [ 0.456338] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned server # [ 0.446036] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned builder # [ 0.456356] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned server # [ 0.446055] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned builder # [ 0.456374] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned server # [ 0.446073] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned builder # [ 0.456392] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned server # [ 0.446090] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned builder # [ 0.456411] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned server # [ 0.446108] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned server # [ 0.446127] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned server # [ 0.446145] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned server # [ 0.446164] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned server # [ 0.446186] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned server # [ 0.446205] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned server # [ 0.446224] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned server # [ 0.446243] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned server # [ 0.446261] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned server # [ 0.446278] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned server # [ 0.446296] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned server # [ 0.446314] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned server # [ 0.446332] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned builder # [ 0.480471] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned server # [ 0.446349] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned builder # [ 0.480520] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned server # [ 0.446372] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] builder # [ 0.480541] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned server # [ 0.446377] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] builder # [ 0.480563] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned server # [ 0.446380] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] builder # [ 0.480582] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned builder # [ 0.480601] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned builder # [ 0.480621] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned builder # [ 0.480639] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned builder # [ 0.480658] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned server # [ 0.487531] pci 0000:00:07.0: enabling device (0000 -> 0002) builder # [ 0.480676] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned builder # [ 0.480695] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned builder # [ 0.480714] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned builder # [ 0.480732] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned builder # [ 0.480759] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] builder # [ 0.480764] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] builder # [ 0.480767] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] builder # [ 0.481682] pci 0000:00:07.0: enabling device (0000 -> 0002) server # [ 0.517967] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) builder # [ 0.517406] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) builder # [ 0.519502] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) server # [ 0.521332] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) builder # [ 0.529875] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) builder # [ 0.531999] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) server # [ 0.531388] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) server # [ 0.533386] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) server # [ 0.537114] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) builder # [ 0.541884] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) builder # [ 0.543822] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) server # [ 0.540192] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) server # [ 0.542823] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) builder # [ 0.554168] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) server # [ 0.552409] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) builder # [ 0.556048] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) server # [ 0.554234] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) builder # [ 0.559616] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) server # [ 0.564559] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) server # [ 0.566816] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) builder # [ 0.569910] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) builder # [ 0.572202] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) server # [ 0.584369] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled builder # [ 0.585738] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled server # [ 0.586173] msm_serial: driver initialized builder # [ 0.587606] msm_serial: driver initialized server # [ 0.586309] SuperH (H)SCI(F) driver initialized builder # [ 0.587739] SuperH (H)SCI(F) driver initialized server # [ 0.586366] STM32 USART driver initialized builder # [ 0.587796] STM32 USART driver initialized builder # [ 0.621253] loop: module loaded builder # [ 0.621417] virtio_blk virtio2: 1/0/0 default/read/poll queues builder # [ 0.622244] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) server # [ 0.624374] loop: module loaded server # [ 0.624561] virtio_blk virtio2: 1/0/0 default/read/poll queues server # [ 0.625365] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) server # [ 0.629859] megasas: 07.734.00.00-rc1 builder # [ 0.629041] megasas: 07.734.00.00-rc1 server # [ 0.630542] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] builder # [ 0.629728] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] builder # [ 0.631593] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 builder # [ 0.631609] Intel/Sharp Extended Query Table at 0x0031 server # [ 0.634110] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 server # [ 0.634125] Intel/Sharp Extended Query Table at 0x0031 server # [ 0.643783] Using buffer write method builder # [ 0.645564] Using buffer write method server # [ 0.643843] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] builder # [ 0.645602] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] server # [ 0.645527] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 builder # [ 0.647249] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 server # [ 0.645541] Intel/Sharp Extended Query Table at 0x0031 builder # [ 0.647264] Intel/Sharp Extended Query Table at 0x0031 server # [ 0.650725] Using buffer write method server # [ 0.650738] Concatenating MTD devices: server # [ 0.650740] (0): "0.flash" server # [ 0.650742] (1): "0.flash" server # [ 0.650744] into device "0.flash" builder # [ 0.652763] Using buffer write method builder # [ 0.652777] Concatenating MTD devices: builder # [ 0.652779] (0): "0.flash" builder # [ 0.652781] (1): "0.flash" builder # [ 0.652783] into device "0.flash" server # [ 0.915416] Freeing initrd memory: 26944K server # [ 0.921640] tun: Universal TUN/TAP device driver, 1.6 server # [ 0.925465] thunder_xcv, ver 1.0 server # [ 0.925492] thunder_bgx, ver 1.0 builder # [ 0.927307] Freeing initrd memory: 26972K server # [ 0.925511] nicpf, ver 1.0 server # [ 0.926083] e1000: Intel(R) PRO/1000 Network Driver server # [ 0.926087] e1000: Copyright (c) 1999-2006 Intel Corporation. server # [ 0.926109] e1000e: Intel(R) PRO/1000 Network Driver server # [ 0.926115] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. server # [ 0.926141] igb: Intel(R) Gigabit Ethernet Network Driver server # [ 0.926145] igb: Copyright (c) 2007-2014 Intel Corporation. server # [ 0.926169] igbvf: Intel(R) Gigabit Virtual Function Network Driver server # [ 0.926173] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. server # [ 0.926308] sky2: driver version 1.30 builder # [ 0.933602] tun: Universal TUN/TAP device driver, 1.6 builder # [ 0.937461] thunder_xcv, ver 1.0 server # [ 0.935642] usbcore: registered new interface driver usb-storage builder # [ 0.937486] thunder_bgx, ver 1.0 builder # [ 0.937506] nicpf, ver 1.0 server # [ 0.935687] usbcore: registered new interface driver usbserial_generic builder # [ 0.938075] e1000: Intel(R) PRO/1000 Network Driver server # [ 0.935698] usbserial: USB Serial support registered for generic builder # [ 0.938080] e1000: Copyright (c) 1999-2006 Intel Corporation. server # [ 0.936304] hv_vmbus: registering driver hyperv_keyboard builder # [ 0.938105] e1000e: Intel(R) PRO/1000 Network Driver server # [ 0.937625] ehci-pci 0000:00:07.0: EHCI Host Controller builder # [ 0.938113] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. server # [ 0.937642] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 builder # [ 0.938136] igb: Intel(R) Gigabit Ethernet Network Driver server # [ 0.937879] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 builder # [ 0.938140] igb: Copyright (c) 2007-2014 Intel Corporation. server # [ 0.938186] rtc-pl031 9010000.pl031: registered as rtc0 builder # [ 0.938160] igbvf: Intel(R) Gigabit Virtual Function Network Driver server # [ 0.938213] rtc-pl031 9010000.pl031: setting system clock to 2026-10-01T10:33:30 UTC (1790850810) builder # [ 0.938164] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. server # [ 0.938552] i2c_dev: i2c /dev entries driver builder # [ 0.938299] sky2: driver version 1.30 builder # [ 0.940044] usbcore: registered new interface driver usb-storage builder # [ 0.940154] usbcore: registered new interface driver usbserial_generic builder # [ 0.940165] usbserial: USB Serial support registered for generic server # [ 0.947395] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 builder # [ 0.951323] ehci-pci 0000:00:07.0: EHCI Host Controller server # [ 0.947666] hub 1-0:1.0: USB hub found server # [ 0.947681] hub 1-0:1.0: 6 ports detected builder # [ 0.951341] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 server # [ 0.950465] sdhci: Secure Digital Host Controller Interface driver builder # [ 0.951554] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 server # [ 0.950470] sdhci: Copyright(c) Pierre Ossman builder # [ 0.952023] hv_vmbus: registering driver hyperv_keyboard server # [ 0.950748] Synopsys Designware Multimedia Card Interface Driver builder # [ 0.956108] rtc-pl031 9010000.pl031: registered as rtc0 builder # [ 0.956132] rtc-pl031 9010000.pl031: setting system clock to 2026-10-01T10:33:30 UTC (1790850810) server # [ 0.955174] sdhci-pltfm: SDHCI platform and OF driver helper server # [ 0.956744] hid: raw HID events driver (C) Jiri Kosina builder # [ 0.958299] i2c_dev: i2c /dev entries driver server # [ 0.956977] usbcore: registered new interface driver usbhid builder # [ 0.960483] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 server # [ 0.956984] usbhid: USB HID core driver builder # [ 0.960769] hub 1-0:1.0: USB hub found builder # [ 0.960784] hub 1-0:1.0: 6 ports detected server # [ 0.958139] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available server # [ 0.963651] drop_monitor: Initializing network drop monitor service builder # [ 0.964723] sdhci: Secure Digital Host Controller Interface driver server # [ 0.963800] NET: Registered PF_INET6 protocol family builder # [ 0.964728] sdhci: Copyright(c) Pierre Ossman server # [ 0.964372] Segment Routing with IPv6 server # [ 0.964383] In-situ OAM (IOAM) with IPv6 builder # [ 0.965014] Synopsys Designware Multimedia Card Interface Driver server # [ 0.964409] NET: Registered PF_PACKET protocol family builder # [ 0.965401] sdhci-pltfm: SDHCI platform and OF driver helper server # [ 0.964466] 9pnet: Installing 9P2000 support builder # [ 0.966971] hid: raw HID events driver (C) Jiri Kosina server # [ 0.964503] Key type dns_resolver registered builder # [ 0.967214] usbcore: registered new interface driver usbhid builder # [ 0.967221] usbhid: USB HID core driver builder # [ 0.968379] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available builder # [ 0.976136] drop_monitor: Initializing network drop monitor service builder # [ 0.976303] NET: Registered PF_INET6 protocol family builder # [ 0.978317] Segment Routing with IPv6 server # [ 0.974935] registered taskstats version 1 builder # [ 0.978329] In-situ OAM (IOAM) with IPv6 server # [ 0.975096] Loading compiled-in X.509 certificates builder # [ 0.978355] NET: Registered PF_PACKET protocol family builder # [ 0.978408] 9pnet: Installing 9P2000 support builder # [ 0.978446] Key type dns_resolver registered server # [ 0.983661] Demotion targets for Node 0: null builder # [ 0.987699] registered taskstats version 1 server # [ 0.983755] Key type .fscrypt registered builder # [ 0.987908] Loading compiled-in X.509 certificates server # [ 0.983762] Key type fscrypt-provisioning registered server # [ 0.983861] ima: No TPM chip found, activating TPM-bypass! server # [ 0.983878] ima: Allocated hash algorithm: sha1 server # [ 0.983902] ima: No architecture policies found server # [ 0.984471] input: gpio-keys as /devices/platform/gpio-keys/input/input0 builder # [ 0.996837] Demotion targets for Node 0: null builder # [ 0.996935] Key type .fscrypt registered builder # [ 0.996941] Key type fscrypt-provisioning registered builder # [ 0.997039] ima: No TPM chip found, activating TPM-bypass! builder # [ 0.997056] ima: Allocated hash algorithm: sha1 builder # [ 0.997074] ima: No architecture policies found builder # [ 0.997659] input: gpio-keys as /devices/platform/gpio-keys/input/input0 server # [ 1.006759] clk: Disabling unused clocks server # [ 1.006775] PM: genpd: Disabling unused power domains server # [ 1.011087] Freeing unused kernel memory: 4736K server # [ 1.011902] Run /init as init process builder # [ 1.020499] clk: Disabling unused clocks builder # [ 1.020513] PM: genpd: Disabling unused power domains builder # [ 1.023535] Freeing unused kernel memory: 4736K builder # [ 1.023713] Run /init as init process server # [ 1.025434] systemd[1]: Successfully made /usr/ read-only. builder # [ 1.042739] systemd[1]: Successfully made /usr/ read-only. server # [ 1.195185] usb 1-1: new high-speed USB device number 2 using ehci-pci builder # [ 1.208485] usb 1-1: new high-speed USB device number 2 using ehci-pci server # [ 1.344821] 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/input1 builder # [ 1.358060] 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/input1 server # [ 1.359327] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) server # [ 1.359350] systemd[1]: Detected virtualization qemu. server # [ 1.359388] systemd[1]: Detected architecture arm64. server # [ 1.359396] systemd[1]: Running in initrd. server # [ 1.359907] systemd[1]: Initializing machine ID from random generator. server # [ 1.360052] systemd[1]: Hostname set to . builder # [ 1.376698] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) builder # [ 1.376722] systemd[1]: Detected virtualization qemu. builder # [ 1.376764] systemd[1]: Detected architecture arm64. builder # [ 1.376773] systemd[1]: Running in initrd. builder # [ 1.377301] systemd[1]: Initializing machine ID from random generator. builder # [ 1.377449] systemd[1]: Hostname set to . server # [ 1.435350] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0 builder # [ 1.444625] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0 server # [ 1.555195] usb 1-2: new high-speed USB device number 3 using ehci-pci builder # [ 1.564485] usb 1-2: new high-speed USB device number 3 using ehci-pci server # [ 1.631365] systemd[1]: bpf-restrict-fs: LSM BPF program attached builder # [ 1.648868] systemd[1]: bpf-restrict-fs: LSM BPF program attached server # [ 1.704361] systemd[1]: Queued start job for default target Initrd Default Target. server # [ 1.710799] 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/input2 server # [ 1.710956] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 server # [ 1.718603] systemd[1]: Created slice Slice /system/modprobe. server # [ 1.718815] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. server # [ 1.718867] systemd[1]: Expecting device /dev/disk/by-label/nixos... server # [ 1.718894] systemd[1]: Reached target Path Units. builder # [ 1.723207] 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/input2 server # [ 1.718911] systemd[1]: Reached target Slice Units. server # [ 1.718929] systemd[1]: Reached target Swaps. server # [ 1.718947] systemd[1]: Reached target Timer Units. server # [ 1.723434] systemd[1]: Listening on D-Bus System Message Bus Socket. builder # [ 1.727018] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 server # [ 1.723621] systemd[1]: Listening on Journal Socket (/dev/log). server # [ 1.723761] systemd[1]: Listening on Journal Sockets. server # [ 1.723893] systemd[1]: Listening on udev Control Socket. server # [ 1.724007] systemd[1]: Listening on udev Kernel Socket. server # [ 1.724026] systemd[1]: Reached target Socket Units. server # [ 1.733592] systemd[1]: Starting Create List of Static Device Nodes... builder # [ 1.733192] systemd[1]: Queued start job for default target Initrd Default Target. server # [ 1.733662] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs builder # [ 1.743058] systemd[1]: Created slice Slice /system/modprobe. builder # [ 1.743281] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. builder # [ 1.743314] systemd[1]: Expecting device /dev/disk/by-label/nixos... builder # [ 1.743338] systemd[1]: Reached target Path Units. server # [ 1.743296] systemd[1]: Mounting Kernel Configuration File System... builder # [ 1.743354] systemd[1]: Reached target Slice Units. builder # [ 1.743371] systemd[1]: Reached target Swaps. builder # [ 1.743386] systemd[1]: Reached target Timer Units. builder # [ 1.743578] systemd[1]: Listening on D-Bus System Message Bus Socket. builder # [ 1.743747] systemd[1]: Listening on Journal Socket (/dev/log). builder # [ 1.744045] systemd[1]: Listening on Journal Sockets. builder # [ 1.744179] systemd[1]: Listening on udev Control Socket. server # [ 1.752499] systemd[1]: Starting Journal Service... builder # [ 1.744259] systemd[1]: Listening on udev Kernel Socket. builder # [ 1.744275] systemd[1]: Reached target Socket Units. builder # [ 1.758611] systemd[1]: Starting Create List of Static Device Nodes... builder # [ 1.758682] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs builder # [ 1.768347] systemd[1]: Mounting Kernel Configuration File System... server # [ 1.767503] systemd[1]: Starting Load Kernel Modules... server # [ 1.767602] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os builder # [ 1.784593] systemd[1]: Starting Journal Service... builder # [ 1.789267] systemd[1]: Starting Load Kernel Modules... builder # [ 1.789367] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os builder # [ 1.800101] systemd[1]: Starting Coldplug All udev Devices... server # [ 1.808170] systemd-journald[71]: Collecting audit messages is disabled. server # [ 1.811304] systemd[1]: Starting Coldplug All udev Devices... server # [ 1.818333] systemd[1]: Finished Create List of Static Device Nodes. server # [ 1.819065] systemd[1]: Mounted Kernel Configuration File System. builder # [ 1.824594] systemd[1]: Finished Create List of Static Device Nodes. builder # [ 1.829820] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... server # [ 1.831486] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... builder # [ 1.839008] systemd-journald[71]: Collecting audit messages is disabled. builder # [ 1.846718] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. builder # [ 1.852804] systemd[1]: Mounted Kernel Configuration File System. builder # [ 1.857450] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev builder # [ 1.875966] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 builder # [ 1.876212] [drm] features: -virgl +edid -resource_blob -host_visible builder # [ 1.876215] [drm] features: -context_init server # [ 1.876972] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. server # [ 1.877800] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. server # [ 1.879484] systemd[1]: Starting Create Static Device Nodes in /dev... builder # [ 1.889112] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. builder # [ 1.890509] systemd[1]: Starting Create Static Device Nodes in /dev... builder # [ 1.897148] [drm] number of scanouts: 1 builder # [ 1.897163] [drm] number of cap sets: 0 builder # [ 1.897680] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic builder # [ 1.897689] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 server # [ 1.895251] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev server # [ 1.909862] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 server # [ 1.910097] [drm] features: -virgl +edid -resource_blob -host_visible server # [ 1.910100] [drm] features: -context_init server # [ 1.910823] [drm] number of scanouts: 1 server # [ 1.910898] [drm] number of cap sets: 0 builder # [ 1.924873] Console: switching to colour frame buffer device 160x50 server # [ 1.927782] systemd[1]: Finished Create Static Device Nodes in /dev. server # [ 1.928122] systemd[1]: Reached target Preparation for Local File Systems. server # [ 1.928147] systemd[1]: Reached target Local File Systems. server # [ 1.929660] systemd[1]: Starting Rule-based Manager for Device Events and Files... server # [ 1.935533] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic server # [ 1.935543] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 builder # [ 1.945124] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device builder # [ 1.946511] systemd[1]: Finished Create Static Device Nodes in /dev. builder # [ 1.946691] systemd[1]: Reached target Preparation for Local File Systems. builder # [ 1.946722] systemd[1]: Reached target Local File Systems. builder # [ 1.952132] systemd[1]: Starting Rule-based Manager for Device Events and Files... server # [ 1.967507] Console: switching to colour frame buffer device 160x50 builder # [ 1.976596] systemd[1]: Finished Load Kernel Modules. builder # [ 1.978280] systemd[1]: Starting Apply Kernel Variables... server # [ 1.974817] systemd-modules-load[73]: Inserted module 'dm_mod' server # [ 1.987605] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device server # [ 1.987903] systemd[1]: Started Journal Service. server # [ 1.984550] systemd-modules-load[73]: Module 'virtio_balloon' is built in server # [ 1.986478] systemd-modules-load[73]: Module 'virtio_console' is built in server # [ 1.996348] systemd[1]: Starting Create System Files and Directories... server # [ 1.998550] systemd-modules-load[73]: Inserted module 'virtio_gpu' server # [ 2.000451] systemd-modules-load[73]: Module 'virtio_rng' is built in builder # [ 2.003203] systemd-modules-load[72]: Inserted module 'dm_mod' server # [ 2.004284] systemd[1]: Finished Load Kernel Modules. builder # [ 2.006706] systemd-modules-load[72]: Module 'virtio_balloon' is built in builder # [ 2.020874] systemd[1]: Started Journal Service. builder # [ 2.017156] systemd-modules-load[72]: Module 'virtio_console' is built in builder # [ 2.020388] systemd-modules-load[72]: Inserted module 'virtio_gpu' builder # [ 2.022547] systemd-modules-load[72]: Module 'virtio_rng' is built in server # [ 2.024120] systemd[1]: Starting Apply Kernel Variables... server # [ 2.029473] systemd-udevd[77]: Using default interface naming scheme 'v261'. builder # [ 2.032324] systemd[1]: Starting Create System Files and Directories... builder # [ 2.035669] systemd[1]: Finished Apply Kernel Variables. builder # [ 2.040600] systemd-udevd[78]: Using default interface naming scheme 'v261'. server # [ 2.042988] systemd[1]: Finished Create System Files and Directories. builder # [ 2.073465] systemd[1]: Finished Create System Files and Directories. server # [ 2.074439] systemd[1]: Finished Apply Kernel Variables. server # [ 2.076128] systemd[1]: Started Rule-based Manager for Device Events and Files. builder # [ 2.079547] systemd[1]: Started Rule-based Manager for Device Events and Files. server # [ 2.136101] systemd[1]: Starting Virtual Console Setup... builder # [ 2.144140] systemd[1]: Starting Virtual Console Setup... server # [ 2.180439] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. server # [ 2.183993] systemd[1]: Finished Virtual Console Setup. builder # [ 2.192478] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones. builder # [ 2.196044] systemd[1]: Finished Virtual Console Setup. server # [ 2.794127] systemd[1]: Finished Coldplug All udev Devices. server # [ 2.795109] systemd[1]: Reached target System Initialization. server # [ 2.795978] systemd[1]: Reached target Basic System. builder # [ 2.807307] systemd[1]: Finished Coldplug All udev Devices. builder # [ 2.808390] systemd[1]: Reached target System Initialization. builder # [ 2.809239] systemd[1]: Reached target Basic System. server # [ 2.931757] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line. builder # [ 2.936750] (udev-worker)[99]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. server # [ 2.960273] (udev-worker)[100]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. server # [ 2.969044] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line. builder # [ 2.979947] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line. builder # [ 2.996236] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. server # [ 3.037926] systemd[1]: Found device /dev/disk/by-label/nixos. builder # [ 3.046543] systemd[1]: Found device /dev/disk/by-label/nixos. builder # [ 3.049198] systemd[1]: Reached target Initrd Root Device. server # [ 3.049870] systemd[1]: Reached target Initrd Root Device. builder # [ 3.052891] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... server # [ 3.054360] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... server # [ 3.099309] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks server # [ 3.106716] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. builder # [ 3.107585] systemd-fsck[108]: nixos: clean, 12/65536 files, 13019/262144 blocks server # [ 3.111986] systemd[1]: Mounting /sysroot... builder # [ 3.115898] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. builder # [ 3.125252] systemd[1]: Mounting /sysroot... server # [ 3.152926] EXT4-fs (vda): mounted filesystem 811665c3-75b4-4a57-b03f-3960e0c00077 r/w with ordered data mode. Quota mode: none. server # [ 3.145006] systemd[1]: Mounted /sysroot. server # [ 3.146408] systemd[1]: Reached target Initrd Root File System. server # [ 3.156082] systemd[1]: Starting Mountpoints Configured in the Real Root... builder # [ 3.169007] EXT4-fs (vda): mounted filesystem d6d8dda6-7ed0-469e-977e-297a9d80bfeb r/w with ordered data mode. Quota mode: none. builder # [ 3.159097] systemd[1]: Mounted /sysroot. builder # [ 3.160429] systemd[1]: Reached target Initrd Root File System. builder # [ 3.164376] systemd[1]: Starting Mountpoints Configured in the Real Root... server # [ 3.175665] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload. server # [ 3.181661] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)... server # [ 3.187411] systemd[1]: Reloading... builder # [ 3.191069] systemd-sysroot-fstab-check[116]: /sysroot should be mounted in the initrd, will request daemon-reload. builder # [ 3.199316] systemd[1]: Reload requested from client PID 116 ('systemd-sysroot') (unit initrd-parse-etc.service)... builder # [ 3.202977] systemd[1]: Reloading... server # [ 3.396558] systemd[1]: Reloading finished in 212 ms. builder # [ 3.408511] systemd[1]: Reloading finished in 208 ms. server # [ 3.428355] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace... server # [ 3.432215] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace... server # [ 3.439310] systemd[1]: initrd-parse-etc.service: Deactivated successfully. builder # [ 3.440594] systemd-sysroot-fstab-check[116]: Requesting initrd-fs.target/start/replace... server # [ 3.442597] systemd[1]: Finished Mountpoints Configured in the Real Root. builder # [ 3.444904] systemd-sysroot-fstab-check[116]: Requesting swap.target/start/replace... server # [ 3.447360] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. builder # [ 3.451347] systemd[1]: initrd-parse-etc.service: Deactivated successfully. builder # [ 3.454199] systemd[1]: Finished Mountpoints Configured in the Real Root. builder # [ 3.456408] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. server # [ 3.805286] systemd[1]: Mounting /sysroot/nix/.ro-store... builder # [ 3.810369] systemd[1]: Mounting /sysroot/nix/.ro-store... server # [ 3.823445] systemd[1]: Mounting /sysroot/nix/.rw-store... server # [ 3.827706] systemd[1]: Mounting /sysroot/run... builder # [ 3.827711] systemd[1]: Mounting /sysroot/nix/.rw-store... builder # [ 3.832335] systemd[1]: Mounting /sysroot/run... server # [ 3.839637] systemd[1]: Mounting /sysroot/tmp/shared... builder # [ 3.861596] systemd[1]: Mounting /sysroot/tmp/shared... server # [ 3.870170] systemd[1]: Mounting /sysroot/tmp/xchg... builder # [ 3.871995] systemd[1]: Mounting /sysroot/tmp/xchg... builder # [ 3.923735] fuse: init (API version 7.45) server # [ 3.932069] fuse: init (API version 7.45) server # [ 3.922290] systemd[1]: Mounted /sysroot/nix/.rw-store. builder # [ 3.940598] virtiofs virtio6: discovered new tag: nix-store server # [ 3.941755] virtiofs virtio6: discovered new tag: nix-store builder # [ 3.941405] virtiofs virtio6: virtio_fs_setup_dax: No cache capability server # [ 3.942548] virtiofs virtio6: virtio_fs_setup_dax: No cache capability builder # [ 3.932858] systemd[1]: Mounted /sysroot/nix/.rw-store. builder # [ 3.958051] virtiofs virtio7: discovered new tag: shared server # [ 3.956827] virtiofs virtio7: discovered new tag: shared builder # [ 3.958822] virtiofs virtio7: virtio_fs_setup_dax: No cache capability server # [ 3.957593] virtiofs virtio7: virtio_fs_setup_dax: No cache capability builder # [ 3.963917] virtiofs virtio8: discovered new tag: xchg server # [ 3.965409] virtiofs virtio8: discovered new tag: xchg server # [ 3.966184] virtiofs virtio8: virtio_fs_setup_dax: No cache capability server # [ 3.956136] systemd[1]: Starting rw-sysroot-nix-store.service... builder # [ 3.974140] virtiofs virtio8: virtio_fs_setup_dax: No cache capability builder # [ 3.965172] systemd[1]: Starting rw-sysroot-nix-store.service... server # [ 3.970900] systemd[1]: Mounted /sysroot/run. builder # [ 3.976101] systemd[1]: Mounted /sysroot/run. server # [ 3.977531] systemd[1]: Mounted /sysroot/nix/.ro-store. server # [ 3.981514] systemd[1]: Mounted /sysroot/tmp/shared. server # [ 3.984100] systemd[1]: Mounted /sysroot/tmp/xchg. builder # [ 3.994686] systemd[1]: Mounted /sysroot/nix/.ro-store. builder # [ 3.997570] systemd[1]: Mounted /sysroot/tmp/shared. server # [ 4.001581] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. server # [ 4.004187] systemd[1]: Finished rw-sysroot-nix-store.service. builder # [ 4.006375] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. server # [ 4.008165] systemd[1]: Mounting /sysroot/nix/store... builder # [ 4.010936] systemd[1]: Finished rw-sysroot-nix-store.service. builder # [ 4.012694] systemd[1]: Mounted /sysroot/tmp/xchg. server # [ 4.050613] systemd[1]: Mounted /sysroot/nix/store. server # [ 4.052570] systemd[1]: Reached target Initrd File Systems. server # [ 4.054572] systemd[1]: Starting Find NixOS closure... server # [ 4.065769] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... server # [ 4.086531] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. server # [ 4.105802] systemd[1]: Finished Find NixOS closure. server # [ 4.109157] systemd[1]: Reached target Initrd Default Target. server # [ 4.112206] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... server # [ 4.146384] systemd[1]: Stopped target Initrd Default Target. server # [ 4.151108] systemd[1]: Stopped target Basic System. server # [ 4.152697] systemd[1]: Stopped target Initrd Root Device. server # [ 4.154683] systemd[1]: Stopped target Path Units. server # [ 4.155957] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. server # [ 4.159809] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. server # [ 4.165671] systemd[1]: Stopped target Slice Units. server # [ 4.167866] systemd[1]: Stopped target Socket Units. server # [ 4.170594] systemd[1]: Stopped target System Initialization. server # [ 4.173002] systemd[1]: Stopped target Swaps. server # [ 4.177577] systemd[1]: Stopped target Timer Units. server # [ 4.178399] systemd[1]: dbus.socket: Deactivated successfully. server # [ 4.181397] systemd[1]: Closed D-Bus System Message Bus Socket. server # [ 4.184149] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. server # [ 4.187605] systemd[1]: Stopped Find NixOS closure. server # [ 4.189762] systemd[1]: Starting rw-sysroot-nix-store.service... server # [ 4.194323] systemd[1]: systemd-sysctl.service: Deactivated successfully. server # [ 4.195467] systemd[1]: Stopped Apply Kernel Variables. server # [ 4.200397] systemd[1]: systemd-modules-load.service: Deactivated successfully. server # [ 4.204324] systemd[1]: Stopped Load Kernel Modules. server # [ 4.207593] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. server # [ 4.211906] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. server # [ 4.213169] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. server # [ 4.214222] systemd[1]: Stopped Create System Files and Directories. server # [ 4.215131] systemd[1]: Stopped target Local File Systems. server # [ 4.223650] systemd[1]: Stopped target Preparation for Local File Systems. server # [ 4.224898] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. server # [ 4.225997] systemd[1]: Stopped Coldplug All udev Devices. server # [ 4.226799] systemd[1]: Stopping Rule-based Manager for Device Events and Files... server # [ 4.227863] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. server # [ 4.230020] systemd[1]: Stopped Virtual Console Setup. server # [ 4.230807] systemd[1]: initrd-cleanup.service: Deactivated successfully. server # [ 4.231853] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. server # [ 4.232936] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. server # [ 4.233964] systemd[1]: Finished rw-sysroot-nix-store.service. server # [ 4.234798] systemd[1]: systemd-udevd.service: Deactivated successfully. server # [ 4.235744] systemd[1]: Stopped Rule-based Manager for Device Events and Files. server # [ 4.244287] systemd[1]: systemd-udevd.service: Consumed 1.126s CPU time over 2.303s wall clock time, 17.9M memory peak. server # [ 4.245807] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. server # [ 4.248380] systemd[1]: Closed udev Control Socket. server # [ 4.252173] systemd[1]: Starting Cleanup udev Database... server # [ 4.252993] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. server # [ 4.254083] systemd[1]: Stopped Create Static Device Nodes in /dev. server # [ 4.256214] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. server # [ 4.260358] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. server # [ 4.261422] systemd[1]: kmod-static-nodes.service: Deactivated successfully. server # [ 4.264190] systemd[1]: Stopped Create List of Static Device Nodes. server # [ 4.281488] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. server # [ 4.284877] systemd[1]: Finished Cleanup udev Database. server # [ 4.285708] systemd[1]: Reached target Switch Root. server # [ 4.288159] systemd[1]: Starting NixOS Activation... server # [ 4.357072] initrd-nixos-activation-start[166]: booting system configuration /nix/store/k66cp5bym2y2x0gahgxdnb3hdjs131vh-nixos-system-server-test server # [ 4.385920] initrd-nixos-activation-start[166]: running activation script... builder # [ 4.386378] (udev-worker)[90]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory builder # [ 4.395382] (udev-worker)[90]: 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 directory builder # [ 4.422638] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. builder # [ 4.424490] systemd[1]: Stopped Virtual Console Setup. builder # [ 4.428179] systemd[1]: Stopping Virtual Console Setup... builder # [ 4.429074] systemd[1]: Starting Virtual Console Setup... builder # [ 4.444932] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. builder # [ 4.446458] systemd[1]: Stopped Virtual Console Setup. builder # [ 4.449700] systemd[1]: Starting Virtual Console Setup... builder # [ 4.476996] systemd-vconsole-setup[151]: Configuration of first virtual console was skipped, ignoring remaining ones. builder # [ 4.480332] systemd[1]: Finished Virtual Console Setup. server # [ 4.582824] initrd-nixos-activation-start[189]: setting up /etc... server # [ 4.690912] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. server # [ 4.693744] systemd[1]: Finished NixOS Activation. server # [ 4.694941] systemd[1]: Starting Switch Root... server # [ 4.710574] systemd[1]: Switching root. builder # [ 4.808918] systemd[1]: Mounting /sysroot/nix/store... builder # [ 4.854661] systemd[1]: Mounted /sysroot/nix/store. builder # [ 4.857894] systemd[1]: Reached target Initrd File Systems. builder # [ 4.860342] systemd[1]: Starting Find NixOS closure... builder # [ 4.865707] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... server # [ 4.896558] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). builder # [ 4.908145] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. builder # [ 4.909879] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. builder # [ 4.922765] systemd[1]: Finished Find NixOS closure. builder # [ 4.925490] systemd[1]: Reached target Initrd Default Target. builder # [ 4.927143] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... builder # [ 4.955234] systemd[1]: Stopped target Initrd Default Target. builder # [ 4.957188] systemd[1]: Stopped target Basic System. builder # [ 4.960427] systemd[1]: Stopped target Initrd Root Device. builder # [ 4.961523] systemd[1]: Stopped target Path Units. builder # [ 4.963304] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. builder # [ 4.966466] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. builder # [ 4.969779] systemd[1]: Stopped target Slice Units. builder # [ 4.972150] systemd[1]: Stopped target Socket Units. builder # [ 4.973821] systemd[1]: Stopped target System Initialization. builder # [ 4.979114] systemd[1]: Stopped target Swaps. builder # [ 4.980977] systemd[1]: Stopped target Timer Units. builder # [ 4.982786] systemd[1]: dbus.socket: Deactivated successfully. builder # [ 4.992277] systemd[1]: Closed D-Bus System Message Bus Socket. builder # [ 4.994102] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. builder # [ 4.998446] systemd[1]: Stopped Find NixOS closure. builder # [ 5.003119] systemd[1]: Starting rw-sysroot-nix-store.service... builder # [ 5.006552] systemd[1]: systemd-sysctl.service: Deactivated successfully. builder # [ 5.007565] systemd[1]: Stopped Apply Kernel Variables. builder # [ 5.010671] systemd[1]: systemd-modules-load.service: Deactivated successfully. builder # [ 5.015123] systemd[1]: Stopped Load Kernel Modules. builder # [ 5.015956] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. builder # [ 5.017287] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. builder # [ 5.020514] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. builder # [ 5.022348] systemd[1]: Stopped Create System Files and Directories. builder # [ 5.024182] systemd[1]: Stopped target Local File Systems. builder # [ 5.026800] systemd[1]: Stopped target Preparation for Local File Systems. builder # [ 5.027963] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. builder # [ 5.029224] systemd[1]: Stopped Coldplug All udev Devices. builder # [ 5.030032] systemd[1]: Stopping Rule-based Manager for Device Events and Files... builder # [ 5.031100] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. builder # [ 5.036343] systemd[1]: Stopped Virtual Console Setup. builder # [ 5.037164] systemd[1]: initrd-cleanup.service: Deactivated successfully. builder # [ 5.040119] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. builder # [ 5.041242] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. builder # [ 5.044345] systemd[1]: Finished rw-sysroot-nix-store.service. builder # [ 5.048123] systemd[1]: systemd-udevd.service: Deactivated successfully. builder # [ 5.049107] systemd[1]: Stopped Rule-based Manager for Device Events and Files. builder # [ 5.050139] systemd[1]: systemd-udevd.service: Consumed 1.434s CPU time over 3.074s wall clock time, 21.9M memory peak. builder # [ 5.056337] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. builder # [ 5.057414] systemd[1]: Closed udev Control Socket. builder # [ 5.058152] systemd[1]: Starting Cleanup udev Database... builder # [ 5.058980] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. builder # [ 5.064467] systemd[1]: Stopped Create Static Device Nodes in /dev. builder # [ 5.065381] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. builder # [ 5.066526] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. builder # [ 5.067612] systemd[1]: kmod-static-nodes.service: Deactivated successfully. builder # [ 5.072130] systemd[1]: Stopped Create List of Static Device Nodes. builder # [ 5.088383] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. builder # [ 5.091034] systemd[1]: Finished Cleanup udev Database. builder # [ 5.096280] systemd[1]: Reached target Switch Root. builder # [ 5.097091] systemd[1]: Starting NixOS Activation... builder # [ 5.167910] initrd-nixos-activation-start[174]: booting system configuration /nix/store/ps81lgv9798c7qwl9q77am5i39ab1lc1-nixos-system-builder-test builder # [ 5.198325] initrd-nixos-activation-start[174]: running activation script... server # [ 5.387552] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) server # [ 5.387611] systemd[1]: Detected virtualization qemu. server # [ 5.387666] systemd[1]: Detected architecture arm64. server # [ 5.387768] systemd[1]: Detected first boot. server # [ 5.389397] systemd[1]: Initializing machine ID from random generator. builder # [ 5.389170] initrd-nixos-activation-start[197]: setting up /etc... builder # [ 5.498087] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. builder # [ 5.500981] systemd[1]: Finished NixOS Activation. builder # [ 5.504188] systemd[1]: Starting Switch Root... builder # [ 5.519466] systemd[1]: Switching root. server # [ 5.665600] systemd[1]: bpf-restrict-fs: LSM BPF program attached builder # [ 5.697642] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). server # [ 5.800464] systemd[1]: Applying preset policy. server # [ 5.998199] systemd[1]: Populated /etc with preset unit settings. builder # [ 6.176583] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) builder # [ 6.176640] systemd[1]: Detected virtualization qemu. builder # [ 6.176691] systemd[1]: Detected architecture arm64. builder # [ 6.176784] systemd[1]: Detected first boot. builder # [ 6.178592] systemd[1]: Initializing machine ID from random generator. server # [ 6.211012] systemd[1]: initrd-switch-root.service: Deactivated successfully. server # [ 6.213405] systemd[1]: Stopped initrd-switch-root.service. server # [ 6.217497] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. server # [ 6.220588] systemd[1]: Created slice Slice /system/getty. server # [ 6.222102] systemd[1]: Created slice User and Session Slice. server # [ 6.222670] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. server # [ 6.226646] systemd[1]: Started Forward Password Requests to Wall Directory Watch. server # [ 6.227018] systemd[1]: Expecting device /dev/hvc0... server # [ 6.229026] systemd[1]: Expecting device /dev/ttyAMA0... server # [ 6.229682] systemd[1]: Reached target Local Encrypted Volumes. server # [ 6.230310] systemd[1]: Stopped target initrd-fs.target. server # [ 6.230995] systemd[1]: Stopped target initrd-root-fs.target. server # [ 6.234504] systemd[1]: Stopped target initrd-switch-root.target. server # [ 6.235113] systemd[1]: Reached target Virtual Machines and Containers. server # [ 6.237387] systemd[1]: Reached target Path Units. server # [ 6.237978] systemd[1]: Reached target Remote File Systems. server # [ 6.238555] systemd[1]: Reached target Slice Units. server # [ 6.241702] systemd[1]: Reached target Swaps. server # [ 6.243531] systemd[1]: Listening on Query the User Interactively for a Password. server # [ 6.247048] systemd[1]: Listening on Process Core Dump Socket. server # [ 6.249190] systemd[1]: Listening on Credential Encryption/Decryption. server # [ 6.250643] systemd[1]: Listening on Factory Reset Management. server # [ 6.253728] systemd[1]: Listening on Hostname Service Socket. server # [ 6.257447] systemd[1]: Starting Journal Log Access Socket... server # [ 6.258946] systemd[1]: Listening on Journal Audit Socket. server # [ 6.262416] systemd[1]: Listening on Console Output Muting Service Socket. server # [ 6.263104] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. server # [ 6.265402] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os server # [ 6.266649] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki server # [ 6.273544] systemd[1]: Listening on Disk Repartitioning Service Socket. server # [ 6.274072] systemd[1]: Listening on udev Control Socket. server # [ 6.274619] systemd[1]: Listening on udev Varlink Socket. server # [ 6.279765] systemd[1]: Mounting Huge Pages File System... server # [ 6.282449] systemd[1]: Mounting POSIX Message Queue File System... server # [ 6.297993] systemd[1]: Mounting Kernel Debug File System... server # [ 6.308487] systemd[1]: Mounting Kernel Trace File System... server # [ 6.323702] systemd[1]: Starting Create List of Static Device Nodes... server # [ 6.324076] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs server # [ 6.335988] systemd[1]: Mounting Kernel Configuration File System... server # [ 6.336393] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm server # [ 6.336665] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore server # [ 6.336935] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse server # [ 6.361075] systemd[1]: Mounting FUSE Control File System... server # [ 6.361509] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 server # [ 6.395076] systemd[1]: Starting Journal Service... server # [ 6.413490] systemd[1]: Starting Load Kernel Modules... server # [ 6.433606] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... server # [ 6.439262] systemd[1]: Starting Remount Root and Kernel File Systems... server # [ 6.439944] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os server # [ 6.446144] systemd[1]: Starting Coldplug All udev Devices... server # [ 6.450736] systemd[1]: Listening on Journal Log Access Socket. server # [ 6.457021] systemd[1]: Mounted Huge Pages File System. server # [ 6.457591] systemd[1]: Mounted POSIX Message Queue File System. builder # [ 6.459930] systemd[1]: bpf-restrict-fs: LSM BPF program attached server # [ 6.458286] systemd[1]: Mounted Kernel Debug File System. server # [ 6.458981] systemd[1]: Mounted Kernel Trace File System. server # [ 6.463208] systemd[1]: Mounted Kernel Configuration File System. server # [ 6.463843] systemd[1]: Mounted FUSE Control File System. server # [ 6.491509] systemd[1]: Finished Create List of Static Device Nodes. server # [ 6.500325] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... server # [ 6.556631] systemd-journald[260]: Collecting audit messages is enabled. server # [ 6.576642] EXT4-fs (vda): re-mounted 811665c3-75b4-4a57-b03f-3960e0c00077. server # [ 6.585330] systemd[1]: Finished Remount Root and Kernel File Systems. server # [ 6.586045] systemd[1]: Listening on Disk Image Download Service Socket. server # [ 6.586591] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore server # [ 6.596286] systemd[1]: Starting Load/Save OS Random Seed... server # [ 6.597362] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os server # [ 6.598954] systemd[1]: Finished Load Kernel Modules. server # [ 6.594047] systemd[1]: Queued start job for default target Multi-User System. builder # [ 6.612402] systemd[1]: Applying preset policy. server # [ 6.615667] systemd[1]: Starting Firewall... server # [ 6.611624] systemd[1]: systemd-journald.service: Deactivated successfully.[ 6.628486] systemd[1]: Starting Apply Kernel Variables... server # [ 6.629668] systemd[1]: Started Journal Service. server # server # [ 6.625368] systemd-modules-load[261]: Module 'atkbd' is built in server # [ 6.638287] systemd-modules-load[261]: Module 'loop' is built in server # [ 6.648934] systemd-modules-load[261]: Inserted module 'tls' server # [ 6.656482] systemd-modules-load[261]: Module 'tun' is built in server # [ 6.667128] systemd[1]: Starting Flush Journal to Persistent Storage... server # [ 6.718276] systemd[1]: Finished Load/Save OS Random Seed. server # [ 6.726979] systemd[1]: Reached target First Boot Complete. server # [ 6.736547] systemd-oomd[263]: No swap; memory pressure usage will be degraded server # [ 6.760409] systemd-journald[260]: Received client request to flush runtime journal. builder # [ 6.810277] systemd[1]: Populated /etc with preset unit settings. server # [ 6.813429] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. server # [ 6.814546] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. server # [ 6.822461] systemd[1]: Starting Create Static Device Nodes in /dev... server # [ 6.823528] systemd[1]: Finished Apply Kernel Variables. server # [ 6.824674] systemd[1]: Finished Flush Journal to Persistent Storage. server # [ 6.923203] systemd[1]: Finished Create Static Device Nodes in /dev. server # [ 6.928305] systemd[1]: Reached target Preparation for Local File Systems. server # [ 6.936815] systemd[1]: Starting Rule-based Manager for Device Events and Files... builder # [ 7.015896] systemd[1]: initrd-switch-root.service: Deactivated successfully. builder # [ 7.018511] systemd[1]: Stopped initrd-switch-root.service. builder # [ 7.022658] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. builder # [ 7.026098] systemd[1]: Created slice Slice /system/getty. builder # [ 7.027348] systemd[1]: Created slice User and Session Slice. builder # [ 7.027725] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. builder # [ 7.028071] systemd[1]: Started Forward Password Requests to Wall Directory Watch. builder # [ 7.028335] systemd[1]: Expecting device /dev/hvc0... builder # [ 7.033511] systemd[1]: Expecting device /dev/ttyAMA0... builder # [ 7.033922] systemd[1]: Reached target Local Encrypted Volumes. builder # [ 7.034551] systemd[1]: Stopped target initrd-fs.target. builder # [ 7.035144] systemd[1]: Stopped target initrd-root-fs.target. builder # [ 7.035729] systemd[1]: Stopped target initrd-switch-root.target. builder # [ 7.036358] systemd[1]: Reached target Virtual Machines and Containers. builder # [ 7.042127] systemd[1]: Reached target Path Units. builder # [ 7.042514] systemd[1]: Reached target Remote File Systems. builder # [ 7.043105] systemd[1]: Reached target Slice Units. builder # [ 7.043720] systemd[1]: Reached target Swaps. builder # [ 7.048655] systemd[1]: Listening on Query the User Interactively for a Password. builder # [ 7.050514] systemd[1]: Listening on Process Core Dump Socket. builder # [ 7.051978] systemd[1]: Listening on Credential Encryption/Decryption. builder # [ 7.056565] systemd[1]: Listening on Factory Reset Management. builder # [ 7.057055] systemd[1]: Listening on Hostname Service Socket. builder # [ 7.060219] systemd[1]: Starting Journal Log Access Socket... builder # [ 7.063945] systemd[1]: Listening on Journal Audit Socket. builder # [ 7.067129] systemd[1]: Listening on Console Output Muting Service Socket. builder # [ 7.067849] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. builder # [ 7.068319] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os builder # [ 7.072096] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki builder # [ 7.078848] systemd[1]: Listening on Disk Repartitioning Service Socket. builder # [ 7.079379] systemd[1]: Listening on udev Control Socket. builder # [ 7.080030] systemd[1]: Listening on udev Varlink Socket. builder # [ 7.085518] systemd[1]: Mounting Huge Pages File System... builder # [ 7.092595] systemd[1]: Mounting POSIX Message Queue File System... builder # [ 7.100589] systemd[1]: Mounting Kernel Debug File System... builder # [ 7.108656] systemd[1]: Mounting Kernel Trace File System... builder # [ 7.120713] systemd[1]: Starting Create List of Static Device Nodes... builder # [ 7.121050] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs builder # [ 7.138302] systemd[1]: Mounting Kernel Configuration File System... builder # [ 7.141503] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm builder # [ 7.144421] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore builder # [ 7.149652] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse builder # [ 7.161486] systemd[1]: Mounting FUSE Control File System... builder # [ 7.161853] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 server # [ 7.161906] systemd-udevd[302]: Using default interface naming scheme 'v261'. builder # [ 7.204977] systemd[1]: Starting Journal Service... server # [ 7.200000] systemd[1]: Mounting /run/wrappers... builder # [ 7.227199] systemd[1]: Starting Load Kernel Modules... builder # [ 7.241328] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... builder # [ 7.246111] systemd[1]: Starting Remount Root and Kernel File Systems... builder # [ 7.246846] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os builder # [ 7.257128] systemd[1]: Starting Coldplug All udev Devices... builder # [ 7.263717] systemd[1]: Listening on Journal Log Access Socket. builder # [ 7.266042] systemd[1]: Mounted Huge Pages File System. builder # [ 7.267278] systemd[1]: Mounted POSIX Message Queue File System. server # [ 7.257023] systemd[1]: Mounted /run/wrappers. builder # [ 7.270222] systemd[1]: Mounted Kernel Debug File System. builder # [ 7.271338] systemd[1]: Mounted Kernel Trace File System. builder # [ 7.272395] systemd[1]: Mounted Kernel Configuration File System. builder # [ 7.276781] systemd[1]: Mounted FUSE Control File System. server # [ 7.264430] systemd[1]: Reached target Local File Systems. server # [ 7.276414] systemd[1]: Listening on Boot Loader Control Service Socket. builder # [ 7.294898] systemd[1]: Finished Create List of Static Device Nodes. builder # [ 7.300237] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... server # [ 7.293726] systemd[1]: Starting register-nix-paths.service... server # [ 7.301329] systemd[1]: Starting Create SUID/SGID Wrappers... server # [ 7.306324] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. server # [ 7.312648] systemd[1]: Starting Save Transient machine-id to Disk... server # [ 7.319479] systemd[1]: Starting Create System Files and Directories... builder # [ 7.375583] systemd[1]: Finished Load Kernel Modules. builder # [ 7.379516] systemd-journald[267]: Collecting audit messages is enabled. builder # [ 7.383872] systemd[1]: Starting Firewall... builder # [ 7.393115] EXT4-fs (vda): re-mounted d6d8dda6-7ed0-469e-977e-297a9d80bfeb. builder # [ 7.395615] systemd[1]: Starting Apply Kernel Variables... builder # [ 7.408684] systemd[1]: Finished Remount Root and Kernel File Systems. builder # [ 7.409376] systemd[1]: Listening on Disk Image Download Service Socket. builder # [ 7.409831] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore builder # [ 7.424585] systemd[1]: Starting Load/Save OS Random Seed... builder # [ 7.425604] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os server # [ 7.415579] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. builder # [ 7.415775] systemd[1]: Queued start job for default target Multi-User System. builder # [ 7.431568] systemd[1]: Started Journal Service. builder # [ 7.421088] systemd[1]: systemd-journald.service: Deactivated successfully. server # [ 7.423111] systemd[1]: Started Rule-based Manager for Device Events and Files. server # [ 7.436419] systemd[1]: Finished Save Transient machine-id to Disk. builder # [ 7.436475] systemd-modules-load[268]: Module 'atkbd' is built in builder # [ 7.450190] systemd-modules-load[268]: Module 'loop' is built in builder # [ 7.460968] systemd-modules-load[268]: Module 'tun' is built in builder # [ 7.469349] systemd[1]: Starting Flush Journal to Persistent Storage... server # [ 7.542226] systemd[1]: Finished Create System Files and Directories. builder # [ 7.550336] systemd-oomd[270]: No swap; memory pressure usage will be degraded server # [ 7.551300] systemd[1]: Starting Rebuild Journal Catalog... server # [ 7.568904] systemd[1]: Starting Record System Boot/Shutdown in UTMP... builder # [ 7.569865] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. builder # [ 7.594828] systemd-journald[267]: Received client request to flush runtime journal. builder # [ 7.640793] systemd[1]: Starting Create Static Device Nodes in /dev... builder # [ 7.641968] systemd[1]: Finished Load/Save OS Random Seed. builder # [ 7.648296] systemd[1]: Reached target First Boot Complete. builder # [ 7.649209] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. builder # [ 7.650164] systemd[1]: Finished Apply Kernel Variables. builder # [ 7.650965] systemd[1]: Finished Flush Journal to Persistent Storage. server # [ 7.701479] systemd[1]: Finished Record System Boot/Shutdown in UTMP. builder # [ 7.731723] systemd[1]: Finished Create Static Device Nodes in /dev. builder # [ 7.733677] systemd[1]: Reached target Preparation for Local File Systems. builder # [ 7.738037] systemd[1]: Starting Rule-based Manager for Device Events and Files... server # [ 7.778652] systemd[1]: Finished Rebuild Journal Catalog. server # [ 7.782551] systemd[1]: Starting Update is Completed... builder # [ 7.858346] systemd-udevd[305]: Using default interface naming scheme 'v261'. server # [ 7.914965] systemd[1]: Finished Update is Completed. builder # [ 7.973438] systemd[1]: Started Rule-based Manager for Device Events and Files. builder # [ 8.011180] systemd[1]: Mounting /run/wrappers... builder # [ 8.065414] systemd[1]: Mounted /run/wrappers. builder # [ 8.066244] systemd[1]: Reached target Local File Systems. builder # [ 8.070159] systemd[1]: Listening on Boot Loader Control Service Socket. builder # [ 8.079470] systemd[1]: Starting register-nix-paths.service... builder # [ 8.083340] systemd[1]: Starting Create SUID/SGID Wrappers... builder # [ 8.094783] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. builder # [ 8.102925] systemd[1]: Starting Save Transient machine-id to Disk... builder # [ 8.110112] systemd[1]: Starting Create System Files and Directories... builder # [ 8.211979] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. builder # [ 8.221875] systemd[1]: Finished Save Transient machine-id to Disk. server # [ 8.318476] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. server # [ 8.325439] systemd[1]: Finished Create SUID/SGID Wrappers. builder # [ 8.326974] systemd[1]: Finished Create System Files and Directories. builder # [ 8.332445] systemd[1]: Starting Rebuild Journal Catalog... builder # [ 8.346110] systemd[1]: Starting Record System Boot/Shutdown in UTMP... builder # [ 8.453972] systemd[1]: Finished Record System Boot/Shutdown in UTMP. server # [ 8.506198] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 server # [ 8.499115] systemd[1]: Finished register-nix-paths.service. builder # [ 8.514660] systemd[1]: Finished Rebuild Journal Catalog. builder # [ 8.518848] systemd[1]: Starting Update is Completed... server # [ 8.578987] systemd[1]: Finished Firewall. builder # [ 8.620672] systemd[1]: Finished Update is Completed. server # [ 8.844595] systemd[1]: Finished Coldplug All udev Devices. server # [ 8.845787] systemd[1]: Reached target System Initialization. server # [ 8.846707] systemd[1]: Started Discard unused filesystem blocks once a week. server # [ 8.852353] systemd[1]: Started niks3 garbage collection timer. server # [ 8.853300] systemd[1]: Started Daily Cleanup of Temporary Directories. server # [ 8.856703] systemd[1]: Reached target Timer Units. server # [ 8.858970] systemd[1]: Listening on D-Bus System Message Bus Socket. server # [ 8.864545] systemd[1]: Starting niks3 server proxy socket... server # [ 8.865466] systemd[1]: Listening on niks3 server socket. server # [ 8.897534] systemd[1]: Listening on Nix Daemon Socket. server # [ 8.898474] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. server # [ 8.899733] systemd[1]: Listening on niks3 server proxy socket. server # [ 8.909361] systemd[1]: Reached target Socket Units. server # [ 8.912146] systemd[1]: Starting D-Bus System Message Bus... builder # [ 8.930700] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. builder # [ 8.933341] systemd[1]: Finished Create SUID/SGID Wrappers. server # [ 8.981062] dbus-broker-launch[488]: Looking up NSS user entry for 'systemd-timesync'... server # [ 8.984382] dbus-broker-launch[488]: NSS returned no entry for 'systemd-timesync' server # [ 8.986587] dbus-broker-launch[488]: Invalid user-name in /nix/store/kz4xz6652a26fgram8mjcavl37zj2ng4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" server # [ 8.998515] systemd[1]: Started D-Bus System Message Bus. server # [ 9.004640] systemd[1]: Reached target Basic System. server # [ 9.018373] systemd[1]: Starting Import lastlog data into lastlog2 database... server # [ 9.030880] systemd[1]: Starting Generate test mTLS certs... server # [ 9.036238] systemd[1]: Starting Name Service Cache Daemon (nsncd)... server # [ 9.038543] systemd[1]: Starting Post-Boot Actions... server # [ 9.039568] systemd[1]: Started Reset console on configuration changes. server # [ 9.057266] systemd[1]: Starting resolvconf update... builder # [ 9.074847] systemd[1]: Finished Firewall. builder # [ 9.105355] systemd[1]: Finished register-nix-paths.service. server # [ 9.143958] dbus-broker-launch[488]: Ready server # [ 9.151783] systemd[1]: Finished Post-Boot Actions. server # [ 9.196836] systemd[1]: Started Name Service Cache Daemon (nsncd). server # [ 9.198178] nsncd[492]: Oct 01 10:33:38.769 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" server # [ 9.217225] systemd[1]: Reached target Host and Network Name Lookups. server # [ 9.223692] systemd[1]: Reached target User and Group Name Lookups. server # [ 9.234476] systemd[1]: Starting User Login Management... server # [ 9.239939] systemd[1]: Finished Import lastlog data into lastlog2 database. server # [ 9.266885] niks3-test-certs-start[502]: ----- server # [ 9.300361] niks3-test-certs-start[526]: ----- builder # [ 9.398185] systemd[1]: Finished Coldplug All udev Devices. builder # [ 9.405312] systemd[1]: Reached target System Initialization. builder # [ 9.406279] systemd[1]: Started Discard unused filesystem blocks once a week. builder # [ 9.407322] systemd[1]: Started Daily Cleanup of Temporary Directories. server # [ 9.410038] systemd-logind[505]: New seat seat0. builder # [ 9.412314] systemd[1]: Reached target Timer Units. builder # [ 9.413107] systemd[1]: Listening on D-Bus System Message Bus Socket. builder # [ 9.414059] systemd[1]: Starting niks3 auto-upload socket... server # [ 9.419893] systemd[1]: Started User Login Management. server # [ 9.427508] systemd[1]: Stopped target Host and Network Name Lookups. builder # [ 9.429894] systemd[1]: Listening on Nix Daemon Socket. builder # [ 9.432277] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. builder # [ 9.436926] systemd[1]: Listening on niks3 auto-upload socket. server # [ 9.438822] niks3-test-certs-start[537]: Certificate request self-signature ok builder # [ 9.441178] systemd[1]: Reached target Socket Units. builder # [ 9.442536] systemd[1]: Starting D-Bus System Message Bus... builder # [ 9.449529] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs server # [ 9.440006] niks3-test-certs-start[537]: subject=CN=server server # [ 9.458498] systemd[1]: Stopping Host and Network Name Lookups... server # [ 9.464874] systemd[1]: Stopped target User and Group Name Lookups. server # [ 9.470393] systemd[1]: Stopping User and Group Name Lookups... server # [ 9.473922] systemd[1]: Starting linger-users.service... builder # [ 9.478100] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse server # [ 9.483317] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... server # [ 9.489427] systemd[1]: nscd.service: Deactivated successfully. server # [ 9.495974] systemd[1]: Stopped Name Service Cache Daemon (nsncd). server # [ 9.501595] systemd[1]: Starting Name Service Cache Daemon (nsncd)... builder # [ 9.503678] dbus-broker-launch[481]: Looking up NSS user entry for 'systemd-timesync'... builder # [ 9.508310] dbus-broker-launch[481]: NSS returned no entry for 'systemd-timesync' server # [ 9.511926] niks3-test-certs-start[567]: ----- builder # [ 9.511491] dbus-broker-launch[481]: Invalid user-name in /nix/store/py4078kyw8b4r0flwgcxav1zmw5s5yi8-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" builder # [ 9.522275] systemd[1]: Started D-Bus System Message Bus. builder # [ 9.530051] systemd[1]: Reached target Basic System. server # [ 9.539891] systemd[1]: linger-users.service: Deactivated successfully. builder # [ 9.540398] systemd[1]: Starting Import lastlog data into lastlog2 database... server # [ 9.545608] systemd[1]: Finished linger-users.service. builder # [ 9.557197] systemd[1]: Starting Name Service Cache Daemon (nsncd)... server # [ 9.560291] systemd[1]: Started Name Service Cache Daemon (nsncd). builder # [ 9.561730] systemd[1]: Starting Post-Boot Actions... server # [ 9.564501] nsncd[561]: Oct 01 10:33:39.135 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" builder # [ 9.569280] systemd[1]: Started Reset console on configuration changes. builder # [ 9.573826] systemd[1]: Starting resolvconf update... server # [ 9.576780] systemd[1]: Reached target Host and Network Name Lookups. server # [ 9.579673] systemd[1]: Reached target User and Group Name Lookups. server # [ 9.603233] systemd[1]: Finished resolvconf update. server # [ 9.605433] systemd[1]: Reached target Preparation for Network. server # [ 9.612357] systemd[1]: Starting DHCP Client... server # [ 9.617712] systemd[1]: Starting Extra networking commands.... builder # [ 9.631258] dbus-broker-launch[481]: Ready server # [ 9.639345] niks3-test-certs-start[574]: Certificate request self-signature ok server # [ 9.644825] niks3-test-certs-start[574]: subject=CN=niks3 test client builder # [ 9.690930] systemd[1]: Finished Post-Boot Actions. server # [ 9.691562] systemd[1]: Finished Generate test mTLS certs. builder # [ 9.716960] systemd[1]: Started Name Service Cache Daemon (nsncd). builder # [ 9.729046] nsncd[484]: Oct 01 10:33:39.278 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" builder # [ 9.739103] systemd[1]: Reached target Host and Network Name Lookups. builder # [ 9.743556] systemd[1]: Reached target User and Group Name Lookups. builder # [ 9.749151] systemd[1]: Starting User Login Management... builder # [ 9.764385] systemd[1]: Finished Import lastlog data into lastlog2 database. builder # [ 9.835770] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. server # [ 9.868486] dhcpcd[599]: dhcpcd-10.3.2 starting server # [ 9.879385] dhcpcd[646]: dev: loaded udev builder # [ 9.882541] systemd-logind[495]: New seat seat0. server # [ 9.884349] dhcpcd[646]: no valid interfaces found builder # [ 9.885926] systemd[1]: Started User Login Management. server # [ 9.886448] dhcpcd[646]: no valid interfaces found builder # [ 9.893024] systemd[1]: Starting linger-users.service... server # [ 9.901263] systemd[1]: Finished Extra networking commands.. server # [ 9.911608] systemd[1]: Reached target Network. builder # [ 9.912360] systemd[1]: Stopped target Host and Network Name Lookups. builder # [ 9.914340] systemd[1]: Stopping Host and Network Name Lookups... builder # [ 9.918637] systemd[1]: Stopped target User and Group Name Lookups. server # [ 9.920190] systemd[1]: Started Mock OIDC server for testing. server # [ 9.924231] systemd[1]: Starting Nginx Web Server... builder # [ 9.924184] systemd[1]: Stopping User and Group Name Lookups... server # [ 9.928392] systemd[1]: Starting PostgreSQL Server... builder # [ 9.932410] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... builder # [ 9.934711] systemd[1]: nscd.service: Deactivated successfully. server # [ 9.940508] systemd[1]: Started RustFS S3-compatible object storage. builder # [ 9.941408] systemd[1]: Stopped Name Service Cache Daemon (nsncd). builder # [ 9.948625] systemd[1]: Starting Name Service Cache Daemon (nsncd)... server # [ 9.951445] systemd[1]: Starting Setup RustFS bucket... builder # [ 9.957066] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. server # [ 9.958265] systemd[1]: Starting Permit User Sessions... builder # [ 9.968492] systemd[1]: Started backdoor.service. builder # [ 10.009417] systemd[1]: linger-users.service: Deactivated successfully. builder # [ 10.014750] systemd[1]: Finished linger-users.service. server # [ 10.022679] systemd[1]: Starting Virtual Console Setup... builder # [ 10.027911] systemd[1]: Started Name Service Cache Daemon (nsncd). builder # [ 10.032583] systemd[1]: Reached target Host and Network Name Lookups. builder # [ 10.037266] nsncd[550]: Oct 01 10:33:39.590 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" builder # [ 10.046522] systemd[1]: Reached target User and Group Name Lookups. builder # connecting to host... server # [ 10.075549] systemd[1]: Finished Permit User Sessions. builder # [ 10.082279] systemd[1]: Finished resolvconf update. server # [ 10.084654] systemd[1]: Started Getty on tty1. builder # [ 10.090958] systemd[1]: Reached target Preparation for Network. builder # [ 10.091969] systemd[1]: Starting DHCP Client... server # [ 10.096510] systemd[1]: Reached target Login Prompts. builder # [ 10.104389] systemd[1]: Starting Extra networking commands.... server # [ 10.107385] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. server # [ 10.114141] systemd[1]: Stopped Virtual Console Setup. server # [ 10.127491] systemd[1]: Starting Virtual Console Setup... builder # [ 10.216731] (udev-worker)[338]: Network interface NamePolicy= disabled on kernel command line. builder # [ 10.236739] (udev-worker)[336]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. builder # [ 10.254222] (udev-worker)[336]: Network interface NamePolicy= disabled on kernel command line. builder # [ 10.319796] dhcpcd[589]: dhcpcd-10.3.2 starting builder # [ 10.328687] dhcpcd[629]: dev: loaded udev builder # [ 10.373302] 8021q: 802.1Q VLAN Support v1.8 builder # [ 10.436522] systemd[1]: Finished Extra networking commands.. builder # [ 10.437457] systemd[1]: Reached target Network. builder # [ 10.452907] systemd[1]: Starting Permit User Sessions... builder # [ 10.480323] cfg80211: Loading compiled-in X.509 certificates for regulatory database builder # [ 10.512769] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' builder # [ 10.513255] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' builder # [ 10.514814] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 builder # [ 10.515132] cfg80211: failed to load regulatory.db builder # [ 10.515591] systemd[1]: Finished Permit User Sessions. builder # [ 10.522691] systemd[1]: Started Getty on tty1. builder # [ 10.523570] systemd[1]: Reached target Login Prompts. builder # [ 10.591190] 8021q: adding VLAN 0 to HW filter on device eth0 builder # [ 10.578386] dhcpcd[629]: eth0: waiting for carrier builder # [ 10.579803] dhcpcd[629]: libudev: received NULL device builder # [ 10.582750] dhcpcd[629]: libudev: received NULL device builder # [ 10.583684] dhcpcd[629]: eth0: carrier acquired server # [ 10.591178] nginx-pre-start[680]: nginx: the configuration file /nix/store/yp5jb2wj0rv7jdsw17jpq148jhgkfqdb-nginx.conf syntax is ok builder # [ 10.591798] dhcpcd[629]: DUID 00:01:00:01:32:50:f3:84:52:54:00:12:34:56 builder # [ 10.596695] dhcpcd[629]: eth0: IAID 00:12:34:56 builder # [ 10.597403] dhcpcd[629]: eth0: adding address fe80::5054:ff:fe12:3456 builder # [ 10.598296] dhcpcd[629]: eth0: IAID 00:12:34:56 builder # [ 10.606174] systemd[1]: Condition check resulted in Virtio network device being skipped. builder # [ 10.607408] systemd[1]: Starting Address configuration of eth1... server # [ 10.603467] nginx-pre-start[680]: nginx: configuration file /nix/store/yp5jb2wj0rv7jdsw17jpq148jhgkfqdb-nginx.conf test is successful server # [ 10.620701] systemd[1]: Started Nginx Web Server. server # [ 10.654555] postgresql-pre-start[683]: The files belonging to this database system will be owned by user "postgres". server # [ 10.666462] postgresql-pre-start[683]: This user must also own the server process. builder # [ 10.683697] 8021q: adding VLAN 0 to HW filter on device eth1 server # [ 10.674387] postgresql-pre-start[683]: The database cluster will be initialized with locale "en_US.UTF-8". builder # [ 10.681380] network-addresses-eth1-start[659]: adding address 192.168.1.1/24... done server # [ 10.688380] postgresql-pre-start[683]: The default database encoding has accordingly been set to "UTF8". builder # [ 10.691947] network-addresses-eth1-start[659]: adding address 2001:db8:1::1/64... done server # [ 10.694484] postgresql-pre-start[683]: The default text search configuration will be set to "english". server # [ 10.701263] postgresql-pre-start[683]: Data page checksums are enabled. server # [ 10.706820] postgresql-pre-start[683]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok builder # [ 10.707782] systemd[1]: Finished Address configuration of eth1. builder # [ 10.710496] systemd-logind[495]: Watching system buttons on /dev/input/event0 (gpio-keys) server # [ 10.717306] postgresql-pre-start[683]: creating subdirectories ... ok server # [ 10.723234] postgresql-pre-start[683]: selecting dynamic shared memory implementation ... posix server # [ 10.731781] systemd-vconsole-setup[668]: Configuration of first virtual console was skipped, ignoring remaining ones. server # [ 10.741490] systemd[1]: Finished Virtual Console Setup. server # [ 10.751107] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs builder # [ 10.792539] mousedev: PS/2 mouse device common for all mice builder # [ 10.856118] systemd-logind[495]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) server # [ 10.945645] postgresql-pre-start[683]: selecting default "max_connections" ... 100 server # [ 11.004194] mock-oidc-server[652]: Mock OIDC Server running server # [ 11.006251] mock-oidc-server[652]: OIDC Address: 127.0.0.1:8080 server # [ 11.012346] mock-oidc-server[652]: Issue Address: 127.0.0.1:8081 server # [ 11.014775] mock-oidc-server[652]: Issuer: http://127.0.0.1:8080/oidc server # [ 11.017269] mock-oidc-server[652]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json server # [ 11.021825] mock-oidc-server[652]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration server # [ 11.027885] mock-oidc-server[652]: Issue tokens: http://127.0.0.1:8081/issue?sub=... server # [ 11.157503] postgresql-pre-start[683]: selecting default "shared_buffers" ... 128MB server # [ 11.204200] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. server # [ 11.306615] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. server # [ 11.316220] systemd[1]: Started backdoor.service. builder # [ 11.338736] dhcpcd[629]: eth0: soliciting a DHCP lease builder # [ 11.344825] dhcpcd[629]: eth0: offered 10.0.2.15 from 10.0.2.2 builder # [ 11.352740] dhcpcd[629]: eth0: probing address 10.0.2.15/24 server # [ 11.439641] 8021q: 802.1Q VLAN Support v1.8 server # connecting to host... server # [ 11.494796] (udev-worker)[687]: Network interface NamePolicy= disabled on kernel command line. server # [ 11.532880] cfg80211: Loading compiled-in X.509 certificates for regulatory database server: Guest shell says: b'Spawning backdoor root shell...\n' server # [ 11.559936] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' server # [ 11.560399] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' server # [ 11.561408] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 server # [ 11.561717] cfg80211: failed to load regulatory.db server: connected to guest root shell server: (connecting took 11.92 seconds) server: (finished: waiting for the VM to finish booting, in 11.92 seconds) builder # [ 11.588791] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 server # [ 11.606135] (udev-worker)[690]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. server # [ 11.617229] (udev-worker)[690]: Network interface NamePolicy= disabled on kernel command line. server # [ 11.825502] systemd-logind[505]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) builder # [ 11.861236] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. builder # [ 11.869173] systemd[1]: Starting Virtual Console Setup... builder # [ 11.893928] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. builder # [ 11.895075] systemd[1]: Stopped Virtual Console Setup. builder # [ 11.897867] systemd[1]: Starting Virtual Console Setup... server # [ 11.953841] 8021q: adding VLAN 0 to HW filter on device eth0 server # [ 11.942423] dhcpcd[646]: eth0: waiting for carrier server # [ 11.947582] dhcpcd[646]: eth0: carrier acquired server # [ 11.961286] systemd[1]: Condition check resulted in Virtio network device being skipped. server # [ 11.967703] dhcpcd[646]: DUID 00:01:00:01:32:50:f3:85:52:54:00:12:34:56 server # [ 11.975527] systemd[1]: Starting Address configuration of eth1... server # [ 11.977212] dhcpcd[646]: eth0: IAID 00:12:34:56 builder # [ 11.978524] systemd-logind[495]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) server # [ 11.979831] dhcpcd[646]: eth0: adding address fe80::5054:ff:fe12:3456 builder # [ 12.033538] systemd-vconsole-setup[686]: Configuration of first virtual console was skipped, ignoring remaining ones. builder # [ 12.037826] systemd[1]: Finished Virtual Console Setup. builder # [ 12.045569] dhcpcd[629]: eth0: soliciting an IPv6 router builder # [ 12.046979] dhcpcd[629]: eth0: Router Advertisement from fe80::2 builder # [ 12.047951] dhcpcd[629]: eth0: adding address fec0::5054:ff:fe12:3456/64 builder # [ 12.049070] dhcpcd[629]: eth0: adding route to fec0::/64 builder # [ 12.049825] dhcpcd[629]: eth0: adding default route via fe80::2 server # [ 12.069345] 8021q: adding VLAN 0 to HW filter on device eth1 server # [ 12.071953] network-addresses-eth1-start[749]: adding address 192.168.1.2/24... done server # [ 12.087508] network-addresses-eth1-start[749]: adding address 2001:db8:1::2/64... done server # [ 12.106627] systemd[1]: Finished Address configuration of eth1. server # [ 12.156420] systemd-logind[505]: Watching system buttons on /dev/input/event0 (gpio-keys) server # [ 12.379171] mousedev: PS/2 mouse device common for all mice server # [ 12.412495] systemd-logind[505]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) server # [ 12.667038] dhcpcd[646]: eth0: soliciting a DHCP lease server # [ 12.674998] dhcpcd[646]: eth0: offered 10.0.2.15 from 10.0.2.2 server # [ 12.680192] dhcpcd[646]: eth0: probing address 10.0.2.15/24 server # [ 12.751488] postgresql-pre-start[683]: selecting default time zone ... UTC server # [ 12.757508] postgresql-pre-start[683]: creating configuration files ... ok server # [ 13.230446] postgresql-pre-start[683]: running bootstrap script ... ok server # [ 14.299933] postgresql-pre-start[683]: performing post-bootstrap initialization ... ok server # [ 14.397739] dhcpcd[646]: eth0: soliciting an IPv6 router server # [ 14.399085] dhcpcd[646]: eth0: Router Advertisement from fe80::2 server # [ 14.401157] dhcpcd[646]: eth0: adding address fec0::5054:ff:fe12:3456/64 server # [ 14.404383] dhcpcd[646]: eth0: adding route to fec0::/64 server # [ 14.405300] dhcpcd[646]: eth0: adding default route via fe80::2 server # [ 14.485117] postgresql-pre-start[683]: syncing data to disk ... ok server # [ 14.486050] postgresql-pre-start[683]: initdb: warning: enabling "trust" authentication for local connections server # [ 14.487340] postgresql-pre-start[683]: 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. server # [ 14.497733] postgresql-pre-start[683]: Success. You can now start the database server using: server # [ 14.501265] postgresql-pre-start[683]: pg_ctl -D /var/lib/postgresql/18 -l logfile start server # [ 14.665962] postgres[787]: [787] LOG: starting PostgreSQL 18.6 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit server # [ 14.673099] postgres[787]: [787] LOG: listening on IPv6 address "::1", port 5432 server # [ 14.674201] postgres[787]: [787] LOG: listening on IPv4 address "127.0.0.1", port 5432 server # [ 14.677793] postgres[787]: [787] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" server # [ 14.689843] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. server # [ 14.690936] systemd[1]: Stopped Virtual Console Setup. server # [ 14.691690] systemd[1]: Stopping Virtual Console Setup... server # [ 14.703765] systemd[1]: Starting Virtual Console Setup... server # [ 14.709710] postgres[800]: [800] LOG: database system was shut down at 2026-10-01 10:33:43 GMT server # [ 14.719167] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. server # [ 14.724180] systemd[1]: Stopped Virtual Console Setup. server # [ 14.726173] postgres[787]: [787] LOG: database system is ready to accept connections server # [ 14.736929] systemd[1]: Started PostgreSQL Server. server # [ 14.737682] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. server # [ 14.755157] systemd[1]: Starting PostgreSQL Setup Scripts... server # [ 14.756101] systemd[1]: Starting Virtual Console Setup... server: (finished: waiting for unit postgresql.service, in 15.29 seconds) server: waiting for unit rustfs.service server # [ 14.985670] systemd-vconsole-setup[805]: Configuration of first virtual console was skipped, ignoring remaining ones. server # [ 14.995960] systemd[1]: Finished Virtual Console Setup. server: (finished: waiting for unit rustfs.service, in 0.07 seconds) server: waiting for unit rustfs-setup.service server # [ 15.107579] postgresql-setup-start[815]: CREATE DATABASE server # [ 15.163026] postgresql-setup-start[831]: CREATE ROLE server # [ 15.178587] postgresql-setup-start[833]: ALTER DATABASE server # [ 15.184880] systemd[1]: Finished PostgreSQL Setup Scripts. server # [ 15.186701] systemd[1]: Reached target PostgreSQL. builder # [ 16.088571] dhcpcd[629]: eth0: leased 10.0.2.15 for 86400 seconds builder # [ 16.088756] dhcpcd[629]: eth0: adding route to 10.0.2.0/24 builder # [ 16.088854] dhcpcd[629]: eth0: adding default route via 10.0.2.2 builder # [ 16.194137] systemd[1]: Started DHCP Client. builder # [ 16.196143] systemd[1]: Reached target Multi-User System. builder # [ 16.197022] systemd[1]: Startup finished in 1.013s (kernel) + 4.743s (initrd) + 10.438s (userspace) = 16.195s. server # [ 17.317139] dhcpcd[646]: eth0: leased 10.0.2.15 for 86400 seconds server # [ 17.317326] dhcpcd[646]: eth0: adding route to 10.0.2.0/24 server # [ 17.317394] dhcpcd[646]: eth0: adding default route via 10.0.2.2 server # [ 17.420792] systemd[1]: Started DHCP Client. server # [ 27.171650] rustfs-setup-start[943]: mb s3://niks3-test server # [ 27.184907] systemd[1]: Finished Setup RustFS bucket. server # [ 27.192597] systemd[1]: Starting niks3 server... server # [ 27.359781] niks3-server[953]: 2026/10/01 10:33:56 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:8080/oidc server # [ 27.367090] niks3-server[953]: 2026/10/01 10:33:56 INFO OIDC authentication enabled config=/nix/store/z019gvf710g6d8396v9cvsyidwg4d4yk-niks3-oidc.json server # [ 27.369903] niks3-server[953]: 2026/10/01 10:33:56 INFO Loaded signing key name=niks3-test-1 path=/nix/store/6jgpl7z0sjpvlynzpcik37wwz06qi4np-niks3-signing-key server # [ 27.389115] niks3-server[953]: 2026/10/01 10:33:56 INFO Using socket-activated listener address=0.0.0.0:5751 server # [ 27.390515] niks3-server[953]: 2026/10/01 10:33:56 INFO Using socket-activated listener address=/run/niks3/proxy.sock server # [ 27.396782] niks3-server[953]: 2026/10/01 10:33:56 INFO systemd watchdog enabled interval=15s server # [ 27.398129] niks3-server[953]: 2026/10/01 10:33:56 INFO Starting HTTP server address=0.0.0.0:5751 server # [ 27.399849] niks3-server[953]: 2026/10/01 10:33:56 INFO Starting HTTP server address=/run/niks3/proxy.sock server # [ 27.401427] systemd[1]: Started niks3 server. server # [ 27.402911] systemd[1]: Reached target Multi-User System. server # [ 27.403742] systemd[1]: Startup finished in 1.000s (kernel) + 3.962s (initrd) + 22.430s (userspace) = 27.394s. server: (finished: waiting for unit rustfs-setup.service, in 12.53 seconds) server: waiting for unit mock-oidc.service server: (finished: waiting for unit mock-oidc.service, in 0.03 seconds) server: waiting for unit niks3.service server: (finished: waiting for unit niks3.service, in 0.03 seconds) server: waiting for TCP port 5751 on localhost server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded! server: (finished: waiting for TCP port 5751 on localhost, in 0.03 seconds) server: waiting for TCP port 8080 on localhost server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded! server: (finished: waiting for TCP port 8080 on localhost, in 0.02 seconds) server: waiting for TCP port 9000 on localhost server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded! server: (finished: waiting for TCP port 9000 on localhost, in 0.02 seconds) server: must succeed: mkdir -p /tmp/test-config server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.01 seconds) server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.01 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 server # [ 27.825789] systemd[1]: Created slice Slice /system/nix-daemon. server # [ 27.829946] systemd[1]: Started Nix Daemon instance (PID 1002/UID 0). server # [ 27.884186] nix-daemon[1004]: remote pid 1002 is unknown user (trusted) server # [ 27.901213] niks3-server[953]: 2026/10/01 10:33:57 INFO Received push request method=POST path=/api/pushes server # [ 27.902626] systemd[1]: nix-daemon@0-1-1002_1003-0.service: Deactivated successfully. server # time=2026-10-01T10:33:57.497Z level=INFO msg="Uploading 5 paths to server (0 already cached)" server # time=2026-10-01T10:33:57.499Z level=INFO msg="Uploading kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 (287.5KB)" server # time=2026-10-01T10:33:57.501Z level=INFO msg="Uploading m54cs0m994hc3n9lax7sg98sgy729qsa-glibc-2.42-84 (44.4MB)" server # time=2026-10-01T10:33:57.502Z level=INFO msg="Uploading 0s40b0an0cz4vypbji92cb7qbr65icl4-libidn2-2.3.8 (366.1KB)" server # time=2026-10-01T10:33:57.505Z level=INFO msg="Uploading h0hd048jzxs7fx9a6g75jwrrhbg50bp8-libunistring-1.4.2 (2.0MB)" server # time=2026-10-01T10:33:57.508Z level=INFO msg="Uploading waax852balprvsvdziyaf1h7r3ldvakg-xgcc-15.3.0-libgcc (150.1KB)" server # [ 28.199774] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=kwhxkl8yn5y8wqiq11jsybagw3fbc4iv.ls server # [ 28.218946] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=nar/16z5dldwcmmhv0ij0pz8h0z98mv285jvqw0ly64fsg0s9lbffamb.nar.zst server # [ 28.241244] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=nar/0rbj231dr19n59qcahf76cdwmqlqv239j85j94g8c89kf9kf5p04.nar.zst server # [ 28.263436] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=h0hd048jzxs7fx9a6g75jwrrhbg50bp8.ls server # [ 28.273544] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=nar/07lydviym1nzmxy9y72apc8lgzy9v6x110r4vxac3kk2xslg0gcg.nar.zst server # [ 28.310552] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=nar/0x48iv4crw96wayixvwmbhz9znvm9djawdf9j0wszdqdxpn92kw1.nar.zst server # [ 28.315564] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=0s40b0an0cz4vypbji92cb7qbr65icl4.ls server # [ 28.320118] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=waax852balprvsvdziyaf1h7r3ldvakg.ls server # [ 28.927844] niks3-server[953]: 2026/10/01 10:33:58 INFO Registered completed upload object_key=m54cs0m994hc3n9lax7sg98sgy729qsa.ls server # [ 28.957536] niks3-server[953]: 2026/10/01 10:33:58 INFO Received complete multipart upload request method=POST path=/api/multipart/complete server # [ 28.972583] niks3-server[953]: 2026/10/01 10:33:58 INFO Completed multipart upload object_key=nar/1vzbqdhwmbhwll0g4bniam5qjj71a1ci2n6lc100n2yfpbpygw8c.nar.zst upload_id=M2MxZDliNzctZDE0ZS00NzBkLTliMzItODY2MDAwMzYzYmMyLjIwZWRkZmE2LTU5NWItNDgxNC1iNTAxLTRmMTQwNGExOTlkNHgxNzkwODUwODM3NDkxMjk0NDQw parts=1 server # time=2026-10-01T10:33:58.556Z level=INFO msg="Uploading 5 narinfos" server # [ 28.981220] niks3-server[953]: 2026/10/01 10:33:58 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign server # [ 28.986074] niks3-server[953]: 2026/10/01 10:33:58 INFO Signed narinfos id=1 count=5 server # [ 29.009207] niks3-server[953]: 2026/10/01 10:33:58 INFO Received complete push request method=POST path=/api/pushes/1/complete server # [ 29.015362] niks3-server[953]: 2026/10/01 10:33:58 INFO Registered completed upload object_key=m54cs0m994hc3n9lax7sg98sgy729qsa.narinfo server # [ 29.026164] niks3-server[953]: 2026/10/01 10:33:58 INFO Registered completed upload object_key=waax852balprvsvdziyaf1h7r3ldvakg.narinfo server # [ 29.030482] niks3-server[953]: 2026/10/01 10:33:58 INFO Registered completed upload object_key=h0hd048jzxs7fx9a6g75jwrrhbg50bp8.narinfo server # [ 29.034707] niks3-server[953]: 2026/10/01 10:33:58 INFO Registered completed upload object_key=0s40b0an0cz4vypbji92cb7qbr65icl4.narinfo server # time=2026-10-01T10:33:58.615Z level=INFO msg="Upload complete. (1.258s)" server # [ 29.044062] niks3-server[953]: 2026/10/01 10:33:58 INFO Registered completed upload object_key=kwhxkl8yn5y8wqiq11jsybagw3fbc4iv.narinfo server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 1.35 seconds) server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.01 seconds) server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 server # [ 29.122531] niks3-server[953]: 2026/10/01 10:33:58 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] server # [ 29.165902] systemd[1]: Started Nix Daemon instance (PID 1034/UID 0). server # [ 29.220366] nix-daemon[1036]: remote pid 1034 is unknown user (trusted) server # [ 29.234096] systemd[1]: nix-daemon@1-2-1034_1035-0.service: Deactivated successfully. server # [ 29.240404] niks3-server[953]: 2026/10/01 10:33:58 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] server # time=2026-10-01T10:33:58.820Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n" server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.18 seconds) server: waiting for unit nginx.service server: (finished: waiting for unit nginx.service, in 0.03 seconds) server: waiting for TCP port 443 on localhost server # Connection to localhost (::1) 443 port [tcp/https] succeeded! server: (finished: waiting for TCP port 443 on localhost, in 0.02 seconds) server: must succeed: /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 server # time=2026-10-01T10:33:58.925Z 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.pem server # time=2026-10-01T10:33:58.939Z level=INFO msg="All 1 paths already cached" server: (finished: must succeed: /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.07 seconds) server: 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 server: (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) server: must fail: /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 server # time=2026-10-01T10:33:58.994Z 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)" server: (finished: must fail: /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.02 seconds) server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 server # time=2026-10-01T10:33:59.052Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem server # time=2026-10-01T10:33:59.060Z level=INFO msg="All 1 paths already cached" server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.07 seconds) server: must succeed: cd /etc/niks3-test-certs && /nix/store/ai0jh6nymysikgd4liv2k8iv9kmipcva-openssl-3.6.4-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client' server # ----- server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/ai0jh6nymysikgd4liv2k8iv9kmipcva-openssl-3.6.4-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client', in 0.02 seconds) server: must succeed: cd /etc/niks3-test-certs && /nix/store/ai0jh6nymysikgd4liv2k8iv9kmipcva-openssl-3.6.4-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem server # Certificate request self-signature ok server # subject=CN=other client server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/ai0jh6nymysikgd4liv2k8iv9kmipcva-openssl-3.6.4-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem, in 0.04 seconds) server: must fail: /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 server # time=2026-10-01T10:33:59.181Z 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.pem server # [ 29.613088] niks3-server[953]: 2026/10/01 10:33:59 WARN mTLS auth: subject not in bound subjects subject="CN=other client" server # [ 29.656401] systemd[1]: Started Nix Daemon instance (PID 1101/UID 0). server # [ 29.705516] nix-daemon[1103]: remote pid 1101 is unknown user (trusted) server # [ 29.720836] systemd[1]: nix-daemon@2-3-1101_1102-0.service: Deactivated successfully. server # [ 29.728523] niks3-server[953]: 2026/10/01 10:33:59 WARN mTLS auth: subject not in bound subjects subject="CN=other client" server # time=2026-10-01T10:33:59.307Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n" server: (finished: must fail: /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.18 seconds) server: must succeed: mkdir -p /tmp/test-store server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.02 seconds) server: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 server: (finished: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 , in 0.68 seconds) server: must succeed: cat > /tmp/test-drv.nix << 'EOF' derivation { name = "test-build-log"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ]; } EOF server: (finished: must succeed: cat > /tmp/test-drv.nix << 'EOF' derivation { name = "test-build-log"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ]; } EOF , in 0.02 seconds) server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring server # [ 30.501703] systemd[1]: Started Nix Daemon instance (PID 1145/UID 0). server # [ 30.552230] nix-daemon[1149]: remote pid 1145 is unknown user (trusted) server # this derivation will be built: server # /nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv server # building '/nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv'... server # test-build-log> test build log output server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.20 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log server # [ 30.650015] systemd[1]: nix-daemon@3-4-1145_1146-0.service: Deactivated successfully. server # [ 30.746087] systemd[1]: Started Nix Daemon instance (PID 1175/UID 0). server # [ 30.799746] nix-daemon[1177]: remote pid 1175 is unknown user (trusted) server # [ 30.812567] systemd[1]: nix-daemon@4-5-1175_1176-0.service: Deactivated successfully. server # [ 30.819346] niks3-server[953]: 2026/10/01 10:34:00 INFO Received push request method=POST path=/api/pushes server # time=2026-10-01T10:34:00.401Z level=INFO msg="Uploading 1 paths to server (0 already cached)" server # time=2026-10-01T10:34:00.402Z level=INFO msg="Uploading z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log (128B)" server # [ 30.846219] niks3-server[953]: 2026/10/01 10:34:00 INFO Registered completed upload object_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.ls server # time=2026-10-01T10:34:00.430Z level=INFO msg="Uploading 1 narinfos" server # [ 30.855896] niks3-server[953]: 2026/10/01 10:34:00 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign server # [ 30.860909] niks3-server[953]: 2026/10/01 10:34:00 INFO Signed narinfos id=2 count=1 server # [ 30.863624] niks3-server[953]: 2026/10/01 10:34:00 INFO Registered completed upload object_key=log/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv server # [ 30.868686] niks3-server[953]: 2026/10/01 10:34:00 INFO Registered completed upload object_key=nar/00zns3gj9hwz2a4b0i07y7nmxybq59lh24bl3xsxblcl6333mjil.nar.zst server # [ 30.875067] niks3-server[953]: 2026/10/01 10:34:00 INFO Received complete push request method=POST path=/api/pushes/2/complete server # time=2026-10-01T10:34:00.458Z level=INFO msg="Upload complete. (178ms)" server # [ 30.882808] niks3-server[953]: 2026/10/01 10:34:00 INFO Registered completed upload object_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.narinfo server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log, in 0.24 seconds) server: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log server: (finished: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log , in 0.16 seconds) subtest: push --stdin streams paths and reports each one server: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }' server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring server # [ 31.105042] systemd[1]: Started Nix Daemon instance (PID 1193/UID 0). server # [ 31.157224] nix-daemon[1197]: remote pid 1193 is unknown user (trusted) server # this derivation will be built: server # /nix/store/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv server # building '/nix/store/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv'... server: (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.20 seconds) server: 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/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --stdin server # [ 31.253589] systemd[1]: nix-daemon@5-6-1193_1194-0.service: Deactivated successfully. server # [ 31.352934] systemd[1]: Started Nix Daemon instance (PID 1225/UID 0). server # [ 31.404379] nix-daemon[1227]: remote pid 1225 is unknown user (trusted) server # [ 31.417384] systemd[1]: nix-daemon@6-7-1225_1226-0.service: Deactivated successfully. server # [ 31.425016] niks3-server[953]: 2026/10/01 10:34:01 INFO Received push request method=POST path=/api/pushes server # time=2026-10-01T10:34:01.004Z level=INFO msg="Uploading 1 paths to server (0 already cached)" server # time=2026-10-01T10:34:01.006Z level=INFO msg="Uploading 7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test (120B)" server # [ 31.454758] niks3-server[953]: 2026/10/01 10:34:01 INFO Received sign narinfos request method=POST path=/api/pushes/3/sign server # time=2026-10-01T10:34:01.036Z level=INFO msg="Uploading 1 narinfos" server # [ 31.463616] niks3-server[953]: 2026/10/01 10:34:01 INFO Signed narinfos id=3 count=1 server # [ 31.469272] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=7j8147s8h4si9jmv6bxa86ql7z6j2m37.ls server # [ 31.475366] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=nar/1hj1r0g515rfxjx685mfn3dgr6akqbn4gf64snlv6bxaxph69lna.nar.zst server # [ 31.481359] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=log/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv server # [ 31.489352] niks3-server[953]: 2026/10/01 10:34:01 INFO Received complete push request method=POST path=/api/pushes/3/complete server # time=2026-10-01T10:34:01.071Z level=INFO msg="Upload complete. (185ms)" server # [ 31.497025] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=7j8147s8h4si9jmv6bxa86ql7z6j2m37.narinfo server: (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/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --stdin, in 0.26 seconds) server: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test server: (finished: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test , in 0.21 seconds) (finished: subtest: push --stdin streams paths and reports each one, in 0.67 seconds) server: must succeed: readlink /etc/niks3-test/symlink-wrapper server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.02 seconds) server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.01 seconds) server: must succeed: test -L /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper server: (finished: must succeed: test -L /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper, in 0.01 seconds) server: must succeed: readlink /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper server: (finished: must succeed: readlink /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper, in 0.01 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper server # [ 31.869499] systemd[1]: Started Nix Daemon instance (PID 1272/UID 0). server # [ 31.918937] nix-daemon[1274]: remote pid 1272 is unknown user (trusted) server # [ 31.932872] systemd[1]: nix-daemon@7-8-1272_1273-0.service: Deactivated successfully. server # [ 31.938762] niks3-server[953]: 2026/10/01 10:34:01 INFO Received push request method=POST path=/api/pushes server # time=2026-10-01T10:34:01.519Z level=INFO msg="Uploading 2 paths to server (0 already cached)" server # time=2026-10-01T10:34:01.520Z level=INFO msg="Uploading 0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper (192B)" server # time=2026-10-01T10:34:01.522Z level=INFO msg="Uploading xafv64bljflg1v8hnf22lyk0w4gma3v5-base-package (536B)" server # [ 31.966396] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=0caxmbk1mdnhh6zbyh0sz4faqqy5f08r.ls server # [ 31.995024] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=xafv64bljflg1v8hnf22lyk0w4gma3v5.ls server # time=2026-10-01T10:34:01.587Z level=INFO msg="Uploading 2 narinfos" server # [ 32.017598] niks3-server[953]: 2026/10/01 10:34:01 INFO Received sign narinfos request method=POST path=/api/pushes/4/sign server # [ 32.019192] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=nar/1ncc9lqll9lyam30m8vdy4yyaym0q7sps47afadhzvyqsw833fy8.nar.zst server # [ 32.028792] niks3-server[953]: 2026/10/01 10:34:01 INFO Signed narinfos id=4 count=2 server # [ 32.032926] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=nar/0820av37plmfgrxw6pcbym6phk1sv14dilyrablrnkzb7z1fd5js.nar.zst server # [ 32.039324] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=xafv64bljflg1v8hnf22lyk0w4gma3v5.narinfo server # [ 32.044523] niks3-server[953]: 2026/10/01 10:34:01 INFO Received complete push request method=POST path=/api/pushes/4/complete server # time=2026-10-01T10:34:01.624Z level=INFO msg="Upload complete. (220ms)" server # [ 32.051750] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=0caxmbk1mdnhh6zbyh0sz4faqqy5f08r.narinfo server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper, in 0.28 seconds) server: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper server: (finished: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper , in 0.18 seconds) server: must succeed: cat > /tmp/oidc-test.nix << 'EOF' derivation { name = "oidc-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'OIDC test derivation' > $out" ]; } EOF server: (finished: must succeed: cat > /tmp/oidc-test.nix << 'EOF' derivation { name = "oidc-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'OIDC test derivation' > $out" ]; } EOF , in 0.02 seconds) server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring server # [ 32.309389] systemd[1]: Started Nix Daemon instance (PID 1301/UID 0). server # [ 32.361753] nix-daemon[1305]: remote pid 1301 is unknown user (trusted) server # this derivation will be built: server # /nix/store/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv server # building '/nix/store/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv'... server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link, in 0.20 seconds) server: 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' server # [ 32.458414] systemd[1]: nix-daemon@8-9-1301_1302-0.service: Deactivated successfully. server: (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) server: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Ik5uTVFBRGpHUFMxbkprZ1p0VVVPR3Jqd1QxZHZhUFcxVUtuazlFX3RIY1UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTA4NTQ0NDIsImlhdCI6MTc5MDg1MDg0MiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.IFCz-6is6z-YjlLmDa9k9laKifLssYGZN97JDxiSdAsXbUrUI95e1jpX0zATwKHLHV4ijmUNjROXeMEfTK-SrpvHkiONYNttWtWRPepTX7YFjCHBRvMSQBEHW7dbPQysjUqv1Yf1W5nxkkuyzRjPbKVOnq4K9lJ_oMOKJ46b8oFlIh7KC7FohaQ3QDwTGQSiwDT_0yX5xslkCG61rI4KMgCfQzMGf9sT1gfF77FVNsDCDI0o-avdvUSfYjR5sAtpLxsuBCYBjednaaQXLGZyUwupOsTg5q4GGAOzbI_GxuKMbcd-LIBaxNaObCPgwI5wypRKPCuhJjP0W2MT3wX_ag' /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test server # time=2026-10-01T10:34:02.081Z 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" server # [ 32.588941] systemd[1]: Started Nix Daemon instance (PID 1334/UID 0). server # [ 32.641952] nix-daemon[1336]: remote pid 1334 is unknown user (trusted) server # [ 32.655904] systemd[1]: nix-daemon@9-10-1334_1335-0.service: Deactivated successfully. server # [ 32.662801] niks3-server[953]: 2026/10/01 10:34:02 INFO Received push request method=POST path=/api/pushes server # time=2026-10-01T10:34:02.243Z level=INFO msg="Uploading 1 paths to server (0 already cached)" server # time=2026-10-01T10:34:02.245Z level=INFO msg="Uploading x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test (136B)" server # [ 32.691217] niks3-server[953]: 2026/10/01 10:34:02 INFO Registered completed upload object_key=x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj.ls server # time=2026-10-01T10:34:02.274Z level=INFO msg="Uploading 1 narinfos" server # [ 32.700214] niks3-server[953]: 2026/10/01 10:34:02 INFO Received sign narinfos request method=POST path=/api/pushes/5/sign server # [ 32.701709] niks3-server[953]: 2026/10/01 10:34:02 INFO Signed narinfos id=5 count=1 server # [ 32.707192] niks3-server[953]: 2026/10/01 10:34:02 INFO Registered completed upload object_key=nar/17h0qgwf5334wia961ibqy9hmpsyvfhrvj5ji9m031klp6sx4v0q.nar.zst server # [ 32.716638] niks3-server[953]: 2026/10/01 10:34:02 INFO Registered completed upload object_key=log/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv server # [ 32.718343] niks3-server[953]: 2026/10/01 10:34:02 INFO Received complete push request method=POST path=/api/pushes/5/complete server # time=2026-10-01T10:34:02.300Z level=INFO msg="Upload complete. (179ms)" server # [ 32.729085] niks3-server[953]: 2026/10/01 10:34:02 INFO Registered completed upload object_key=x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj.narinfo server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Ik5uTVFBRGpHUFMxbkprZ1p0VVVPR3Jqd1QxZHZhUFcxVUtuazlFX3RIY1UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTA4NTQ0NDIsImlhdCI6MTc5MDg1MDg0MiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.IFCz-6is6z-YjlLmDa9k9laKifLssYGZN97JDxiSdAsXbUrUI95e1jpX0zATwKHLHV4ijmUNjROXeMEfTK-SrpvHkiONYNttWtWRPepTX7YFjCHBRvMSQBEHW7dbPQysjUqv1Yf1W5nxkkuyzRjPbKVOnq4K9lJ_oMOKJ46b8oFlIh7KC7FohaQ3QDwTGQSiwDT_0yX5xslkCG61rI4KMgCfQzMGf9sT1gfF77FVNsDCDI0o-avdvUSfYjR5sAtpLxsuBCYBjednaaQXLGZyUwupOsTg5q4GGAOzbI_GxuKMbcd-LIBaxNaObCPgwI5wypRKPCuhJjP0W2MT3wX_ag' /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test, in 0.24 seconds) server: must succeed: cat > /tmp/oidc-test2.nix << 'EOF' derivation { name = "oidc-test2"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'OIDC test 2' > $out" ]; } EOF server: (finished: must succeed: cat > /tmp/oidc-test2.nix << 'EOF' derivation { name = "oidc-test2"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'OIDC test 2' > $out" ]; } EOF , in 0.02 seconds) server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring server # [ 32.805306] systemd[1]: Started Nix Daemon instance (PID 1347/UID 0). server # [ 32.852827] nix-daemon[1351]: remote pid 1347 is unknown user (trusted) server # this derivation will be built: server # /nix/store/ldlfjwl576hkm4b5ag3b447nnn59cwiw-oidc-test2.drv server # building '/nix/store/ldlfjwl576hkm4b5ag3b447nnn59cwiw-oidc-test2.drv'... server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link, in 0.20 seconds) server: 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' server # [ 32.951540] systemd[1]: nix-daemon@10-11-1347_1348-0.service: Deactivated successfully. server: (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) server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Ik5uTVFBRGpHUFMxbkprZ1p0VVVPR3Jqd1QxZHZhUFcxVUtuazlFX3RIY1UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTA4NTQ0NDIsImlhdCI6MTc5MDg1MDg0MiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.SrPJawZli2BxJ5avzfSmqYUFCRtM6yR3mxzXUDDI2IYsX7NK9N4sboPJOId6R0nCPQi5DQtSE91fIxnJSpGW9PmRQRiczU9KKW6865IgS0o2an2uuK05Yh39R6SPRVj4LjGzyjc7oQ6Ser9PuEtn2bvYW394oratUcWfzglzZQWIsYNkz_aZaUyZIUkfquugLpq9BQaxl9E9UqyrY6xl2wNHWrwsqnY-GC_sz8civP8M75DDVaUwjOB2NQmogGejpM3tWB7aUeo9DMg5Q_qt340-rbQWi1txkSOizHp5jE9xtGXpgIgZC-yEH6gzIwW6t44pdv44xPzO3bMLEoKd8w' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2 server # time=2026-10-01T10:34:02.574Z 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" server # [ 33.040272] niks3-server[953]: 2026/10/01 10:34:02 WARN Authentication failed token_preview=eyJhbGciOi...3bMLEoKd8w token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test] server # [ 33.082804] systemd[1]: Started Nix Daemon instance (PID 1380/UID 0). server # [ 33.138039] nix-daemon[1382]: remote pid 1380 is unknown user (trusted) server # [ 33.151863] systemd[1]: nix-daemon@11-12-1380_1381-0.service: Deactivated successfully. server # [ 33.157880] niks3-server[953]: 2026/10/01 10:34:02 WARN Authentication failed token_preview=eyJhbGciOi...3bMLEoKd8w token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test] server # time=2026-10-01T10:34:02.738Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n" server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Ik5uTVFBRGpHUFMxbkprZ1p0VVVPR3Jqd1QxZHZhUFcxVUtuazlFX3RIY1UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTA4NTQ0NDIsImlhdCI6MTc5MDg1MDg0MiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.SrPJawZli2BxJ5avzfSmqYUFCRtM6yR3mxzXUDDI2IYsX7NK9N4sboPJOId6R0nCPQi5DQtSE91fIxnJSpGW9PmRQRiczU9KKW6865IgS0o2an2uuK05Yh39R6SPRVj4LjGzyjc7oQ6Ser9PuEtn2bvYW394oratUcWfzglzZQWIsYNkz_aZaUyZIUkfquugLpq9BQaxl9E9UqyrY6xl2wNHWrwsqnY-GC_sz8civP8M75DDVaUwjOB2NQmogGejpM3tWB7aUeo9DMg5Q_qt340-rbQWi1txkSOizHp5jE9xtGXpgIgZC-yEH6gzIwW6t44pdv44xPzO3bMLEoKd8w' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.18 seconds) server: 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' server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://wrong:5751&repository_owner=myorg', in 0.03 seconds) server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Ik5uTVFBRGpHUFMxbkprZ1p0VVVPR3Jqd1QxZHZhUFcxVUtuazlFX3RIY1UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MDg1NDQ0MiwiaWF0IjoxNzkwODUwODQyLCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.a3Rz8z-L216Dllb_aLZO4ThJfj6vD1NlOHCFqN8PJjOq_bqHr_HGPu-ysZ5Jc0-ujLurOooZUVDMRnmrY2kBgE2V8LtiKYr1pWaJvjs6HMJhI-LzXFA-oON9N1kx0jBqN44IgoNogXk6Rt7Q0pxMQMopeoSrgYR2C5NW-AkEeRL39LLBa3-YbBJLqI6HbXjKpAzmsGB9Fpgsi2aqBVCl-q8QJrN1JEe-HbwUQIoG69pnuWDwVECeIXBfFDcudLCxN3QlxyBrluHpOGD7QptDBL3RMBjV5nsmWy0uwKJZ_C3H2nWzerJvRT6tlOHctJXIiRRrm_WEGxsBf82I2SAaig' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2 server # time=2026-10-01T10:34:02.784Z 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" server # [ 33.251159] niks3-server[953]: 2026/10/01 10:34:02 WARN Authentication failed token_preview=eyJhbGciOi...f82I2SAaig token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test] server # [ 33.293386] systemd[1]: Started Nix Daemon instance (PID 1402/UID 0). server # [ 33.339796] nix-daemon[1404]: remote pid 1402 is unknown user (trusted) server # [ 33.353286] systemd[1]: nix-daemon@12-13-1402_1403-0.service: Deactivated successfully. server # [ 33.359988] niks3-server[953]: 2026/10/01 10:34:02 WARN Authentication failed token_preview=eyJhbGciOi...f82I2SAaig token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test] server # time=2026-10-01T10:34:02.939Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n" server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Ik5uTVFBRGpHUFMxbkprZ1p0VVVPR3Jqd1QxZHZhUFcxVUtuazlFX3RIY1UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MDg1NDQ0MiwiaWF0IjoxNzkwODUwODQyLCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.a3Rz8z-L216Dllb_aLZO4ThJfj6vD1NlOHCFqN8PJjOq_bqHr_HGPu-ysZ5Jc0-ujLurOooZUVDMRnmrY2kBgE2V8LtiKYr1pWaJvjs6HMJhI-LzXFA-oON9N1kx0jBqN44IgoNogXk6Rt7Q0pxMQMopeoSrgYR2C5NW-AkEeRL39LLBa3-YbBJLqI6HbXjKpAzmsGB9Fpgsi2aqBVCl-q8QJrN1JEe-HbwUQIoG69pnuWDwVECeIXBfFDcudLCxN3QlxyBrluHpOGD7QptDBL3RMBjV5nsmWy0uwKJZ_C3H2nWzerJvRT6tlOHctJXIiRRrm_WEGxsBf82I2SAaig' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.17 seconds) server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2 server # time=2026-10-01T10:34:02.959Z 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" server # [ 33.433277] niks3-server[953]: 2026/10/01 10:34:03 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] server # [ 33.481712] systemd[1]: Started Nix Daemon instance (PID 1421/UID 0). server # [ 33.534945] nix-daemon[1423]: remote pid 1421 is unknown user (trusted) server # [ 33.549135] systemd[1]: nix-daemon@13-14-1421_1422-0.service: Deactivated successfully. server # [ 33.556197] niks3-server[953]: 2026/10/01 10:34:03 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] server # time=2026-10-01T10:34:03.136Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n" server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.20 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins create hello-pin /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 server # [ 33.623638] niks3-server[953]: 2026/10/01 10:34:03 INFO Received create pin request method=POST path=/api/pins/hello-pin server # time=2026-10-01T10:34:03.208Z level=INFO msg="Created pin" name=hello-pin store_path=/nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 server # [ 33.633745] niks3-server[953]: 2026/10/01 10:34:03 INFO Created/updated pin name=hello-pin store_path=/nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 narinfo_key=kwhxkl8yn5y8wqiq11jsybagw3fbc4iv.narinfo server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins create hello-pin /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 , in 0.07 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list server # [ 33.721735] niks3-server[953]: 2026/10/01 10:34:03 INFO Received list pins request method=GET path=/api/pins server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list , in 0.09 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --names-only server # [ 33.780928] niks3-server[953]: 2026/10/01 10:34:03 INFO Received list pins request method=GET path=/api/pins server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --names-only , in 0.06 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --json server # [ 33.837292] niks3-server[953]: 2026/10/01 10:34:03 INFO Received list pins request method=GET path=/api/pins server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --json , in 0.06 seconds) server: must succeed: export S3_ENDPOINT_URL=http://localhost:9000 export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin /nix/store/1mxif175wb0rn50pgaisvczshqv3mw1i-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin server: (finished: must succeed: export S3_ENDPOINT_URL=http://localhost:9000 export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin /nix/store/1mxif175wb0rn50pgaisvczshqv3mw1i-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin , in 0.03 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --pin ca-pin /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log server # time=2026-10-01T10:34:03.501Z level=INFO msg="All 1 paths already cached" server # [ 33.927082] niks3-server[953]: 2026/10/01 10:34:03 INFO Received create pin request method=POST path=/api/pins/ca-pin server # time=2026-10-01T10:34:03.519Z level=INFO msg="Created pin" name=ca-pin store_path=/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log server # [ 33.944393] niks3-server[953]: 2026/10/01 10:34:03 INFO Created/updated pin name=ca-pin store_path=/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log narinfo_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.narinfo server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --pin ca-pin /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log , in 0.08 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --names-only server # [ 34.006947] niks3-server[953]: 2026/10/01 10:34:03 INFO Received list pins request method=GET path=/api/pins server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --names-only , in 0.06 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins delete hello-pin server # [ 34.065353] niks3-server[953]: 2026/10/01 10:34:03 INFO Received delete pin request method=DELETE path=/api/pins/hello-pin server # time=2026-10-01T10:34:03.650Z level=INFO msg="Deleted pin" name=hello-pin server # [ 34.074456] niks3-server[953]: 2026/10/01 10:34:03 INFO Deleted pin name=hello-pin server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins delete hello-pin , in 0.07 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --names-only server # [ 34.134898] niks3-server[953]: 2026/10/01 10:34:03 INFO Received list pins request method=GET path=/api/pins server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --names-only , in 0.06 seconds) server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent server # [ 34.192105] niks3-server[953]: 2026/10/01 10:34:03 INFO Received create pin request method=POST path=/api/pins/bad-pin server # time=2026-10-01T10:34:03.770Z level=ERROR msg="Fatal error" error="creating pin: server returned 400: invalid store path: invalid store path format: /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent\n" server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent , in 0.06 seconds) server: must succeed: systemctl start niks3-gc.service server # [ 34.223816] systemd[1]: Starting niks3 garbage collection... server # [ 34.272811] niks3[1543]: time=2026-10-01T10:34:03.849Z level=INFO msg="Starting garbage collection" older-than=720h failed-uploads-older-than=6h force=false server # [ 34.277055] niks3-server[953]: 2026/10/01 10:34:03 INFO Starting cleanup of old closures method=DELETE path=/api/closures server # [ 34.281269] niks3[1543]: time=2026-10-01T10:34:03.856Z level=INFO msg="Garbage collection started" server # [ 34.283561] niks3-server[953]: 2026/10/01 10:34:03 INFO Aborted multipart uploads count=0 kept=0 server # [ 34.288846] niks3-server[953]: 2026/10/01 10:34:03 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=0 server # [ 34.294700] niks3-server[953]: 2026/10/01 10:34:03 INFO Vacuumed table table=pending_closures server # [ 34.298297] niks3-server[953]: 2026/10/01 10:34:03 INFO Vacuumed table table=pending_objects server # [ 34.301813] niks3-server[953]: 2026/10/01 10:34:03 INFO Vacuumed table table=multipart_uploads server # [ 34.304732] niks3-server[953]: 2026/10/01 10:34:03 INFO Vacuumed table table=closures server # [ 34.307722] niks3-server[953]: 2026/10/01 10:34:03 INFO Vacuumed table table=objects server # [ 36.281212] niks3[1543]: time=2026-10-01T10:34:05.857Z level=INFO msg="Garbage collection completed successfully" failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=0 server # [ 36.291136] systemd[1]: niks3-gc.service: Deactivated successfully. server # [ 36.295694] systemd[1]: Finished niks3 garbage collection. server # [ 36.298916] systemd[1]: niks3-gc.service: Consumed 39ms CPU time over 2.069s wall clock time, 2.5M memory peak, 1.2K incoming IP traffic, 823B outgoing IP traffic. server: (finished: must succeed: systemctl start niks3-gc.service, in 2.11 seconds) builder: waiting for unit niks3-auto-upload.socket builder: waiting for the VM to finish booting builder: Guest shell says: b'Spawning backdoor root shell...\n' builder: connected to guest root shell builder: (connecting took 0.00 seconds) builder: (finished: waiting for the VM to finish booting, in 0.00 seconds) builder: (finished: waiting for unit niks3-auto-upload.socket, in 0.04 seconds) builder: must succeed: test -S /run/niks3/upload-to-cache.sock builder: (finished: must succeed: test -S /run/niks3/upload-to-cache.sock, in 0.02 seconds) builder: must succeed: grep post-build-hook /etc/nix/nix.conf builder: (finished: must succeed: grep post-build-hook /etc/nix/nix.conf, in 0.02 seconds) builder: must succeed: cat > /tmp/test-drv.nix << 'EOF' derivation { name = "post-build-hook-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'hello from post-build-hook test' > $out" ]; } EOF builder: (finished: must succeed: cat > /tmp/test-drv.nix << 'EOF' derivation { name = "post-build-hook-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'hello from post-build-hook test' > $out" ]; } EOF , in 0.02 seconds) builder: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link builder # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring builder # [ 36.469665] systemd[1]: Created slice Slice /system/nix-daemon. builder # [ 36.473699] systemd[1]: Started Nix Daemon instance (PID 769/UID 0). builder # [ 36.525782] nix-daemon[773]: remote pid 769 is unknown user (trusted) builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 585 ms (attempt 1/5) builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 931 ms (attempt 2/5) builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 1973 ms (attempt 3/5) builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 3886 ms (attempt 4/5) builder # warning: Failed to setup the substituter at URI 'https://cache.nixos.org/': error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6) builder # this derivation will be built: builder # /nix/store/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv builder # building '/nix/store/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv'... builder # [ 44.079853] systemd[1]: Started niks3 auto-upload daemon. builder # [ 44.188997] niks3-hook[798]: time=2026-10-01T10:34:13.748Z 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=0s builder # [ 44.196289] niks3-hook[798]: time=2026-10-01T10:34:13.755Z level=INFO msg="Upload queue status" pending=1 builder # [ 44.199738] niks3-hook[798]: time=2026-10-01T10:34:13.755Z level=INFO msg="Uploading batch" count=1 builder: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link, in 7.81 seconds) builder: waiting for unit niks3-auto-upload.service builder # [ 44.226607] systemd[1]: nix-daemon@0-1-769_770-0.service: Deactivated successfully. builder # [ 44.231830] systemd[1]: nix-daemon@0-1-769_770-0.service: Consumed 173ms CPU time over 7.753s wall clock time, 19.6M memory peak, 1.4K outgoing IP traffic. builder: (finished: waiting for unit niks3-auto-upload.service, in 0.08 seconds) ??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead. File "/nix/store/iiab4a2pmymr5lc22a6yc0d0fyf3g00n-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 builder: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive ??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead. File "/nix/store/iiab4a2pmymr5lc22a6yc0d0fyf3g00n-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 builder # [ 44.311562] systemd[1]: Started Nix Daemon instance (PID 809/UID 0). builder # [ 44.377720] nix-daemon[820]: remote pid 809 is unknown user (trusted) builder # [ 44.390606] systemd[1]: nix-daemon@1-2-809_810-0.service: Deactivated successfully. server # [ 44.398810] niks3-server[953]: 2026/10/01 10:34:13 INFO Received push request method=POST path=/api/pushes builder # [ 44.407913] niks3-hook[798]: time=2026-10-01T10:34:13.966Z level=INFO msg="Uploading 1 paths to server (0 already cached)" builder # [ 44.409763] niks3-hook[798]: time=2026-10-01T10:34:13.966Z level=INFO msg="Uploading 1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test (144B)" server # [ 44.439809] niks3-server[953]: 2026/10/01 10:34:14 INFO Registered completed upload object_key=log/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv server # [ 44.446825] niks3-server[953]: 2026/10/01 10:34:14 INFO Registered completed upload object_key=nar/1a0vfw93shmvlz6lac7d1lv82jxzfdswigh5s32hpvncf74i1i0y.nar.zst server # [ 44.456835] niks3-server[953]: 2026/10/01 10:34:14 INFO Received sign narinfos request method=POST path=/api/pushes/6/sign builder # [ 44.457606] niks3-hook[798]: time=2026-10-01T10:34:14.016Z level=INFO msg="Uploading 1 narinfos" server # [ 44.459301] niks3-server[953]: 2026/10/01 10:34:14 INFO Signed narinfos id=6 count=1 server # [ 44.465415] niks3-server[953]: 2026/10/01 10:34:14 INFO Registered completed upload object_key=1qlca7drab1x7rccc3h1pw1fl9c0j7yx.ls server # [ 44.474281] niks3-server[953]: 2026/10/01 10:34:14 INFO Received complete push request method=POST path=/api/pushes/6/complete builder # [ 44.478877] niks3-hook[798]: time=2026-10-01T10:34:14.038Z level=INFO msg="Upload complete. (283ms)" server # [ 44.480623] niks3-server[953]: 2026/10/01 10:34:14 INFO Registered completed upload object_key=1qlca7drab1x7rccc3h1pw1fl9c0j7yx.narinfo builder # [ 49.196459] niks3-hook[798]: time=2026-10-01T10:34:18.756Z level=INFO msg="Idle timeout reached and queue is empty, shutting down" builder # [ 49.199612] niks3-hook[798]: time=2026-10-01T10:34:18.759Z level=INFO msg="niks3-hook serve stopped" builder # [ 49.209672] systemd[1]: niks3-auto-upload.service: Deactivated successfully. builder # [ 49.213160] systemd[1]: niks3-auto-upload.service: Consumed 140ms CPU time over 5.132s wall clock time, 10.7M memory peak, 68K written to disk, 5.5K incoming IP traffic, 8.3K outgoing IP traffic. builder: (finished: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive, in 5.20 seconds) server: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin 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-test server: (finished: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin 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-test , in 0.21 seconds) server: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test server: (finished: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test, in 0.05 seconds) (finished: run the VM test script, in 50.49 seconds) test script finished in 50.58s cleanup kill QemuMachine (pid 47) builder # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14) builder # [2026-10-01T10:34:19Z INFO virtiofsd] Client disconnected, shutting down builder # [2026-10-01T10:34:19Z INFO virtiofsd] Client disconnected, shutting down builder # [2026-10-01T10:34:19Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 48) server # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14) server # [2026-10-01T10:34:19Z INFO virtiofsd] Client disconnected, shutting down server # [2026-10-01T10:34:19Z INFO virtiofsd] Client disconnected, shutting down server # [2026-10-01T10:34:19Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.45 seconds) additionally exposed symbols: builder, server, vlan1, 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_ssh Hello store path: /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 Test output path: /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log Symlink wrapper store path: /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper Symlink wrapper points to: /nix/store/xafv64bljflg1v8hnf22lyk0w4gma3v5-base-package/bin/test-program OIDC test store path: /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test Valid OIDC token obtained (length=677) OIDC push with valid token: SUCCESS Invalid OIDC token obtained (wrong org) OIDC push with wrong org: correctly rejected Wrong audience OIDC token obtained OIDC push with wrong audience: correctly rejected OIDC push with malformed token: correctly rejected All OIDC tests passed! All pin tests passed! Built store path: /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test Post-build-hook pipeline test passed!