vm-test-run-nixos-test-niks3
checks.aarch64-linux.nixos-test-niks3-lix
· build #419
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.03 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7start all VMs8builder: starting vm9server: starting vm10builder # Disk image does not exist, creating the virtualisation disk image...11builder: QEMU running (pid 47)12builder # Formatting '/build/vm-state-builder/tmp.81S6gfvx28', fmt=raw size=107374182413builder # mke2fs 1.47.4 (6-Mar-2025)14builder # Discarding device blocks: 0/262144 done15builder # Creating filesystem with 262144 4k blocks and 65536 inodes16builder # Filesystem UUID: 0e203903-7fb4-4ba7-a0b3-0f9b864c7d3417builder # Superblock backups stored on blocks:18builder # 32768, 98304, 163840, 22937619builder # 20builder # Allocating group tables: 0/8 done21builder # Writing inode tables: 0/8 done22builder # Creating journal (8192 blocks): done23builder # Writing superblocks and filesystem accounting information: 0/8 done24builder # 25builder # Virtualisation disk image created.26builder # Starting virtiofs daemons...27builder # [2026-10-20T08:18:06Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28builder # [2026-10-20T08:18:06Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29builder # [2026-10-20T08:18:06Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)30builder # [2026-10-20T08:18:06Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether31builder # [2026-10-20T08:18:06Z INFO virtiofsd] Waiting for vhost-user socket connection...32builder # [2026-10-20T08:18:06Z INFO virtiofsd] Waiting for vhost-user socket connection...33builder # [2026-10-20T08:18:06Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34builder # [2026-10-20T08:18:06Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35builder # [2026-10-20T08:18:06Z INFO virtiofsd] Waiting for vhost-user socket connection...36builder # [2026-10-20T08:18:06Z INFO virtiofsd] Client connected, servicing requests37builder # [2026-10-20T08:18:06Z INFO virtiofsd] Client connected, servicing requests38builder # [2026-10-20T08:18:06Z INFO virtiofsd] Client connected, servicing requests39server # Disk image does not exist, creating the virtualisation disk image...40server: QEMU running (pid 48)41server # Formatting '/build/vm-state-server/tmp.9PDlhDbCvs', fmt=raw size=107374182442server # mke2fs 1.47.4 (6-Mar-2025)43server # Discarding device blocks: 0/262144 done44server # Creating filesystem with 262144 4k blocks and 65536 inodes45(finished: start all VMs, in 2.26 seconds)46server # Filesystem UUID: 9de2f555-7836-4f56-a4b6-9187e26fe84b47server: waiting for unit postgresql.service48server # Superblock backups stored on blocks:49server: waiting for the VM to finish booting50server # 32768, 98304, 163840, 22937651server # 52server # Allocating group tables: 0/8 done53server # Writing inode tables: 0/8 done54server # Creating journal (8192 blocks): done55server # Writing superblocks and filesystem accounting information: 0/8 done56server # 57server # Virtualisation disk image created.58server # Starting virtiofs daemons...59server # [2026-10-20T08:18:06Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)60server # [2026-10-20T08:18:06Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether61server # [2026-10-20T08:18:06Z INFO virtiofsd] Waiting for vhost-user socket connection...62server # [2026-10-20T08:18:06Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)63server # [2026-10-20T08:18:06Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether64server # [2026-10-20T08:18:06Z INFO virtiofsd] Waiting for vhost-user socket connection...65server # [2026-10-20T08:18:06Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)66server # [2026-10-20T08:18:06Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether67server # [2026-10-20T08:18:06Z INFO virtiofsd] Waiting for vhost-user socket connection...68server # [2026-10-20T08:18:06Z INFO virtiofsd] Client connected, servicing requests69server # [2026-10-20T08:18:06Z INFO virtiofsd] Client connected, servicing requests70server # [2026-10-20T08:18:06Z INFO virtiofsd] Client connected, servicing requests71builder # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]72builder # [ 0.000000] Linux version 6.18.55 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Oct 3 10:39:03 UTC 202673builder # [ 0.000000] KASLR enabled74builder # [ 0.000000] random: crng init done75builder # [ 0.000000] Machine model: linux,dummy-virt76builder # [ 0.000000] efi: UEFI not found.77builder # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT78builder # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]79builder # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]80builder # [ 0.000000] Zone ranges:81builder # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]82builder # [ 0.000000] DMA32 empty83builder # [ 0.000000] Normal empty84builder # [ 0.000000] Device empty85builder # [ 0.000000] Movable zone start for each node86builder # [ 0.000000] Early memory node ranges87builder # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]88builder # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]89builder # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000090builder # [ 0.000000] psci: probing for conduit method from DT.91builder # [ 0.000000] psci: PSCIv1.3 detected in firmware.92builder # [ 0.000000] psci: Using standard PSCI v0.2 function IDs93builder # [ 0.000000] psci: Trusted OS migration not required94builder # [ 0.000000] psci: SMC Calling Convention v1.195server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]96builder # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)97builder # [ 0.000000] percpu: Embedded 76 pages/cpu s186840 r8192 d116264 u31129698server # [ 0.000000] Linux version 6.18.55 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Oct 3 10:39:03 UTC 202699server # [ 0.000000] KASLR enabled100builder # [ 0.000000] Detected PIPT I-cache on CPU0101server # [ 0.000000] random: crng init done102server # [ 0.000000] Machine model: linux,dummy-virt103builder # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)104server # [ 0.000000] efi: UEFI not found.105builder # [ 0.000000] CPU features: detected: GICv3 CPU interface106server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT107builder # [ 0.000000] CPU features: detected: Spectre-v4108builder # [ 0.000000] CPU features: detected: Spectre-BHB109server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]110builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38111server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]112server # [ 0.000000] Zone ranges:113builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23114server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]115builder # [ 0.000000] alternatives: applying boot alternatives116server # [ 0.000000] DMA32 empty117server # [ 0.000000] Normal empty118server # [ 0.000000] Device empty119server # [ 0.000000] Movable zone start for each node120server # [ 0.000000] Early memory node ranges121server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]122builder # [ 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/zbb223rb4d5lzkksc3m8h7sx9i1l4z0b-nixos-system-builder-test/init regInfo=/nix/.ro-store/aidh3zax6sv1vfp53j1g1q08rc80c96h-closure-info/registration console=ttyAMA0,115200n8 console=tty0123server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]124server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000125builder # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/aidh3zax6sv1vfp53j1g1q08rc80c96h-closure-info/registration", will be passed to user space.126server # [ 0.000000] psci: probing for conduit method from DT.127server # [ 0.000000] psci: PSCIv1.3 detected in firmware.128builder # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes129server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs130builder # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)131server # [ 0.000000] psci: Trusted OS migration not required132server # [ 0.000000] psci: SMC Calling Convention v1.1133builder # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)134server # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)135builder # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB136builder # [ 0.000000] software IO TLB: area num 1.137server # [ 0.000000] percpu: Embedded 76 pages/cpu s186840 r8192 d116264 u311296138server # [ 0.000000] Detected PIPT I-cache on CPU0139builder # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)140builder # [ 0.000000] Fallback order for Node 0: 0141server # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)142builder # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144143server # [ 0.000000] CPU features: detected: GICv3 CPU interface144builder # [ 0.000000] Policy zone: DMA145server # [ 0.000000] CPU features: detected: Spectre-v4146server # [ 0.000000] CPU features: detected: Spectre-BHB147builder # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off148server # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38149builder # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1150builder # [ 0.000000] allocated 2097152 bytes of page_ext151server # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23152builder # [ 0.000000] ftrace: allocating 75039 entries in 294 pages153server # [ 0.000000] alternatives: applying boot alternatives154builder # [ 0.000000] ftrace: allocated 294 pages with 4 groups155builder # [ 0.000000] rcu: Hierarchical RCU implementation.156builder # [ 0.000000] rcu: RCU event tracing is enabled.157builder # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.158builder # [ 0.000000] Trampoline variant of Tasks RCU enabled.159server # [ 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/ayhdj7hnffrdc9m6jkipzkk69qydvj6y-nixos-system-server-test/init regInfo=/nix/.ro-store/z47icxy5g8zlfbnlsisgcqlcm9c9py7j-closure-info/registration console=ttyAMA0,115200n8 console=tty0160builder # [ 0.000000] Rude variant of Tasks RCU enabled.161builder # [ 0.000000] Tracing variant of Tasks RCU enabled.162builder # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.163server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/z47icxy5g8zlfbnlsisgcqlcm9c9py7j-closure-info/registration", will be passed to user space.164builder # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1165server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes166builder # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.167server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)168builder # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.169server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)170builder # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.171builder # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0172server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB173builder # [ 0.000000] GICv3: 256 SPIs implemented174server # [ 0.000000] software IO TLB: area num 1.175builder # [ 0.000000] GICv3: 0 Extended SPIs implemented176builder # [ 0.000000] Root IRQ handler: gic_handle_irq177server # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)178builder # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI179server # [ 0.000000] Fallback order for Node 0: 0180builder # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0181server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144182builder # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000183server # [ 0.000000] Policy zone: DMA184builder # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]185server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off186server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1187builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)188server # [ 0.000000] allocated 2097152 bytes of page_ext189server # [ 0.000000] ftrace: allocating 75039 entries in 294 pages190builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)191server # [ 0.000000] ftrace: allocated 294 pages with 4 groups192builder # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000193server # [ 0.000000] rcu: Hierarchical RCU implementation.194server # [ 0.000000] rcu: RCU event tracing is enabled.195builder # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000196server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.197builder # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.198server # [ 0.000000] Trampoline variant of Tasks RCU enabled.199server # [ 0.000000] Rude variant of Tasks RCU enabled.200builder # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns201server # [ 0.000000] Tracing variant of Tasks RCU enabled.202builder # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).203server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.204builder # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns205server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1206builder # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns207server # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.208builder # [ 0.000051] arm-pv: using stolen time PV209server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.210builder # [ 0.000542] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)211builder # [ 0.000832] Console: colour dummy device 80x25212server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.213builder # [ 0.000842] printk: legacy console [tty0] enabled214server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0215server # [ 0.000000] GICv3: 256 SPIs implemented216server # [ 0.000000] GICv3: 0 Extended SPIs implemented217builder # [ 0.001083] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)218server # [ 0.000000] Root IRQ handler: gic_handle_irq219builder # [ 0.001091] pid_max: default: 32768 minimum: 301220server # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI221builder # [ 0.001182] LSM: initializing lsm=capability,landlock,yama,bpf,ima222server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0223builder # [ 0.001382] landlock: Up and running.224builder # [ 0.001387] Yama: becoming mindful.225server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000226builder # [ 0.002025] LSM support for eBPF active227server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]228builder # [ 0.002675] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)229server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)230builder # [ 0.002695] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)231builder # [ 0.005443] rcu: Hierarchical SRCU implementation.232server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)233builder # [ 0.005450] rcu: Max phase no-delay instances is 1000.234server # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000235builder # [ 0.006813] fsl-mc MSI: its@8080000 domain created236server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000237builder # [ 0.006954] EFI services will not be available.238builder # [ 0.007056] smp: Bringing up secondary CPUs ...239server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.240builder # [ 0.007068] smp: Brought up 1 node, 1 CPU241builder # [ 0.007072] SMP: Total of 1 processors activated.242server # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns243builder # [ 0.007075] CPU: All CPU(s) started at EL1244server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).245builder # [ 0.007088] CPU features: detected: Branch Target Identification246builder # [ 0.007095] CPU features: detected: ARMv8.4 Translation Table Level247server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns248builder # [ 0.007099] CPU features: detected: Instruction cache invalidation not required for I/D coherence249server # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns250server # [ 0.000053] arm-pv: using stolen time PV251builder # [ 0.007102] CPU features: detected: Data cache clean to the PoU not required for I/D coherence252builder # [ 0.007106] CPU features: detected: Common not Private translations253server # [ 0.000602] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)254builder # [ 0.007109] CPU features: detected: CRC32 instructions255server # [ 0.000862] Console: colour dummy device 80x25256server # [ 0.000874] printk: legacy console [tty0] enabled257builder # [ 0.007112] CPU features: detected: Data cache clean to Point of Deep Persistence258builder # [ 0.007116] CPU features: detected: Data cache clean to Point of Persistence259server # [ 0.001104] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)260server # [ 0.001113] pid_max: default: 32768 minimum: 301261server # [ 0.001226] LSM: initializing lsm=capability,landlock,yama,bpf,ima262server # [ 0.001437] landlock: Up and running.263server # [ 0.001441] Yama: becoming mindful.264builder # [ 0.007119] CPU features: detected: Data independent timing control (DIT)265server # [ 0.001964] LSM support for eBPF active266builder # [ 0.007122] CPU features: detected: E0PD267builder # [ 0.007125] CPU features: detected: Enhanced Counter Virtualization268server # [ 0.002170] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)269builder # [ 0.007128] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)270server # [ 0.002200] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)271server # [ 0.006310] rcu: Hierarchical SRCU implementation.272builder # [ 0.007131] CPU features: detected: Enhanced Virtualization Traps273server # [ 0.006322] rcu: Max phase no-delay instances is 1000.274builder # [ 0.007134] CPU features: detected: Fine Grained Traps275server # [ 0.007764] fsl-mc MSI: its@8080000 domain created276builder # [ 0.007138] CPU features: detected: Generic authentication (architected QARMA5 algorithm)277server # [ 0.007921] EFI services will not be available.278server # [ 0.008038] smp: Bringing up secondary CPUs ...279builder # [ 0.007143] CPU features: detected: RCpc load-acquire (LDAPR)280server # [ 0.008057] smp: Brought up 1 node, 1 CPU281builder # [ 0.007146] CPU features: detected: LSE atomic instructions282server # [ 0.008061] SMP: Total of 1 processors activated.283builder # [ 0.007149] CPU features: detected: Privileged Access Never284server # [ 0.008065] CPU: All CPU(s) started at EL1285builder # [ 0.007152] CPU features: detected: PMUv3286server # [ 0.008087] CPU features: detected: Branch Target Identification287builder # [ 0.007154] CPU features: detected: RAS Extension Support288server # [ 0.008095] CPU features: detected: ARMv8.4 Translation Table Level289builder # [ 0.007157] CPU features: detected: RASv1p1 Extension Support290builder # [ 0.007160] CPU features: detected: Random Number Generator291server # [ 0.008099] CPU features: detected: Instruction cache invalidation not required for I/D coherence292builder # [ 0.007162] CPU features: detected: Speculation barrier (SB)293server # [ 0.008102] CPU features: detected: Data cache clean to the PoU not required for I/D coherence294builder # [ 0.007165] CPU features: detected: Stage-2 Force Write-Back295server # [ 0.008106] CPU features: detected: Common not Private translations296builder # [ 0.007168] CPU features: detected: TLB range maintenance instructions297server # [ 0.008109] CPU features: detected: CRC32 instructions298builder # [ 0.007173] CPU features: detected: Speculative Store Bypassing Safe (SSBS)299server # [ 0.008112] CPU features: detected: Data cache clean to Point of Deep Persistence300builder # [ 0.007212] alternatives: applying system-wide alternatives301server # [ 0.008116] CPU features: detected: Data cache clean to Point of Persistence302builder # [ 0.012112] CPU features: detected: BBM Level 2 without TLB conflict abort303server # [ 0.008119] CPU features: detected: Data independent timing control (DIT)304server # [ 0.008122] CPU features: detected: E0PD305builder # [ 0.014567] Memory: 893056K/1048576K available (24576K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 114188K reserved, 32768K cma-reserved)306server # [ 0.008125] CPU features: detected: Enhanced Counter Virtualization307builder # [ 0.016756] devtmpfs: initialized308server # [ 0.008130] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)309builder # [ 0.019398] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)310server # [ 0.008134] CPU features: detected: Enhanced Virtualization Traps311server # [ 0.008137] CPU features: detected: Fine Grained Traps312builder # [ 0.019426] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).313builder # [ 0.019718] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL314server # [ 0.008140] CPU features: detected: Generic authentication (architected QARMA5 algorithm)315builder # [ 0.019732] 0 pages in range for non-PLT usage316server # [ 0.008147] CPU features: detected: RCpc load-acquire (LDAPR)317builder # [ 0.019733] 508192 pages in range for PLT usage318server # [ 0.008150] CPU features: detected: LSE atomic instructions319builder # [ 0.019857] pinctrl core: initialized pinctrl subsystem320server # [ 0.008153] CPU features: detected: Privileged Access Never321builder # [ 0.020868] DMI not present or invalid.322server # [ 0.008155] CPU features: detected: PMUv3323builder # [ 0.024709] NET: Registered PF_NETLINK/PF_ROUTE protocol family324server # [ 0.008158] CPU features: detected: RAS Extension Support325builder # [ 0.027094] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations326server # [ 0.008161] CPU features: detected: RASv1p1 Extension Support327server # [ 0.008163] CPU features: detected: Random Number Generator328builder # [ 0.027250] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations329server # [ 0.008166] CPU features: detected: Speculation barrier (SB)330builder # [ 0.027408] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations331server # [ 0.008169] CPU features: detected: Stage-2 Force Write-Back332builder # [ 0.027436] audit: initializing netlink subsys (disabled)333server # [ 0.008171] CPU features: detected: TLB range maintenance instructions334builder # [ 0.028174] audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1335server # [ 0.008176] CPU features: detected: Speculative Store Bypassing Safe (SSBS)336builder # [ 0.028207] thermal_sys: Registered thermal governor 'fair_share'337server # [ 0.008255] alternatives: applying system-wide alternatives338builder # [ 0.028209] thermal_sys: Registered thermal governor 'bang_bang'339server # [ 0.012713] CPU features: detected: BBM Level 2 without TLB conflict abort340builder # [ 0.028212] thermal_sys: Registered thermal governor 'step_wise'341builder # [ 0.028215] thermal_sys: Registered thermal governor 'user_space'342server # [ 0.012854] Memory: 893092K/1048576K available (24576K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 114176K reserved, 32768K cma-reserved)343builder # [ 0.028220] thermal_sys: Registered thermal governor 'power_allocator'344server # [ 0.014125] devtmpfs: initialized345builder # [ 0.028252] cpuidle: using governor ladder346builder # [ 0.028260] cpuidle: using governor menu347server # [ 0.016153] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)348builder # [ 0.028443] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.349server # [ 0.016176] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).350builder # [ 0.028466] ASID allocator initialised with 65536 entries351server # [ 0.016446] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL352builder # [ 0.029950] Serial: AMBA PL011 UART driver353server # [ 0.016454] 0 pages in range for non-PLT usage354server # [ 0.016455] 508192 pages in range for PLT usage355builder # [ 0.037194] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1356server # [ 0.016574] pinctrl core: initialized pinctrl subsystem357builder # [ 0.037372] printk: console [ttyAMA0] enabled358server # [ 0.017546] DMI not present or invalid.359server # [ 0.021952] NET: Registered PF_NETLINK/PF_ROUTE protocol family360server # [ 0.024626] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations361server # [ 0.024828] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations362server # [ 0.025018] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations363server # [ 0.025056] audit: initializing netlink subsys (disabled)364server # [ 0.026219] thermal_sys: Registered thermal governor 'fair_share'365server # [ 0.026225] thermal_sys: Registered thermal governor 'bang_bang'366server # [ 0.026234] thermal_sys: Registered thermal governor 'step_wise'367server # [ 0.026242] thermal_sys: Registered thermal governor 'user_space'368server # [ 0.026248] thermal_sys: Registered thermal governor 'power_allocator'369server # [ 0.026331] audit: type=2000 audit(0.024:1): state=initialized audit_enabled=0 res=1370server # [ 0.026361] cpuidle: using governor ladder371server # [ 0.026381] cpuidle: using governor menu372server # [ 0.026617] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.373server # [ 0.026670] ASID allocator initialised with 65536 entries374server # [ 0.029188] Serial: AMBA PL011 UART driver375server # [ 0.037936] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1376server # [ 0.038163] printk: console [ttyAMA0] enabled377builder # [ 0.171216] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages378builder # [ 0.171242] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page379builder # [ 0.171246] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages380builder # [ 0.171249] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page381builder # [ 0.171252] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages382builder # [ 0.171255] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page383builder # [ 0.171258] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages384builder # [ 0.171260] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page385builder # [ 0.172827] fbcon: Taking over console386builder # [ 0.172846] ACPI: Interpreter disabled.387builder # [ 0.190602] iommu: Default domain type: Translated388builder # [ 0.190611] iommu: DMA domain TLB invalidation policy: strict mode389server # [ 0.172788] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages390builder # [ 0.191132] SCSI subsystem initialized391server # [ 0.172843] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page392server # [ 0.172848] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages393server # [ 0.172853] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page394builder # [ 0.193308] usbcore: registered new interface driver usbfs395server # [ 0.172863] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages396builder # [ 0.193338] usbcore: registered new interface driver hub397server # [ 0.172871] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page398builder # [ 0.193355] usbcore: registered new device driver usb399server # [ 0.172875] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages400builder # [ 0.193799] pps_core: LinuxPPS API ver. 1 registered401server # [ 0.172881] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page402builder # [ 0.193803] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>403builder # [ 0.193812] PTP clock support registered404builder # [ 0.193859] EDAC MC: Ver: 3.0.0405server # [ 0.183692] fbcon: Taking over console406builder # [ 0.194070] scmi_core: SCMI protocol bus registered407server # [ 0.183706] ACPI: Interpreter disabled.408builder # [ 0.194522] FPGA manager framework409builder # [ 0.205760] vgaarb: loaded410builder # [ 0.206699] clocksource: Switched to clocksource arch_sys_counter411server # [ 0.193657] iommu: Default domain type: Translated412server # [ 0.193669] iommu: DMA domain TLB invalidation policy: strict mode413server # [ 0.194902] SCSI subsystem initialized414server # [ 0.195632] usbcore: registered new interface driver usbfs415server # [ 0.195675] usbcore: registered new interface driver hub416builder # [ 0.211573] VFS: Disk quotas dquot_6.6.0417server # [ 0.195695] usbcore: registered new device driver usb418builder # [ 0.211632] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)419server # [ 0.196024] pps_core: LinuxPPS API ver. 1 registered420builder # [ 0.211945] netfs: FS-Cache loaded421builder # [ 0.212852] pnp: PnP ACPI: disabled422server # [ 0.196028] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>423server # [ 0.196036] PTP clock support registered424server # [ 0.196098] EDAC MC: Ver: 3.0.0425server # [ 0.196287] scmi_core: SCMI protocol bus registered426server # [ 0.204284] FPGA manager framework427builder # [ 0.221685] NET: Registered PF_INET protocol family428builder # [ 0.221878] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)429server # [ 0.207207] vgaarb: loaded430server # [ 0.207600] clocksource: Switched to clocksource arch_sys_counter431server # [ 0.213290] VFS: Disk quotas dquot_6.6.0432server # [ 0.213369] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)433server # [ 0.213798] netfs: FS-Cache loaded434server # [ 0.215043] pnp: PnP ACPI: disabled435server # [ 0.229204] NET: Registered PF_INET protocol family436server # [ 0.229407] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)437builder # [ 0.317025] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)438builder # [ 0.317128] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)439builder # [ 0.317165] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)440builder # [ 0.317231] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)441server # [ 0.316186] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)442builder # [ 0.317332] TCP: Hash tables configured (established 8192 bind 8192)443server # [ 0.316251] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)444builder # [ 0.317583] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)445server # [ 0.316275] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)446builder # [ 0.317693] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)447server # [ 0.316325] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)448server # [ 0.316398] TCP: Hash tables configured (established 8192 bind 8192)449builder # [ 0.317726] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)450server # [ 0.316562] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)451builder # [ 0.317868] NET: Registered PF_UNIX/PF_LOCAL protocol family452server # [ 0.316617] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)453builder # [ 0.317907] NET: Registered PF_XDP protocol family454builder # [ 0.317937] PCI: CLS 0 bytes, default 64455server # [ 0.316642] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)456builder # [ 0.318498] Trying to unpack rootfs image as initramfs...457server # [ 0.316758] NET: Registered PF_UNIX/PF_LOCAL protocol family458server # [ 0.316788] NET: Registered PF_XDP protocol family459server # [ 0.316813] PCI: CLS 0 bytes, default 64460server # [ 0.317186] Trying to unpack rootfs image as initramfs...461builder # [ 0.344007] kvm [1]: HYP mode not available462server # [ 0.335637] kvm [1]: HYP mode not available463server # [ 0.448506] Initialise system trusted keyrings464server # [ 0.448654] workingset: timestamp_bits=42 max_order=18 bucket_order=0465server # [ 0.449099] squashfs: version 4.0 (2009/01/31) Phillip Lougher466server # [ 0.449154] 9p: Installing v9fs 9p2000 file system support467builder # [ 0.468686] Initialise system trusted keyrings468builder # [ 0.468854] workingset: timestamp_bits=42 max_order=18 bucket_order=0469builder # [ 0.469345] squashfs: version 4.0 (2009/01/31) Phillip Lougher470builder # [ 0.469395] 9p: Installing v9fs 9p2000 file system support471server # [ 0.472164] Key type asymmetric registered472server # [ 0.472179] Asymmetric key parser 'x509' registered473server # [ 0.472274] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)474builder # [ 0.497907] Key type asymmetric registered475builder # [ 0.497927] Asymmetric key parser 'x509' registered476builder # [ 0.498271] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)477builder # [ 0.498411] io scheduler mq-deadline registered478builder # [ 0.498415] io scheduler kyber registered479server # [ 0.483666] io scheduler mq-deadline registered480server # [ 0.483681] io scheduler kyber registered481builder # [ 0.510889] pl061_gpio 9030000.pl061: PL061 GPIO chip registered482server # [ 0.495797] pl061_gpio 9030000.pl061: PL061 GPIO chip registered483builder # [ 0.511605] ledtrig-cpu: registered to indicate activity on CPUs484server # [ 0.496494] ledtrig-cpu: registered to indicate activity on CPUs485builder # [ 0.512057] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:486server # [ 0.496954] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:487builder # [ 0.512077] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000488server # [ 0.496972] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000489builder # [ 0.512090] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000490server # [ 0.496983] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000491builder # [ 0.512096] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000492server # [ 0.496989] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000493builder # [ 0.512118] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits494server # [ 0.497009] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits495builder # [ 0.512146] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]496server # [ 0.497036] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]497builder # [ 0.512228] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00498server # [ 0.497136] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00499builder # [ 0.512241] pci_bus 0000:00: root bus resource [bus 00-ff]500server # [ 0.497149] pci_bus 0000:00: root bus resource [bus 00-ff]501builder # [ 0.512246] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]502server # [ 0.497154] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]503builder # [ 0.512249] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]504server # [ 0.497157] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]505builder # [ 0.512252] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]506server # [ 0.497160] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]507builder # [ 0.512328] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint508server # [ 0.497234] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint509builder # [ 0.512835] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint510server # [ 0.497733] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint511builder # [ 0.513025] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]512server # [ 0.497920] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]513builder # [ 0.513038] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]514server # [ 0.497932] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]515builder # [ 0.513064] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]516server # [ 0.497957] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]517builder # [ 0.513075] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]518server # [ 0.497986] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]519builder # [ 0.513588] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint520server # [ 0.498465] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint521builder # [ 0.513776] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]522server # [ 0.498651] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]523builder # [ 0.513788] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]524server # [ 0.498662] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]525builder # [ 0.513814] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]526server # [ 0.498687] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]527builder # [ 0.514290] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint528server # [ 0.499159] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint529builder # [ 0.514479] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]530server # [ 0.499344] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]531builder # [ 0.514491] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]532server # [ 0.499356] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]533builder # [ 0.514517] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]534server # [ 0.499381] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]535server # [ 0.524470] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint536server # [ 0.524667] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]537server # [ 0.524679] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]538server # [ 0.524704] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]539server # [ 0.524716] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]540server # [ 0.525180] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint541server # [ 0.525368] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]542server # [ 0.525393] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]543server # [ 0.525855] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint544server # [ 0.526109] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]545builder # [ 0.547129] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint546builder # [ 0.547336] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]547server # [ 0.526135] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]548builder # [ 0.547349] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]549server # [ 0.526555] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint550builder # [ 0.547375] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]551server # [ 0.526734] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]552builder # [ 0.547386] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]553server # [ 0.526981] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint554builder # [ 0.547867] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint555server # [ 0.527166] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]556builder # [ 0.548061] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]557server # [ 0.527191] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]558builder # [ 0.548088] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]559builder # [ 0.548571] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint560builder # [ 0.548763] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]561builder # [ 0.548789] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]562builder # [ 0.549199] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint563builder # [ 0.549385] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]564builder # [ 0.549653] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint565builder # [ 0.549844] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]566builder # [ 0.549871] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]567builder # [ 0.550363] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint568builder # [ 0.550556] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]569builder # [ 0.550583] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]570server # [ 0.547851] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint571server # [ 0.548060] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]572server # [ 0.548087] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]573server # [ 0.548584] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint574server # [ 0.548774] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]575builder # [ 0.568063] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint576builder # [ 0.568259] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]577server # [ 0.548800] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]578builder # [ 0.568286] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]579server # [ 0.549278] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint580server # [ 0.549553] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]581builder # [ 0.568768] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint582builder # [ 0.569016] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]583server # [ 0.549565] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]584builder # [ 0.569029] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]585server # [ 0.549591] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]586builder # [ 0.569054] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]587server # [ 0.550159] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint588server # [ 0.550345] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]589builder # [ 0.569605] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint590builder # [ 0.569793] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]591server # [ 0.550357] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]592builder # [ 0.569805] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]593server # [ 0.550382] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]594builder # [ 0.569831] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]595server # [ 0.551042] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned596builder # [ 0.570472] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned597server # [ 0.551048] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned598builder # [ 0.570479] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned599server # [ 0.551052] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned600builder # [ 0.570483] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned601server # [ 0.551097] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned602builder # [ 0.570529] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned603server # [ 0.551142] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned604builder # [ 0.570574] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned605server # [ 0.551186] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned606builder # [ 0.570619] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned607server # [ 0.551231] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned608builder # [ 0.570664] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned609server # [ 0.551275] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned610server # [ 0.551321] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned611server # [ 0.551365] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned612server # [ 0.551407] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned613server # [ 0.551450] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned614server # [ 0.551520] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned615server # [ 0.551562] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned616server # [ 0.551580] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned617server # [ 0.551597] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned618server # [ 0.579573] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned619server # [ 0.579597] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned620builder # [ 0.594832] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned621server # [ 0.581367] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned622builder # [ 0.594891] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned623server # [ 0.581386] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned624server # [ 0.581406] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned625builder # [ 0.594938] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned626server # [ 0.581424] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned627builder # [ 0.594983] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned628server # [ 0.581450] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned629builder # [ 0.595029] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned630server # [ 0.581469] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned631builder # [ 0.595155] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned632server # [ 0.581488] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned633builder # [ 0.595201] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned634server # [ 0.581506] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned635server # [ 0.581524] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned636builder # [ 0.595221] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned637server # [ 0.581541] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned638builder # [ 0.595240] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned639server # [ 0.581558] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned640builder # [ 0.595259] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned641server # [ 0.581576] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned642builder # [ 0.595277] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned643server # [ 0.581594] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned644builder # [ 0.595296] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned645server # [ 0.581622] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]646builder # [ 0.595314] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned647server # [ 0.581627] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]648builder # [ 0.595333] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned649server # [ 0.581629] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]650server # [ 0.582500] pci 0000:00:07.0: enabling device (0000 -> 0002)651builder # [ 0.595352] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned652builder # [ 0.595374] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned653builder # [ 0.595392] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned654builder # [ 0.595411] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned655builder # [ 0.595429] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned656builder # [ 0.595446] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned657builder # [ 0.595464] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned658builder # [ 0.595481] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned659builder # [ 0.595498] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned660builder # [ 0.595516] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned661builder # [ 0.595544] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]662builder # [ 0.595549] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]663builder # [ 0.595552] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]664builder # [ 0.596425] pci 0000:00:07.0: enabling device (0000 -> 0002)665builder # [ 0.643167] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)666server # [ 0.624468] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)667server # [ 2.107245] Freeing initrd memory: 26932K668builder # [ 2.134297] Freeing initrd memory: 26944K669builder # [ 2.137547] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)670server # [ 2.110238] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)671builder # [ 2.170556] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)672builder # [ 2.173806] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)673server # [ 2.155901] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)674server # [ 2.158158] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)675builder # [ 2.176917] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)676builder # [ 2.179658] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)677builder # [ 2.185855] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)678server # [ 2.161952] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)679server # [ 2.176740] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)680server # [ 2.178881] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)681builder # [ 2.188877] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)682server # [ 2.182939] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)683builder # [ 2.309670] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)684server # [ 2.296199] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)685server # [ 2.298344] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)686builder # [ 2.312819] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)687builder # [ 2.320795] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)688server # [ 2.311068] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)689builder # [ 2.330108] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled690builder # [ 2.333040] msm_serial: driver initialized691builder # [ 2.333230] SuperH (H)SCI(F) driver initialized692server # [ 2.319583] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled693builder # [ 2.333300] STM32 USART driver initialized694server # [ 2.322589] msm_serial: driver initialized695server # [ 2.322765] SuperH (H)SCI(F) driver initialized696server # [ 2.322836] STM32 USART driver initialized697builder # [ 2.355086] loop: module loaded698server # [ 2.343524] loop: module loaded699builder # [ 2.355268] virtio_blk virtio2: 1/0/0 default/read/poll queues700builder # [ 2.356111] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)701builder # [ 2.362331] megasas: 07.734.00.00-rc1702server # [ 2.344172] virtio_blk virtio2: 1/0/0 default/read/poll queues703server # [ 2.345165] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)704builder # [ 2.363632] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]705server # [ 2.350668] megasas: 07.734.00.00-rc1706server # [ 2.351434] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]707builder # [ 2.368702] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000708builder # [ 2.368720] Intel/Sharp Extended Query Table at 0x0031709builder # [ 2.386419] Using buffer write method710builder # [ 2.386485] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]711server # [ 2.368505] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000712server # [ 2.368572] Intel/Sharp Extended Query Table at 0x0031713server # [ 2.370395] Using buffer write method714server # [ 2.370447] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]715server # [ 2.383879] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000716server # [ 2.383904] Intel/Sharp Extended Query Table at 0x0031717builder # [ 2.390343] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000718server # [ 2.386638] Using buffer write method719builder # [ 2.390361] Intel/Sharp Extended Query Table at 0x0031720server # [ 2.386658] Concatenating MTD devices:721server # [ 2.386661] (0): "0.flash"722server # [ 2.386663] (1): "0.flash"723builder # [ 2.406600] Using buffer write method724server # [ 2.386665] into device "0.flash"725builder # [ 2.406625] Concatenating MTD devices:726builder # [ 2.406627] (0): "0.flash"727builder # [ 2.406630] (1): "0.flash"728builder # [ 2.406632] into device "0.flash"729server # [ 2.398769] tun: Universal TUN/TAP device driver, 1.6730builder # [ 2.415911] tun: Universal TUN/TAP device driver, 1.6731server # [ 2.403430] thunder_xcv, ver 1.0732server # [ 2.403466] thunder_bgx, ver 1.0733server # [ 2.403486] nicpf, ver 1.0734builder # [ 2.421309] thunder_xcv, ver 1.0735builder # [ 2.421357] thunder_bgx, ver 1.0736builder # [ 2.421378] nicpf, ver 1.0737builder # [ 2.422021] e1000: Intel(R) PRO/1000 Network Driver738server # [ 2.405594] e1000: Intel(R) PRO/1000 Network Driver739builder # [ 2.422025] e1000: Copyright (c) 1999-2006 Intel Corporation.740server # [ 2.405601] e1000: Copyright (c) 1999-2006 Intel Corporation.741builder # [ 2.422054] e1000e: Intel(R) PRO/1000 Network Driver742server # [ 2.405639] e1000e: Intel(R) PRO/1000 Network Driver743builder # [ 2.422059] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.744server # [ 2.405646] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.745builder # [ 2.422090] igb: Intel(R) Gigabit Ethernet Network Driver746server # [ 2.405681] igb: Intel(R) Gigabit Ethernet Network Driver747builder # [ 2.422093] igb: Copyright (c) 2007-2014 Intel Corporation.748server # [ 2.405685] igb: Copyright (c) 2007-2014 Intel Corporation.749builder # [ 2.422117] igbvf: Intel(R) Gigabit Virtual Function Network Driver750server # [ 2.405708] igbvf: Intel(R) Gigabit Virtual Function Network Driver751builder # [ 2.422121] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.752builder # [ 2.422263] sky2: driver version 1.30753server # [ 2.405712] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.754server # [ 2.405867] sky2: driver version 1.30755server # [ 2.408351] ehci-pci 0000:00:07.0: EHCI Host Controller756server # [ 2.408397] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1757server # [ 2.408645] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000758builder # [ 2.431722] usbcore: registered new interface driver usb-storage759builder # [ 2.431794] usbcore: registered new interface driver usbserial_generic760server # [ 2.418039] usbcore: registered new interface driver usb-storage761builder # [ 2.431806] usbserial: USB Serial support registered for generic762server # [ 2.418522] usbcore: registered new interface driver usbserial_generic763builder # [ 2.432467] hv_vmbus: registering driver hyperv_keyboard764server # [ 2.418537] usbserial: USB Serial support registered for generic765builder # [ 2.434014] ehci-pci 0000:00:07.0: EHCI Host Controller766server # [ 2.419205] hv_vmbus: registering driver hyperv_keyboard767builder # [ 2.434053] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1768server # [ 2.419664] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00769builder # [ 2.434285] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000770server # [ 2.420058] hub 1-0:1.0: USB hub found771server # [ 2.420077] hub 1-0:1.0: 6 ports detected772builder # [ 2.434606] rtc-pl031 9010000.pl031: registered as rtc0773builder # [ 2.434638] rtc-pl031 9010000.pl031: setting system clock to 2026-10-20T08:18:11 UTC (1792484291)774builder # [ 2.442532] i2c_dev: i2c /dev entries driver775builder # [ 2.442732] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00776builder # [ 2.443235] hub 1-0:1.0: USB hub found777builder # [ 2.443257] hub 1-0:1.0: 6 ports detected778server # [ 2.426315] rtc-pl031 9010000.pl031: registered as rtc0779server # [ 2.426344] rtc-pl031 9010000.pl031: setting system clock to 2026-10-20T08:18:11 UTC (1792484291)780server # [ 2.426691] i2c_dev: i2c /dev entries driver781server # [ 2.430211] sdhci: Secure Digital Host Controller Interface driver782server # [ 2.430217] sdhci: Copyright(c) Pierre Ossman783builder # [ 2.449407] sdhci: Secure Digital Host Controller Interface driver784server # [ 2.430516] Synopsys Designware Multimedia Card Interface Driver785builder # [ 2.449413] sdhci: Copyright(c) Pierre Ossman786server # [ 2.430929] sdhci-pltfm: SDHCI platform and OF driver helper787builder # [ 2.449813] Synopsys Designware Multimedia Card Interface Driver788builder # [ 2.450227] sdhci-pltfm: SDHCI platform and OF driver helper789server # [ 2.438361] hid: raw HID events driver (C) Jiri Kosina790server # [ 2.438666] usbcore: registered new interface driver usbhid791server # [ 2.438670] usbhid: USB HID core driver792builder # [ 2.455056] hid: raw HID events driver (C) Jiri Kosina793builder # [ 2.455329] usbcore: registered new interface driver usbhid794server # [ 2.441929] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available795builder # [ 2.455333] usbhid: USB HID core driver796builder # [ 2.456628] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available797builder # [ 2.458436] drop_monitor: Initializing network drop monitor service798builder # [ 2.458665] NET: Registered PF_INET6 protocol family799server # [ 2.445206] drop_monitor: Initializing network drop monitor service800server # [ 2.445399] NET: Registered PF_INET6 protocol family801server # [ 2.446427] Segment Routing with IPv6802server # [ 2.446456] In-situ OAM (IOAM) with IPv6803server # [ 2.446489] NET: Registered PF_PACKET protocol family804server # [ 2.446554] 9pnet: Installing 9P2000 support805builder # [ 2.464246] Segment Routing with IPv6806server # [ 2.446600] Key type dns_resolver registered807builder # [ 2.464260] In-situ OAM (IOAM) with IPv6808builder # [ 2.464294] NET: Registered PF_PACKET protocol family809builder # [ 2.464374] 9pnet: Installing 9P2000 support810builder # [ 2.464429] Key type dns_resolver registered811builder # [ 2.473655] registered taskstats version 1812server # [ 2.457754] registered taskstats version 1813builder # [ 2.473978] Loading compiled-in X.509 certificates814server # [ 2.458046] Loading compiled-in X.509 certificates815server # [ 2.467378] Demotion targets for Node 0: null816server # [ 2.467509] Key type .fscrypt registered817server # [ 2.467513] Key type fscrypt-provisioning registered818server # [ 2.467651] ima: No TPM chip found, activating TPM-bypass!819server # [ 2.467677] ima: Allocated hash algorithm: sha1820builder # [ 2.482986] Demotion targets for Node 0: null821builder # [ 2.483121] Key type .fscrypt registered822server # [ 2.467713] ima: No architecture policies found823builder # [ 2.483125] Key type fscrypt-provisioning registered824builder # [ 2.483268] ima: No TPM chip found, activating TPM-bypass!825builder # [ 2.483291] ima: Allocated hash algorithm: sha1826builder # [ 2.483328] ima: No architecture policies found827builder # [ 2.484133] input: gpio-keys as /devices/platform/gpio-keys/input/input0828server # [ 2.472974] input: gpio-keys as /devices/platform/gpio-keys/input/input0829builder # [ 2.509029] clk: Disabling unused clocks830server # [ 2.493522] clk: Disabling unused clocks831builder # [ 2.509052] PM: genpd: Disabling unused power domains832server # [ 2.493550] PM: genpd: Disabling unused power domains833server # [ 2.499440] Freeing unused kernel memory: 4736K834builder # [ 2.514870] Freeing unused kernel memory: 4736K835server # [ 2.500449] Run /init as init process836builder # [ 2.515196] Run /init as init process837builder # [ 2.533335] systemd[1]: Successfully made /usr/ read-only.838server # [ 2.518382] systemd[1]: Successfully made /usr/ read-only.839server # [ 2.671694] usb 1-1: new high-speed USB device number 2 using ehci-pci840builder # [ 2.690791] usb 1-1: new high-speed USB device number 2 using ehci-pci841server # [ 2.822243] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1842builder # [ 2.842659] 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/input1843builder # [ 2.867565] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)844server # [ 2.852605] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)845builder # [ 2.867601] systemd[1]: Detected virtualization qemu.846server # [ 2.852638] systemd[1]: Detected virtualization qemu.847builder # [ 2.867657] systemd[1]: Detected architecture arm64.848server # [ 2.852710] systemd[1]: Detected architecture arm64.849builder # [ 2.867666] systemd[1]: Running in initrd.850server # [ 2.852720] systemd[1]: Running in initrd.851builder # [ 2.868338] systemd[1]: Initializing machine ID from random generator.852server # [ 2.853523] systemd[1]: Initializing machine ID from random generator.853builder # [ 2.868552] systemd[1]: Hostname set to <builder>.854server # [ 2.853728] systemd[1]: Hostname set to <server>.855server # [ 2.911959] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0856builder # [ 2.935097] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0857server # [ 3.038133] usb 1-2: new high-speed USB device number 3 using ehci-pci858builder # [ 3.058815] usb 1-2: new high-speed USB device number 3 using ehci-pci859server # [ 3.061769] systemd[1]: bpf-restrict-fs: LSM BPF program attached860builder # [ 3.083982] systemd[1]: bpf-restrict-fs: LSM BPF program attached861server # [ 3.128152] systemd[1]: Queued start job for default target Initrd Default Target.862builder # [ 3.150358] systemd[1]: Queued start job for default target Initrd Default Target.863server # [ 3.135404] systemd[1]: Created slice Slice /system/modprobe.864server # [ 3.135595] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.865server # [ 3.137424] systemd[1]: Expecting device /dev/disk/by-label/nixos...866server # [ 3.137453] systemd[1]: Reached target Path Units.867server # [ 3.137469] systemd[1]: Reached target Slice Units.868server # [ 3.137482] systemd[1]: Reached target Swaps.869server # [ 3.137496] systemd[1]: Reached target Timer Units.870server # [ 3.137653] systemd[1]: Listening on D-Bus System Message Bus Socket.871builder # [ 3.156920] systemd[1]: Created slice Slice /system/modprobe.872server # [ 3.137802] systemd[1]: Listening on Journal Socket (/dev/log).873builder # [ 3.157103] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.874server # [ 3.137913] systemd[1]: Listening on Journal Sockets.875builder # [ 3.157135] systemd[1]: Expecting device /dev/disk/by-label/nixos...876server # [ 3.138054] systemd[1]: Listening on udev Control Socket.877builder # [ 3.157160] systemd[1]: Reached target Path Units.878server # [ 3.138142] systemd[1]: Listening on udev Kernel Socket.879builder # [ 3.157175] systemd[1]: Reached target Slice Units.880server # [ 3.138156] systemd[1]: Reached target Socket Units.881builder # [ 3.157190] systemd[1]: Reached target Swaps.882builder # [ 3.157204] systemd[1]: Reached target Timer Units.883server # [ 3.147225] systemd[1]: Starting Create List of Static Device Nodes...884builder # [ 3.157351] systemd[1]: Listening on D-Bus System Message Bus Socket.885server # [ 3.147308] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs886builder # [ 3.157608] systemd[1]: Listening on Journal Socket (/dev/log).887builder # [ 3.157730] systemd[1]: Listening on Journal Sockets.888builder # [ 3.157845] systemd[1]: Listening on udev Control Socket.889builder # [ 3.157938] systemd[1]: Listening on udev Kernel Socket.890builder # [ 3.157954] systemd[1]: Reached target Socket Units.891builder # [ 3.168873] systemd[1]: Starting Create List of Static Device Nodes...892server # [ 3.152839] systemd[1]: Mounting Kernel Configuration File System...893builder # [ 3.168941] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs894builder # [ 3.178023] systemd[1]: Mounting Kernel Configuration File System...895server # [ 3.163349] systemd[1]: Starting Journal Service...896builder # [ 3.195201] systemd[1]: Starting Journal Service...897server # [ 3.180069] systemd[1]: Starting Load Kernel Modules...898server # [ 3.180260] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os899builder # [ 3.199602] systemd[1]: Starting Load Kernel Modules...900server # [ 3.187355] 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/input2901builder # [ 3.199704] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os902server # [ 3.187552] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0903builder # [ 3.216456] 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/input2904builder # [ 3.216676] systemd[1]: Starting Coldplug All udev Devices...905builder # [ 3.221353] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0906server # [ 3.212596] systemd[1]: Starting Coldplug All udev Devices...907server # [ 3.221366] systemd-journald[70]: Collecting audit messages is disabled.908server # [ 3.224986] systemd[1]: Finished Create List of Static Device Nodes.909server # [ 3.226190] systemd[1]: Mounted Kernel Configuration File System.910builder # [ 3.246067] systemd-journald[70]: Collecting audit messages is disabled.911builder # [ 3.254939] systemd[1]: Finished Create List of Static Device Nodes.912server # [ 3.239765] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...913builder # [ 3.274892] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...914builder # [ 3.291389] systemd[1]: Mounted Kernel Configuration File System.915server # [ 3.296473] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.916builder # [ 3.308916] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.917server # [ 3.298532] systemd[1]: Starting Create Static Device Nodes in /dev...918builder # [ 3.326817] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev919builder # [ 3.329024] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.920builder # [ 3.344634] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0921builder # [ 3.344880] [drm] features: -virgl +edid -resource_blob -host_visible922server # [ 3.333607] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.923builder # [ 3.344885] [drm] features: -context_init924builder # [ 3.345693] [drm] number of scanouts: 1925server # [ 3.334322] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev926builder # [ 3.345704] [drm] number of cap sets: 0927builder # [ 3.346536] systemd[1]: Starting Create Static Device Nodes in /dev...928builder # [ 3.367289] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic929builder # [ 3.367307] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0930server # [ 3.353726] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0931server # [ 3.354013] [drm] features: -virgl +edid -resource_blob -host_visible932server # [ 3.354016] [drm] features: -context_init933server # [ 3.354772] [drm] number of scanouts: 1934server # [ 3.354784] [drm] number of cap sets: 0935server # [ 3.372637] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic936server # [ 3.372654] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0937server # [ 3.379762] systemd[1]: Finished Create Static Device Nodes in /dev.938server # [ 3.380001] systemd[1]: Reached target Preparation for Local File Systems.939server # [ 3.380024] systemd[1]: Reached target Local File Systems.940builder # [ 3.399189] Console: switching to colour frame buffer device 160x50941server # [ 3.388161] systemd[1]: Starting Rule-based Manager for Device Events and Files...942builder # [ 3.420189] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device943builder # [ 3.421229] systemd[1]: Finished Create Static Device Nodes in /dev.944builder # [ 3.421564] systemd[1]: Reached target Preparation for Local File Systems.945builder # [ 3.421644] systemd[1]: Reached target Local File Systems.946server # [ 3.411337] Console: switching to colour frame buffer device 160x50947builder # [ 3.432108] systemd[1]: Starting Rule-based Manager for Device Events and Files...948server # [ 3.414724] systemd-modules-load[71]: Inserted module 'dm_mod'949server # [ 3.428286] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device950builder # [ 3.447382] systemd[1]: Finished Load Kernel Modules.951server # [ 3.436783] systemd[1]: Started Journal Service.952builder # [ 3.455044] systemd[1]: Starting Apply Kernel Variables...953server # [ 3.427726] systemd-modules-load[71]: Module 'virtio_balloon' is built in954builder # [ 3.438794] systemd-modules-load[71]: Inserted module 'dm_mod'955builder # [ 3.444235] systemd-modules-load[71]: Module 'virtio_balloon' is built in956server # [ 3.436568] systemd-modules-load[71]: Module 'virtio_console' is built in957server # [ 3.437757] systemd-modules-load[71]: Inserted module 'virtio_gpu'958server # [ 3.438923] systemd-modules-load[71]: Module 'virtio_rng' is built in959builder # [ 3.448438] systemd-modules-load[71]: Module 'virtio_console' is built in960builder # [ 3.449696] systemd-modules-load[71]: Inserted module 'virtio_gpu'961builder # [ 3.473514] systemd[1]: Started Journal Service.962builder # [ 3.453128] systemd-modules-load[71]: Module 'virtio_rng' is built in963server # [ 3.449210] systemd[1]: Starting Create System Files and Directories...964server # [ 3.453386] systemd[1]: Finished Load Kernel Modules.965builder # [ 3.534796] systemd[1]: Starting Create System Files and Directories...966builder # [ 3.561249] systemd[1]: Finished Apply Kernel Variables.967builder # [ 3.578278] systemd[1]: Finished Create System Files and Directories.968server # [ 3.571045] systemd[1]: Starting Apply Kernel Variables...969server # [ 3.575789] systemd-udevd[77]: Using default interface naming scheme 'v261'.970server # [ 3.584947] systemd[1]: Finished Create System Files and Directories.971builder # [ 3.594130] systemd-udevd[77]: Using default interface naming scheme 'v261'.972server # [ 3.592300] systemd[1]: Finished Apply Kernel Variables.973server # [ 3.610525] systemd[1]: Started Rule-based Manager for Device Events and Files.974builder # [ 3.630406] systemd[1]: Started Rule-based Manager for Device Events and Files.975builder # [ 3.717409] systemd[1]: Starting Virtual Console Setup...976server # [ 3.712868] systemd[1]: Starting Virtual Console Setup...977server # [ 3.790736] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.978server # [ 3.795163] systemd[1]: Finished Virtual Console Setup.979builder # [ 3.813854] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.980builder # [ 3.818301] systemd[1]: Finished Virtual Console Setup.981builder # [ 4.537178] systemd[1]: Finished Coldplug All udev Devices.982builder # [ 4.538944] systemd[1]: Reached target System Initialization.983server # [ 4.530777] systemd[1]: Finished Coldplug All udev Devices.984builder # [ 4.539810] systemd[1]: Reached target Basic System.985server # [ 4.536793] systemd[1]: Reached target System Initialization.986server # [ 4.537724] systemd[1]: Reached target Basic System.987server # [ 4.686841] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.988builder # [ 4.700139] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.989builder # [ 4.720327] (udev-worker)[93]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.990builder # [ 4.730457] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.991server # [ 4.735834] (udev-worker)[90]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.992server # [ 4.739573] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.993server # [ 4.810064] systemd[1]: Found device /dev/disk/by-label/nixos.994server # [ 4.812430] systemd[1]: Reached target Initrd Root Device.995server # [ 4.816978] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...996builder # [ 4.828150] systemd[1]: Found device /dev/disk/by-label/nixos.997builder # [ 4.829217] systemd[1]: Reached target Initrd Root Device.998builder # [ 4.832642] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...999server # [ 4.882386] systemd-fsck[108]: nixos: clean, 12/65536 files, 13019/262144 blocks1000server # [ 4.896227] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1001builder # [ 4.904730] systemd-fsck[108]: nixos: clean, 12/65536 files, 13019/262144 blocks1002server # [ 4.902493] systemd[1]: Mounting /sysroot...1003builder # [ 4.918910] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1004builder # [ 4.926495] systemd[1]: Mounting /sysroot...1005server # [ 4.984993] EXT4-fs (vda): mounted filesystem 9de2f555-7836-4f56-a4b6-9187e26fe84b r/w with ordered data mode. Quota mode: none.1006builder # [ 5.008071] EXT4-fs (vda): mounted filesystem 0e203903-7fb4-4ba7-a0b3-0f9b864c7d34 r/w with ordered data mode. Quota mode: none.1007server # [ 4.981511] systemd[1]: Mounted /sysroot.1008server # [ 4.982815] systemd[1]: Reached target Initrd Root File System.1009server # [ 4.986764] systemd[1]: Starting Mountpoints Configured in the Real Root...1010builder # [ 4.995611] systemd[1]: Mounted /sysroot.1011builder # [ 4.996994] systemd[1]: Reached target Initrd Root File System.1012builder # [ 5.001655] systemd[1]: Starting Mountpoints Configured in the Real Root...1013server # [ 5.025061] systemd-sysroot-fstab-check[116]: /sysroot should be mounted in the initrd, will request daemon-reload.1014server # [ 5.029777] systemd[1]: Reload requested from client PID 116 ('systemd-sysroot') (unit initrd-parse-etc.service)...1015builder # [ 5.038560] systemd-sysroot-fstab-check[116]: /sysroot should be mounted in the initrd, will request daemon-reload.1016server # [ 5.035596] systemd[1]: Reloading...1017builder # [ 5.043484] systemd[1]: Reload requested from client PID 116 ('systemd-sysroot') (unit initrd-parse-etc.service)...1018builder # [ 5.048600] systemd[1]: Reloading...1019server # [ 5.296745] systemd[1]: Reloading finished in 262 ms.1020builder # [ 5.318296] systemd[1]: Reloading finished in 271 ms.1021server # [ 5.328771] systemd-sysroot-fstab-check[116]: Requesting initrd-fs.target/start/replace...1022builder # [ 5.344542] systemd-sysroot-fstab-check[116]: Requesting initrd-fs.target/start/replace...1023server # [ 5.335643] systemd-sysroot-fstab-check[116]: Requesting swap.target/start/replace...1024builder # [ 5.349964] systemd-sysroot-fstab-check[116]: Requesting swap.target/start/replace...1025server # [ 5.345836] systemd[1]: Mounting /sysroot/nix/.ro-store...1026builder # [ 5.360452] systemd[1]: Mounting /sysroot/nix/.ro-store...1027builder # [ 5.392129] systemd[1]: Mounting /sysroot/nix/.rw-store...1028server # [ 5.384994] systemd[1]: Mounting /sysroot/nix/.rw-store...1029builder # [ 5.418587] systemd[1]: Mounting /sysroot/run...1030server # [ 5.417708] systemd[1]: Mounting /sysroot/run...1031builder # [ 5.450318] systemd[1]: Mounting /sysroot/tmp/shared...1032server # [ 5.450893] systemd[1]: Mounting /sysroot/tmp/shared...1033server # [ 5.479166] fuse: init (API version 7.45)1034builder # [ 5.482024] systemd[1]: Mounting /sysroot/tmp/xchg...1035server # [ 5.479112] systemd[1]: Mounting /sysroot/tmp/xchg...1036server # [ 5.499700] virtiofs virtio6: discovered new tag: nix-store1037builder # [ 5.518266] fuse: init (API version 7.45)1038server # [ 5.500598] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1039server # [ 5.495315] systemd[1]: Mounted /sysroot/nix/.rw-store.1040server # [ 5.518398] virtiofs virtio7: discovered new tag: shared1041server # [ 5.519230] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1042builder # [ 5.537867] virtiofs virtio6: discovered new tag: nix-store1043builder # [ 5.520088] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1044server # [ 5.530600] virtiofs virtio8: discovered new tag: xchg1045server # [ 5.531487] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1046builder # [ 5.527206] systemd[1]: Finished Mountpoints Configured in the Real Root.[ 5.548648] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1047builder # 1048builder # [ 5.531579] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1049builder # [ 5.536267] systemd[1]: Mounted /sysroot/nix/.rw-store.1050server # [ 5.528581] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1051builder # [ 5.564964] virtiofs virtio7: discovered new tag: shared1052builder # [ 5.565841] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1053builder # [ 5.573145] virtiofs virtio8: discovered new tag: xchg1054builder # [ 5.574175] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1055server # [ 5.543613] systemd[1]: Finished Mountpoints Configured in the Real Root.1056server # [ 5.549033] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1057server # [ 5.551194] systemd[1]: Mounted /sysroot/nix/.ro-store.1058server # [ 5.582563] systemd[1]: Mounted /sysroot/run.1059builder # [ 5.591997] systemd[1]: Mounted /sysroot/nix/.ro-store.1060builder # [ 5.600848] systemd[1]: Mounted /sysroot/run.1061server # [ 5.599491] systemd[1]: Mounted /sysroot/tmp/shared.1062builder # [ 5.612806] systemd[1]: Mounted /sysroot/tmp/shared.1063server # [ 5.606892] systemd[1]: Mounted /sysroot/tmp/xchg.1064server # [ 5.620989] systemd[1]: Starting rw-sysroot-nix-store.service...1065builder # [ 5.635591] systemd[1]: Starting rw-sysroot-nix-store.service...1066builder # [ 5.640197] systemd[1]: Mounted /sysroot/tmp/xchg.1067server # [ 5.653998] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1068server # [ 5.655743] systemd[1]: Finished rw-sysroot-nix-store.service.1069builder # [ 5.670854] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1070builder # [ 5.674500] systemd[1]: Finished rw-sysroot-nix-store.service.1071server # [ 6.336133] systemd[1]: Mounting /sysroot/nix/store...1072builder # [ 6.352118] systemd[1]: Mounting /sysroot/nix/store...1073server # [ 6.388107] systemd[1]: Mounted /sysroot/nix/store.1074server # [ 6.389178] systemd[1]: Reached target Initrd File Systems.1075server # [ 6.392164] systemd[1]: Starting Find NixOS closure...1076builder # [ 6.403239] systemd[1]: Mounted /sysroot/nix/store.1077builder # [ 6.408142] systemd[1]: Reached target Initrd File Systems.1078builder # [ 6.412247] systemd[1]: Starting Find NixOS closure...1079server # [ 6.410261] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1080builder # [ 6.421451] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1081server # [ 6.449220] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1082server # [ 6.453814] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1083builder # [ 6.472079] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1084server # [ 6.472397] systemd[1]: Finished Find NixOS closure.1085server # [ 6.478086] systemd[1]: Reached target Initrd Default Target.1086builder # [ 6.486708] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1087server # [ 6.481559] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1088builder # [ 6.508270] systemd[1]: Finished Find NixOS closure.1089builder # [ 6.511618] systemd[1]: Reached target Initrd Default Target.1090builder # [ 6.524361] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1091server # [ 6.527941] systemd[1]: Stopped target Initrd Default Target.1092server # [ 6.532090] systemd[1]: Stopped target Basic System.1093server # [ 6.533006] systemd[1]: Stopped target Initrd Root Device.1094server # [ 6.536553] systemd[1]: Stopped target Path Units.1095server # [ 6.540142] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1096server # [ 6.544479] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1097server # [ 6.548131] systemd[1]: Stopped target Slice Units.1098server # [ 6.552617] systemd[1]: Stopped target Socket Units.1099server # [ 6.556116] systemd[1]: Stopped target System Initialization.1100server # [ 6.557014] systemd[1]: Stopped target Swaps.1101server # [ 6.557701] systemd[1]: Stopped target Timer Units.1102builder # [ 6.566430] systemd[1]: Stopped target Initrd Default Target.1103server # [ 6.560200] systemd[1]: dbus.socket: Deactivated successfully.1104builder # [ 6.576087] systemd[1]: Stopped target Basic System.1105server # [ 6.569662] systemd[1]: Closed D-Bus System Message Bus Socket.1106server # [ 6.570781] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1107builder # [ 6.580558] systemd[1]: Stopped target Initrd Root Device.1108server # [ 6.574695] systemd[1]: Stopped Find NixOS closure.1109builder # [ 6.583707] systemd[1]: Stopped target Path Units.1110server # [ 6.575499] systemd[1]: Starting rw-sysroot-nix-store.service...1111builder # [ 6.584574] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1112server # [ 6.576373] systemd[1]: systemd-sysctl.service: Deactivated successfully.1113server # [ 6.577331] systemd[1]: Stopped Apply Kernel Variables.1114server # [ 6.578088] systemd[1]: systemd-modules-load.service: Deactivated successfully.1115server # [ 6.579123] systemd[1]: Stopped Load Kernel Modules.1116server # [ 6.579877] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1117builder # [ 6.589360] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1118builder # [ 6.591371] systemd[1]: Stopped target Slice Units.1119builder # [ 6.596573] systemd[1]: Stopped target Socket Units.1120builder # [ 6.597366] systemd[1]: Stopped target System Initialization.1121builder # [ 6.598193] systemd[1]: Stopped target Swaps.1122builder # [ 6.598895] systemd[1]: Stopped target Timer Units.1123server # [ 6.590415] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1124builder # [ 6.599639] systemd[1]: dbus.socket: Deactivated successfully.1125server # [ 6.600145] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1126builder # [ 6.609973] systemd[1]: Closed D-Bus System Message Bus Socket.1127builder # [ 6.611032] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1128server # [ 6.604341] systemd[1]: Stopped Create System Files and Directories.1129builder # [ 6.616314] systemd[1]: Stopped Find NixOS closure.1130server # [ 6.607971] systemd[1]: Stopped target Local File Systems.1131builder # [ 6.619020] systemd[1]: Starting rw-sysroot-nix-store.service...1132builder # [ 6.621026] systemd[1]: systemd-sysctl.service: Deactivated successfully.1133server # [ 6.613506] systemd[1]: Stopped target Preparation for Local File Systems.1134builder # [ 6.624159] systemd[1]: Stopped Apply Kernel Variables.1135server # [ 6.615789] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1136builder # [ 6.628095] systemd[1]: systemd-modules-load.service: Deactivated successfully.1137server # [ 6.619941] systemd[1]: Stopped Coldplug All udev Devices.1138server # [ 6.622602] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1139builder # [ 6.632153] systemd[1]: Stopped Load Kernel Modules.1140server # [ 6.626065] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1141server # [ 6.627380] systemd[1]: Stopped Virtual Console Setup.1142builder # [ 6.635523] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1143server # [ 6.629576] systemd[1]: initrd-cleanup.service: Deactivated successfully.1144server # [ 6.630692] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1145builder # [ 6.639677] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1146server # [ 6.631641] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1147server # [ 6.632887] systemd[1]: Finished rw-sysroot-nix-store.service.1148builder # [ 6.643955] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1149server # [ 6.639487] systemd[1]: systemd-udevd.service: Deactivated successfully.1150builder # [ 6.649773] systemd[1]: Stopped Create System Files and Directories.1151builder # [ 6.650915] systemd[1]: Stopped target Local File Systems.1152server # [ 6.642519] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1153builder # [ 6.651748] systemd[1]: Stopped target Preparation for Local File Systems.1154server # [ 6.644191] systemd[1]: systemd-udevd.service: Consumed 1.683s CPU time over 3.266s wall clock time, 23.1M memory peak.1155builder # [ 6.657175] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1156server # [ 6.648409] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1157builder # [ 6.659675] systemd[1]: Stopped Coldplug All udev Devices.1158server # [ 6.652345] systemd[1]: Closed udev Control Socket.1159server # [ 6.653145] systemd[1]: Starting Cleanup udev Database...1160builder # [ 6.662019] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1161server # [ 6.654495] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1162server # [ 6.655700] systemd[1]: Stopped Create Static Device Nodes in /dev.1163builder # [ 6.664486] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1164server # [ 6.656741] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1165server # [ 6.657903] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1166builder # [ 6.666982] systemd[1]: Stopped Virtual Console Setup.1167server # [ 6.658949] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1168server # [ 6.659957] systemd[1]: Stopped Create List of Static Device Nodes.1169builder # [ 6.668905] systemd[1]: initrd-cleanup.service: Deactivated successfully.1170builder # [ 6.671212] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1171builder # [ 6.676224] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1172builder # [ 6.677305] systemd[1]: Finished rw-sysroot-nix-store.service.1173builder # [ 6.694456] systemd[1]: systemd-udevd.service: Deactivated successfully.1174builder # [ 6.698541] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1175builder # [ 6.699725] systemd[1]: systemd-udevd.service: Consumed 1.715s CPU time over 3.286s wall clock time, 22.8M memory peak.1176builder # [ 6.704631] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1177builder # [ 6.705718] systemd[1]: Closed udev Control Socket.1178builder # [ 6.706615] systemd[1]: Starting Cleanup udev Database...1179builder # [ 6.708404] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1180server # [ 6.701655] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1181builder # [ 6.712222] systemd[1]: Stopped Create Static Device Nodes in /dev.1182server # [ 6.704580] systemd[1]: Finished Cleanup udev Database.1183builder # [ 6.713218] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1184builder # [ 6.714389] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1185server # [ 6.707052] systemd[1]: Reached target Switch Root.1186builder # [ 6.715436] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1187builder # [ 6.716757] systemd[1]: Stopped Create List of Static Device Nodes.1188server # [ 6.708565] systemd[1]: Starting NixOS Activation...1189builder # [ 6.766069] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1190builder # [ 6.769503] systemd[1]: Finished Cleanup udev Database.1191builder # [ 6.770424] systemd[1]: Reached target Switch Root.1192builder # [ 6.772501] systemd[1]: Starting NixOS Activation...1193server # [ 6.792295] initrd-nixos-activation-start[168]: booting system configuration /nix/store/ayhdj7hnffrdc9m6jkipzkk69qydvj6y-nixos-system-server-test1194server # [ 6.826839] initrd-nixos-activation-start[168]: running activation script...1195builder # [ 6.855794] initrd-nixos-activation-start[169]: booting system configuration /nix/store/zbb223rb4d5lzkksc3m8h7sx9i1l4z0b-nixos-system-builder-test1196builder # [ 6.892150] initrd-nixos-activation-start[169]: running activation script...1197server # [ 7.092805] initrd-nixos-activation-start[191]: setting up /etc...1198builder # [ 7.154479] initrd-nixos-activation-start[192]: setting up /etc...1199server # [ 7.260166] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1200server # [ 7.261954] systemd[1]: Finished NixOS Activation.1201server # [ 7.264700] systemd[1]: Starting Switch Root...1202server # [ 7.291286] systemd[1]: Switching root.1203builder # [ 7.331736] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1204builder # [ 7.335271] systemd[1]: Finished NixOS Activation.1205builder # [ 7.336598] systemd[1]: Starting Switch Root...1206builder # [ 7.365781] systemd[1]: Switching root.1207server # [ 7.473664] systemd-journald[70]: Received SIGTERM from PID 1 (systemd).1208builder # [ 7.556717] systemd-journald[70]: Received SIGTERM from PID 1 (systemd).1209server # [ 7.978179] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1210server # [ 7.978243] systemd[1]: Detected virtualization qemu.1211server # [ 7.978319] systemd[1]: Detected architecture arm64.1212server # [ 7.978454] systemd[1]: Detected first boot.1213server # [ 7.991117] systemd[1]: Initializing machine ID from random generator.1214builder # [ 8.067974] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1215builder # [ 8.068037] systemd[1]: Detected virtualization qemu.1216builder # [ 8.068129] systemd[1]: Detected architecture arm64.1217builder # [ 8.068267] systemd[1]: Detected first boot.1218builder # [ 8.079097] systemd[1]: Initializing machine ID from random generator.1219server # [ 8.126305] systemd[1]: bpf-restrict-fs: LSM BPF program attached1220builder # [ 8.214424] systemd[1]: bpf-restrict-fs: LSM BPF program attached1221server # [ 8.269498] systemd[1]: Applying preset policy.1222builder # [ 8.376527] systemd[1]: Applying preset policy.1223server # [ 8.510641] systemd[1]: Populated /etc with preset unit settings.1224builder # [ 8.672008] systemd[1]: Populated /etc with preset unit settings.1225server # [ 8.750028] systemd[1]: initrd-switch-root.service: Deactivated successfully.1226server # [ 8.750526] systemd[1]: Stopped initrd-switch-root.service.1227server # [ 8.756315] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1228server # [ 8.758123] systemd[1]: Created slice Slice /system/getty.1229server # [ 8.763811] systemd[1]: Created slice User and Session Slice.1230server # [ 8.764463] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1231server # [ 8.765193] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1232server # [ 8.765743] systemd[1]: Expecting device /dev/hvc0...1233server # [ 8.766297] systemd[1]: Expecting device /dev/ttyAMA0...1234server # [ 8.766855] systemd[1]: Reached target Local Encrypted Volumes.1235server # [ 8.767385] systemd[1]: Stopped target initrd-fs.target.1236server # [ 8.773987] systemd[1]: Stopped target initrd-root-fs.target.1237server # [ 8.774403] systemd[1]: Stopped target initrd-switch-root.target.1238server # [ 8.775102] systemd[1]: Reached target Virtual Machines and Containers.1239server # [ 8.778633] systemd[1]: Reached target Path Units.1240server # [ 8.779041] systemd[1]: Reached target Remote File Systems.1241server # [ 8.779602] systemd[1]: Reached target Slice Units.1242server # [ 8.782444] systemd[1]: Reached target Swaps.1243server # [ 8.785351] systemd[1]: Listening on Query the User Interactively for a Password.1244server # [ 8.788641] systemd[1]: Listening on Process Core Dump Socket.1245server # [ 8.790178] systemd[1]: Listening on Credential Encryption/Decryption.1246server # [ 8.793448] systemd[1]: Listening on Factory Reset Management.1247server # [ 8.794022] systemd[1]: Listening on Hostname Service Socket.1248server # [ 8.799205] systemd[1]: Starting Journal Log Access Socket...1249server # [ 8.802507] systemd[1]: Listening on Journal Audit Socket.1250server # [ 8.806739] systemd[1]: Listening on Console Output Muting Service Socket.1251server # [ 8.808842] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1252server # [ 8.810036] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1253server # [ 8.810459] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1254server # [ 8.819371] systemd[1]: Listening on Disk Repartitioning Service Socket.1255server # [ 8.821009] systemd[1]: Listening on udev Control Socket.1256server # [ 8.821800] systemd[1]: Listening on udev Varlink Socket.1257server # [ 8.826825] systemd[1]: Mounting Huge Pages File System...1258server # [ 8.835901] systemd[1]: Mounting POSIX Message Queue File System...1259server # [ 8.843012] systemd[1]: Mounting Kernel Debug File System...1260server # [ 8.875498] systemd[1]: Mounting Kernel Trace File System...1261server # [ 8.893413] systemd[1]: Starting Create List of Static Device Nodes...1262server # [ 8.894031] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1263builder # [ 8.915412] systemd[1]: initrd-switch-root.service: Deactivated successfully.1264builder # [ 8.916287] systemd[1]: Stopped initrd-switch-root.service.1265builder # [ 8.921798] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1266builder # [ 8.926149] systemd[1]: Created slice Slice /system/getty.1267builder # [ 8.929148] systemd[1]: Created slice User and Session Slice.1268builder # [ 8.929849] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1269builder # [ 8.930495] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1270builder # [ 8.934274] systemd[1]: Expecting device /dev/hvc0...1271builder # [ 8.934665] systemd[1]: Expecting device /dev/ttyAMA0...1272builder # [ 8.937176] systemd[1]: Reached target Local Encrypted Volumes.1273builder # [ 8.937599] systemd[1]: Stopped target initrd-fs.target.1274builder # [ 8.938175] systemd[1]: Stopped target initrd-root-fs.target.1275builder # [ 8.941542] systemd[1]: Stopped target initrd-switch-root.target.1276builder # [ 8.941941] systemd[1]: Reached target Virtual Machines and Containers.1277builder # [ 8.942585] systemd[1]: Reached target Path Units.1278builder # [ 8.946117] systemd[1]: Reached target Remote File Systems.1279builder # [ 8.946499] systemd[1]: Reached target Slice Units.1280builder # [ 8.948555] systemd[1]: Reached target Swaps.1281server # [ 8.933305] systemd[1]: Mounting Kernel Configuration File System...1282server # [ 8.933753] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1283builder # [ 8.951488] systemd[1]: Listening on Query the User Interactively for a Password.1284server # [ 8.934169] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1285builder # [ 8.953697] systemd[1]: Listening on Process Core Dump Socket.1286server # [ 8.934461] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1287builder # [ 8.957038] systemd[1]: Listening on Credential Encryption/Decryption.1288builder # [ 8.959609] systemd[1]: Listening on Factory Reset Management.1289builder # [ 8.960122] systemd[1]: Listening on Hostname Service Socket.1290builder # [ 8.966024] systemd[1]: Starting Journal Log Access Socket...1291builder # [ 8.968855] systemd[1]: Listening on Journal Audit Socket.1292builder # [ 8.971893] systemd[1]: Listening on Console Output Muting Service Socket.1293builder # [ 8.972674] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1294builder # [ 8.973141] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1295builder # [ 8.973915] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1296server # [ 8.965637] systemd[1]: Mounting FUSE Control File System...1297server # [ 8.966250] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671298builder # [ 8.984651] systemd[1]: Listening on Disk Repartitioning Service Socket.1299builder # [ 8.985256] systemd[1]: Listening on udev Control Socket.1300builder # [ 8.986124] systemd[1]: Listening on udev Varlink Socket.1301builder # [ 8.992201] systemd[1]: Mounting Huge Pages File System...1302builder # [ 8.997320] systemd[1]: Mounting POSIX Message Queue File System...1303server # [ 8.993406] systemd[1]: Starting Journal Service...1304builder # [ 9.011997] systemd[1]: Mounting Kernel Debug File System...1305builder # [ 9.026917] systemd[1]: Mounting Kernel Trace File System...1306server # [ 9.008101] systemd[1]: Starting Load Kernel Modules...1307server # [ 9.024812] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1308builder # [ 9.045075] systemd[1]: Starting Create List of Static Device Nodes...1309builder # [ 9.045525] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1310server # [ 9.037351] systemd[1]: Starting Remount Root and Kernel File Systems...1311server # [ 9.037748] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1312builder # [ 9.070139] systemd[1]: Mounting Kernel Configuration File System...1313server # [ 9.053418] systemd[1]: Starting Coldplug All udev Devices...1314builder # [ 9.070571] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1315server # [ 9.060606] systemd[1]: Listening on Journal Log Access Socket.1316builder # [ 9.076324] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1317server # [ 9.061657] systemd[1]: Mounted Huge Pages File System.1318server # [ 9.063199] systemd[1]: Mounted POSIX Message Queue File System.1319builder # [ 9.076707] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1320server # [ 9.068357] systemd[1]: Mounted Kernel Debug File System.1321server # [ 9.069748] systemd[1]: Mounted Kernel Trace File System.1322server # [ 9.071112] systemd[1]: Mounted Kernel Configuration File System.1323server # [ 9.077108] systemd[1]: Mounted FUSE Control File System.1324server # [ 9.085372] systemd[1]: Finished Create List of Static Device Nodes.1325builder # [ 9.103463] systemd[1]: Mounting FUSE Control File System...1326server # [ 9.091221] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1327builder # [ 9.103897] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671328builder # [ 9.134200] systemd[1]: Starting Journal Service...1329builder # [ 9.159456] systemd[1]: Starting Load Kernel Modules...1330builder # [ 9.172035] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1331builder # [ 9.179575] systemd[1]: Starting Remount Root and Kernel File Systems...1332builder # [ 9.180001] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1333builder # [ 9.194640] systemd[1]: Starting Coldplug All udev Devices...1334builder # [ 9.201340] systemd[1]: Listening on Journal Log Access Socket.1335builder # [ 9.207229] systemd[1]: Mounted Huge Pages File System.1336builder # [ 9.208004] systemd[1]: Mounted POSIX Message Queue File System.1337builder # [ 9.208954] systemd[1]: Mounted Kernel Debug File System.1338builder # [ 9.209976] systemd[1]: Mounted Kernel Trace File System.1339server # [ 9.198016] EXT4-fs (vda): re-mounted 9de2f555-7836-4f56-a4b6-9187e26fe84b.1340builder # [ 9.215260] systemd[1]: Mounted Kernel Configuration File System.1341builder # [ 9.216964] systemd[1]: Mounted FUSE Control File System.1342server # [ 9.207821] systemd[1]: Finished Remount Root and Kernel File Systems.1343server # [ 9.208760] systemd[1]: Listening on Disk Image Download Service Socket.1344server # [ 9.209435] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1345server # [ 9.227665] systemd[1]: Starting Load/Save OS Random Seed...1346server # [ 9.228175] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1347server # [ 9.230213] systemd[1]: Finished Load Kernel Modules.1348server # [ 9.234706] systemd-journald[262]: Collecting audit messages is enabled.1349server # [ 9.252457] systemd[1]: Starting Firewall...1350builder # [ 9.272591] systemd[1]: Finished Create List of Static Device Nodes.1351builder # [ 9.278144] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1352server # [ 9.264670] systemd[1]: Starting Apply Kernel Variables...1353server # [ 9.289704] systemd[1]: Started Journal Service.1354server # [ 9.280251] systemd[1]: Queued start job for default target Multi-User System.1355server # [ 9.281526] systemd[1]: systemd-journald.service: Deactivated successfully.1356server # [ 9.295563] systemd-modules-load[263]: Module 'atkbd' is built in1357builder # [ 9.348912] systemd[1]: Finished Load Kernel Modules.1358builder # [ 9.359807] systemd[1]: Starting Firewall...1359builder # [ 9.372381] systemd[1]: Starting Apply Kernel Variables...1360server # [ 9.347641] systemd-modules-load[263]: Module 'loop' is built in1361builder # [ 9.380204] systemd-journald[263]: Collecting audit messages is enabled.1362server # [ 9.366225] systemd-modules-load[263]: Inserted module 'tls'1363server # [ 9.377112] systemd-modules-load[263]: Module 'tun' is built in1364builder # [ 9.416327] EXT4-fs (vda): re-mounted 0e203903-7fb4-4ba7-a0b3-0f9b864c7d34.1365builder # [ 9.439260] systemd[1]: Started Journal Service.1366server # [ 9.425000] systemd[1]: Starting Flush Journal to Persistent Storage...1367server # [ 9.426320] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1368builder # [ 9.437383] systemd[1]: Queued start job for default target Multi-User System.1369builder # [ 9.438865] systemd[1]: systemd-journald.service: Deactivated successfully.1370builder # [ 9.463184] systemd-modules-load[264]: Module 'atkbd' is built in1371server # [ 9.473232] systemd-oomd[264]: No swap; memory pressure usage will be degraded1372server # [ 9.479934] systemd[1]: Starting Create Static Device Nodes in /dev...1373server # [ 9.492548] systemd[1]: Finished Load/Save OS Random Seed.1374builder # [ 9.501597] systemd-modules-load[264]: Module 'loop' is built in1375server # [ 9.503530] systemd[1]: Reached target First Boot Complete.1376builder # [ 9.512137] systemd-modules-load[264]: Module 'tun' is built in1377server # [ 9.512291] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1378builder # [ 9.522084] systemd[1]: Finished Remount Root and Kernel File Systems.1379server # [ 9.534262] systemd-journald[262]: Received client request to flush runtime journal.1380builder # [ 9.535172] systemd[1]: Listening on Disk Image Download Service Socket.1381builder # [ 9.552066] systemd[1]: Starting Flush Journal to Persistent Storage...1382builder # [ 9.562528] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1383builder # [ 9.581007] systemd[1]: Starting Load/Save OS Random Seed...1384builder # [ 9.595580] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1385builder # [ 9.624909] systemd[1]: Finished Load/Save OS Random Seed.1386builder # [ 9.636411] systemd[1]: Reached target First Boot Complete.1387builder # [ 9.637476] systemd[1]: Finished Apply Kernel Variables.1388builder # [ 9.670376] systemd-journald[263]: Received client request to flush runtime journal.1389server # [ 9.651305] systemd[1]: Finished Apply Kernel Variables.1390server # [ 9.659585] systemd[1]: Finished Flush Journal to Persistent Storage.1391server # [ 9.669486] systemd[1]: Finished Create Static Device Nodes in /dev.1392server # [ 9.680347] systemd[1]: Reached target Preparation for Local File Systems.1393server # [ 9.685104] systemd[1]: Starting Rule-based Manager for Device Events and Files...1394server # [ 9.742159] systemd[1]: Mounting /run/wrappers...1395builder # [ 9.752590] systemd-oomd[266]: No swap; memory pressure usage will be degraded1396builder # [ 9.762952] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1397builder # [ 9.766879] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1398builder # [ 9.773708] systemd[1]: Starting Create Static Device Nodes in /dev...1399builder # [ 9.776737] systemd[1]: Finished Flush Journal to Persistent Storage.1400server # [ 9.818973] systemd[1]: Mounted /run/wrappers.1401server # [ 9.819925] systemd[1]: Reached target Local File Systems.1402server # [ 9.828417] systemd[1]: Listening on Boot Loader Control Service Socket.1403server # [ 9.834282] systemd[1]: Starting register-nix-paths.service...1404server # [ 9.843012] systemd[1]: Starting Create SUID/SGID Wrappers...1405builder # [ 9.857555] systemd[1]: Finished Create Static Device Nodes in /dev.1406builder # [ 9.860451] systemd[1]: Reached target Preparation for Local File Systems.1407builder # [ 9.866403] systemd[1]: Starting Rule-based Manager for Device Events and Files...1408server # [ 9.861569] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1409server # [ 9.874956] systemd[1]: Starting Save Transient machine-id to Disk...1410server # [ 9.884914] systemd[1]: Starting Create System Files and Directories...1411builder # [ 9.935546] systemd[1]: Mounting /run/wrappers...1412builder # [ 10.045085] systemd[1]: Mounted /run/wrappers.1413builder # [ 10.046195] systemd[1]: Reached target Local File Systems.1414server # [ 10.042656] systemd-udevd[299]: Using default interface naming scheme 'v261'.1415builder # [ 10.054817] systemd[1]: Listening on Boot Loader Control Service Socket.1416builder # [ 10.067748] systemd[1]: Starting register-nix-paths.service...1417builder # [ 10.081379] systemd[1]: Starting Create SUID/SGID Wrappers...1418builder # [ 10.087281] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1419server # [ 10.095316] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1420builder # [ 10.108458] systemd[1]: Starting Save Transient machine-id to Disk...1421builder # [ 10.114517] systemd[1]: Starting Create System Files and Directories...1422server # [ 10.106463] systemd[1]: Finished Save Transient machine-id to Disk.1423server # [ 10.230185] systemd[1]: Finished Create System Files and Directories.1424builder # [ 10.249072] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1425server # [ 10.246206] systemd[1]: Starting Rebuild Journal Catalog...1426builder # [ 10.257195] systemd[1]: Finished Save Transient machine-id to Disk.1427server # [ 10.255484] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1428builder # [ 10.299226] systemd-udevd[303]: Using default interface naming scheme 'v261'.1429server # [ 10.318555] systemd[1]: Started Rule-based Manager for Device Events and Files.1430builder # [ 10.396838] systemd[1]: Finished Create System Files and Directories.1431builder # [ 10.407128] systemd[1]: Starting Rebuild Journal Catalog...1432server # [ 10.405250] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1433builder # [ 10.424913] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1434server # [ 10.511470] systemd[1]: Finished Rebuild Journal Catalog.1435server # [ 10.515429] systemd[1]: Starting Update is Completed...1436builder # [ 10.573870] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1437builder # [ 10.640422] systemd[1]: Started Rule-based Manager for Device Events and Files.1438server # [ 10.650332] systemd[1]: Finished Update is Completed.1439builder # [ 10.667920] systemd[1]: Finished Rebuild Journal Catalog.1440builder # [ 10.673307] systemd[1]: Starting Update is Completed...1441builder # [ 10.794078] systemd[1]: Finished Update is Completed.1442server # [ 11.222265] systemd[1]: Starting Virtual Console Setup...1443server # [ 11.271448] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1444server # [ 11.285544] systemd[1]: Stopped Virtual Console Setup.1445server # [ 11.286502] systemd[1]: Starting Virtual Console Setup...1446server # [ 11.301464] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1447server # [ 11.309741] systemd[1]: Finished Create SUID/SGID Wrappers.1448builder # [ 11.391203] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1449builder # [ 11.392585] systemd[1]: Finished Create SUID/SGID Wrappers.1450server # [ 11.427975] systemd[1]: Finished register-nix-paths.service.1451builder # [ 11.584543] systemd[1]: Finished register-nix-paths.service.1452builder # [ 11.628235] systemd[1]: Starting Virtual Console Setup...1453server # [ 11.644611] systemd-vconsole-setup[445]: Configuration of first virtual console was skipped, ignoring remaining ones.1454builder # [ 11.656525] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1455server # [ 11.654061] systemd[1]: Finished Virtual Console Setup.1456builder # [ 11.665258] systemd[1]: Stopped Virtual Console Setup.1457builder # [ 11.669495] systemd[1]: Starting Virtual Console Setup...1458server # [ 11.924824] systemd[1]: Finished Firewall.1459builder # [ 12.018342] systemd[1]: Finished Firewall.1460builder # [ 12.109906] systemd-vconsole-setup[460]: Configuration of first virtual console was skipped, ignoring remaining ones.1461builder # [ 12.120409] systemd[1]: Finished Virtual Console Setup.1462server # [ 12.169716] systemd[1]: Finished Coldplug All udev Devices.1463server # [ 12.170799] systemd[1]: Reached target System Initialization.1464server # [ 12.171731] systemd[1]: Started Discard unused filesystem blocks once a week.1465server # [ 12.174058] systemd[1]: Started niks3 garbage collection timer.1466server # [ 12.176404] systemd[1]: Started Daily Cleanup of Temporary Directories.1467server # [ 12.185969] systemd[1]: Reached target Timer Units.1468server # [ 12.188580] systemd[1]: Listening on D-Bus System Message Bus Socket.1469server # [ 12.190711] systemd[1]: Starting niks3 server proxy socket...1470server # [ 12.191646] systemd[1]: Listening on niks3 server socket.1471server # [ 12.226808] systemd[1]: Listening on Nix Daemon Socket.1472server # [ 12.227866] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1473server # [ 12.236271] systemd[1]: Listening on niks3 server proxy socket.1474server # [ 12.243836] systemd[1]: Reached target Socket Units.1475server # [ 12.245732] systemd[1]: Starting D-Bus System Message Bus...1476server # [ 12.263913] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1477server # [ 12.323744] dbus-broker-launch[496]: Looking up NSS user entry for 'systemd-timesync'...1478server # [ 12.329728] dbus-broker-launch[496]: NSS returned no entry for 'systemd-timesync'1479server # [ 12.333193] dbus-broker-launch[496]: Invalid user-name in /nix/store/vr5m4183nx944fb55n9d0d58j57jqn9z-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1480server # [ 12.346264] systemd[1]: Started D-Bus System Message Bus.1481server # [ 12.355669] systemd[1]: Reached target Basic System.1482server # [ 12.364822] systemd[1]: Starting Import lastlog data into lastlog2 database...1483builder # [ 12.377734] systemd[1]: Finished Coldplug All udev Devices.1484builder # [ 12.379737] systemd[1]: Reached target System Initialization.1485builder # [ 12.381270] systemd[1]: Started Discard unused filesystem blocks once a week.1486builder # [ 12.382325] systemd[1]: Started Daily Cleanup of Temporary Directories.1487builder # [ 12.383399] systemd[1]: Reached target Timer Units.1488builder # [ 12.390349] systemd[1]: Listening on D-Bus System Message Bus Socket.1489builder # [ 12.391447] systemd[1]: Starting niks3 auto-upload socket...1490server # [ 12.385217] systemd[1]: Starting Generate test mTLS certs...1491builder # [ 12.400287] systemd[1]: Listening on Nix Daemon Socket.1492builder # [ 12.401167] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1493builder # [ 12.402524] systemd[1]: Listening on niks3 auto-upload socket.1494server # [ 12.394309] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1495builder # [ 12.403401] systemd[1]: Reached target Socket Units.1496builder # [ 12.412282] systemd[1]: Starting D-Bus System Message Bus...1497server # [ 12.405195] systemd[1]: Starting Post-Boot Actions...1498server # [ 12.413965] systemd[1]: Started Reset console on configuration changes.1499server # [ 12.426066] systemd[1]: Starting resolvconf update...1500server # [ 12.456673] dbus-broker-launch[496]: Ready1501builder # [ 12.468971] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1502builder # [ 12.484522] dbus-broker-launch[483]: Looking up NSS user entry for 'systemd-timesync'...1503builder # [ 12.490894] dbus-broker-launch[483]: NSS returned no entry for 'systemd-timesync'1504builder # [ 12.494781] dbus-broker-launch[483]: Invalid user-name in /nix/store/0wg9hmaq68a09jxmln40pd1zm6dpwvqf-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1505builder # [ 12.506379] systemd[1]: Started D-Bus System Message Bus.1506builder # [ 12.511963] systemd[1]: Reached target Basic System.1507builder # [ 12.521994] systemd[1]: Starting Import lastlog data into lastlog2 database...1508builder # [ 12.532866] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1509builder # [ 12.545635] systemd[1]: Starting Post-Boot Actions...1510server # [ 12.544508] systemd[1]: Finished Post-Boot Actions.1511builder # [ 12.556381] systemd[1]: Started Reset console on configuration changes.1512builder # [ 12.564910] systemd[1]: Starting resolvconf update...1513server # [ 12.569095] systemd[1]: Started Name Service Cache Daemon (nsncd).1514server # [ 12.577373] systemd[1]: Reached target Host and Network Name Lookups.1515server # [ 12.587765] nsncd[500]: Oct 20 08:18:21.659 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1516server # [ 12.604321] systemd[1]: Reached target User and Group Name Lookups.1517builder # [ 12.619383] dbus-broker-launch[483]: Ready1518server # [ 12.611233] systemd[1]: Starting User Login Management...1519server # [ 12.631552] systemd[1]: Finished Import lastlog data into lastlog2 database.1520server # [ 12.661104] niks3-test-certs-start[511]: -----1521builder # [ 12.682518] systemd[1]: Finished Post-Boot Actions.1522builder # [ 12.697973] systemd[1]: Started Name Service Cache Daemon (nsncd).1523builder # [ 12.701550] systemd[1]: Reached target Host and Network Name Lookups.1524builder # [ 12.707414] systemd[1]: Reached target User and Group Name Lookups.1525builder # [ 12.721067] systemd[1]: Starting User Login Management...1526server # [ 12.720086] niks3-test-certs-start[532]: -----1527builder # [ 12.753925] nsncd[486]: Oct 20 08:18:21.787 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1528builder # [ 12.765871] systemd[1]: Finished Import lastlog data into lastlog2 database.1529server # [ 12.820652] systemd-logind[513]: New seat seat0.1530server # [ 12.834917] systemd[1]: Started User Login Management.1531server # [ 12.840713] systemd[1]: Starting linger-users.service...1532server # [ 12.850778] niks3-test-certs-start[541]: Certificate request self-signature ok1533server # [ 12.861327] niks3-test-certs-start[541]: subject=CN=server1534builder # [ 12.887508] systemd-logind[506]: New seat seat0.1535builder # [ 12.897206] systemd[1]: Started User Login Management.1536server # [ 12.891014] systemd[1]: Stopped target Host and Network Name Lookups.1537server # [ 12.894461] systemd[1]: Stopping Host and Network Name Lookups...1538builder # [ 12.904758] systemd[1]: Starting linger-users.service...1539server # [ 12.908744] systemd[1]: Stopped target User and Group Name Lookups.1540server # [ 12.914898] systemd[1]: Stopping User and Group Name Lookups...1541server # [ 12.926416] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1542builder # [ 12.940941] systemd[1]: Stopped target Host and Network Name Lookups.1543builder # [ 12.944759] systemd[1]: Stopping Host and Network Name Lookups...1544server # [ 12.938099] systemd[1]: nscd.service: Deactivated successfully.1545server # [ 12.944493] niks3-test-certs-start[568]: -----1546server # [ 12.948825] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1547builder # [ 12.960378] systemd[1]: Stopped target User and Group Name Lookups.1548server # [ 12.954313] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1549builder # [ 12.968488] systemd[1]: Stopping User and Group Name Lookups...1550server # [ 12.961123] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1551builder # [ 12.973966] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1552builder # [ 12.981174] systemd[1]: nscd.service: Deactivated successfully.1553server # [ 12.978991] systemd[1]: linger-users.service: Deactivated successfully.1554builder # [ 12.988726] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1555builder # [ 12.992072] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1556server # [ 12.983794] systemd[1]: Finished linger-users.service.1557builder # [ 13.000100] systemd[1]: linger-users.service: Deactivated successfully.1558builder # [ 13.005480] systemd[1]: Finished linger-users.service.1559server # [ 13.042375] systemd[1]: Started Name Service Cache Daemon (nsncd).1560server # [ 13.047327] nsncd[572]: Oct 20 08:18:22.130 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1561builder # [ 13.059483] systemd[1]: Started Name Service Cache Daemon (nsncd).1562builder # [ 13.066361] systemd[1]: Reached target Host and Network Name Lookups.1563server # [ 13.060431] systemd[1]: Reached target Host and Network Name Lookups.1564server # [ 13.062752] systemd[1]: Reached target User and Group Name Lookups.1565builder # [ 13.071186] nsncd[552]: Oct 20 08:18:22.149 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1566builder # [ 13.081904] systemd[1]: Reached target User and Group Name Lookups.1567builder # [ 13.085964] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1568server # [ 13.082527] systemd[1]: Finished resolvconf update.1569server # [ 13.089495] systemd[1]: Reached target Preparation for Network.1570builder # [ 13.099042] systemd[1]: Finished resolvconf update.1571builder # [ 13.099923] systemd[1]: Reached target Preparation for Network.1572server # [ 13.099514] systemd[1]: Starting DHCP Client...1573builder # [ 13.109987] systemd[1]: Starting DHCP Client...1574server # [ 13.105247] niks3-test-certs-start[578]: Certificate request self-signature ok1575builder # [ 13.113855] systemd[1]: Starting Extra networking commands....1576server # [ 13.112565] niks3-test-certs-start[578]: subject=CN=niks3 test client1577server # [ 13.118256] systemd[1]: Starting Extra networking commands....1578server # [ 13.153626] systemd[1]: Finished Generate test mTLS certs.1579builder # [ 13.168138] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1580builder # [ 13.173699] systemd[1]: Started backdoor.service.1581server # [ 13.179481] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1582server # [ 13.187031] systemd[1]: Started backdoor.service.1583builder # connecting to host...1584server # connecting to host...1585server: Guest shell says: b'Spawning backdoor root shell...\n'1586builder # [ 13.381769] (udev-worker)[397]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1587server: connected to guest root shell1588server: (connecting took 13.84 seconds)1589server: (finished: waiting for the VM to finish booting, in 13.85 seconds)1590builder # [ 13.396184] (udev-worker)[397]: Network interface NamePolicy= disabled on kernel command line.1591server # [ 13.433447] dhcpcd[611]: dhcpcd-10.3.2 starting1592builder # [ 13.443967] dhcpcd[587]: dhcpcd-10.3.2 starting1593builder # [ 13.448882] (udev-worker)[388]: Network interface NamePolicy= disabled on kernel command line.1594server # [ 13.460866] dhcpcd[663]: dev: loaded udev1595builder # [ 13.473929] dhcpcd[630]: dev: loaded udev1596builder # [ 13.546586] 8021q: 802.1Q VLAN Support v1.81597server # [ 13.529085] 8021q: 802.1Q VLAN Support v1.81598builder # [ 13.603756] systemd[1]: Condition check resulted in Virtio network device being skipped.1599server # [ 13.607114] (udev-worker)[616]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1600builder # [ 13.617317] systemd[1]: Starting Address configuration of eth1...1601server # [ 13.629468] (udev-worker)[616]: Network interface NamePolicy= disabled on kernel command line.1602server # [ 13.634240] (udev-worker)[612]: Network interface NamePolicy= disabled on kernel command line.1603builder # [ 13.685822] cfg80211: Loading compiled-in X.509 certificates for regulatory database1604builder # [ 13.668435] systemd[1]: Finished Extra networking commands..1605builder # [ 13.672296] systemd[1]: Reached target Network.1606builder # [ 13.681590] systemd[1]: Starting Permit User Sessions...1607server # [ 13.694776] cfg80211: Loading compiled-in X.509 certificates for regulatory database1608builder # [ 13.723181] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1609builder # [ 13.723719] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1610builder # [ 13.724774] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21611builder # [ 13.725112] cfg80211: failed to load regulatory.db1612server # [ 13.716922] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1613server # [ 13.717441] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1614server # [ 13.718567] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21615server # [ 13.718899] cfg80211: failed to load regulatory.db1616builder # [ 13.766874] systemd[1]: Finished Permit User Sessions.1617builder # [ 13.773358] systemd[1]: Started Getty on tty1.1618server # [ 13.764957] systemd[1]: Finished Extra networking commands..1619builder # [ 13.774262] systemd[1]: Reached target Login Prompts.1620server # [ 13.766000] systemd[1]: Reached target Network.1621server # [ 13.794518] systemd[1]: Started Mock OIDC server for testing.1622server # [ 13.795535] systemd[1]: Starting Nginx Web Server...1623builder # [ 13.832494] 8021q: adding VLAN 0 to HW filter on device eth11624server # [ 13.814483] systemd[1]: Starting PostgreSQL Server...1625server # [ 13.815438] systemd[1]: Started RustFS S3-compatible object storage.1626builder # [ 13.835434] network-addresses-eth1-start[644]: adding address 192.168.1.1/24... done1627builder # [ 13.843824] dhcpcd[630]: eth0: waiting for carrier[ 13.864840] 8021q: adding VLAN 0 to HW filter on device eth01628builder # 1629server # [ 13.840315] systemd[1]: Starting Setup RustFS bucket...1630server # [ 13.841250] systemd[1]: Starting Permit User Sessions...1631builder # [ 13.852910] dhcpcd[630]: eth0: waiting for carrier1632builder # [ 13.855721] dhcpcd[630]: eth0: carrier acquired1633builder # [ 13.858272] network-addresses-eth1-start[644]: adding address 2001:db8:1::1/64... done1634builder # [ 13.873729] dhcpcd[630]: DUID 00:01:00:01:32:69:e0:4e:52:54:00:12:34:561635builder # [ 13.874860] dhcpcd[630]: eth0: IAID 00:12:34:561636builder # [ 13.875529] dhcpcd[630]: eth0: adding address fe80::5054:ff:fe12:34561637builder # [ 13.886527] systemd[1]: Finished Address configuration of eth1.1638server # [ 13.996562] 8021q: adding VLAN 0 to HW filter on device eth01639server # [ 13.990939] dhcpcd[663]: eth0: waiting for carrier1640server # [ 13.998790] dhcpcd[663]: eth0: carrier acquired1641server # [ 14.026503] systemd[1]: Finished Permit User Sessions.1642server # [ 14.038613] systemd[1]: Started Getty on tty1.1643server # [ 14.039420] systemd[1]: Reached target Login Prompts.1644server # [ 14.056974] dhcpcd[663]: DUID 00:01:00:01:32:69:e0:4f:52:54:00:12:34:561645server # [ 14.057998] dhcpcd[663]: eth0: IAID 00:12:34:561646server # [ 14.058698] dhcpcd[663]: eth0: adding address fe80::5054:ff:fe12:34561647builder # [ 14.107551] systemd-logind[506]: Watching system buttons on /dev/input/event0 (gpio-keys)1648builder # [ 14.210849] mousedev: PS/2 mouse device common for all mice1649builder # [ 14.296480] systemd-logind[506]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1650server # [ 14.360449] dhcpcd[663]: eth0: soliciting a DHCP lease1651server # [ 14.369020] dhcpcd[663]: eth0: offered 10.0.2.15 from 10.0.2.21652server # [ 14.369939] dhcpcd[663]: eth0: probing address 10.0.2.15/241653server # [ 14.469977] systemd[1]: Condition check resulted in Virtio network device being skipped.1654server # [ 14.482341] systemd[1]: Starting Address configuration of eth1...1655server # [ 14.537530] dhcpcd[663]: eth0: IAID 00:12:34:561656server # [ 14.590046] nginx-pre-start[724]: nginx: the configuration file /nix/store/faa69z7cxcf98yn9lxcirib5jxnw1vy1-nginx.conf syntax is ok1657server # [ 14.591907] nginx-pre-start[724]: nginx: configuration file /nix/store/faa69z7cxcf98yn9lxcirib5jxnw1vy1-nginx.conf test is successful1658server # [ 14.621992] systemd[1]: Started Nginx Web Server.1659server # [ 14.632672] postgresql-pre-start[727]: The files belonging to this database system will be owned by user "postgres".1660server # [ 14.649255] postgresql-pre-start[727]: This user must also own the server process.1661server # [ 14.656000] postgresql-pre-start[727]: The database cluster will be initialized with locale "en_US.UTF-8".1662server # [ 14.657492] postgresql-pre-start[727]: The default database encoding has accordingly been set to "UTF8".1663server # [ 14.658792] postgresql-pre-start[727]: The default text search configuration will be set to "english".1664server # [ 14.685750] postgresql-pre-start[727]: Data page checksums are enabled.1665server # [ 14.686922] postgresql-pre-start[727]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok1666server # [ 14.703136] postgresql-pre-start[727]: creating subdirectories ... ok1667server # [ 14.712376] postgresql-pre-start[727]: selecting dynamic shared memory implementation ... posix1668server # [ 14.745682] 8021q: adding VLAN 0 to HW filter on device eth11669server # [ 14.795847] network-addresses-eth1-start[732]: adding address 192.168.1.2/24... done1670server # [ 14.833890] network-addresses-eth1-start[732]: adding address 2001:db8:1::2/64... done1671server # [ 14.891690] systemd[1]: Finished Address configuration of eth1.1672builder # [ 14.997401] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31673server # [ 15.092268] postgresql-pre-start[727]: selecting default "max_connections" ... 1001674server # [ 15.139084] mock-oidc-server[691]: Mock OIDC Server running1675server # [ 15.144595] mock-oidc-server[691]: OIDC Address: 127.0.0.1:80801676server # [ 15.145613] mock-oidc-server[691]: Issue Address: 127.0.0.1:80811677server # [ 15.146590] mock-oidc-server[691]: Issuer: http://127.0.0.1:8080/oidc1678server # [ 15.147600] mock-oidc-server[691]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json1679server # [ 15.172833] mock-oidc-server[691]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration1680server # [ 15.174327] mock-oidc-server[691]: Issue tokens: http://127.0.0.1:8081/issue?sub=...1681server # [ 15.313849] dhcpcd[663]: eth0: soliciting a DHCP lease1682server # [ 15.314906] dhcpcd[663]: eth0: offered 10.0.2.15 from 10.0.2.21683server # [ 15.324505] dhcpcd[663]: eth0: probing address 10.0.2.15/241684builder # [ 15.407933] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1685builder # [ 15.410535] systemd[1]: Stopped Virtual Console Setup.1686builder # [ 15.411422] systemd[1]: Stopping Virtual Console Setup...1687builder # [ 15.419193] systemd[1]: Starting Virtual Console Setup...1688builder # [ 15.443370] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1689builder # [ 15.447845] systemd[1]: Stopped Virtual Console Setup.1690builder # [ 15.453227] systemd[1]: Starting Virtual Console Setup...1691builder # [ 15.522945] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1692server # [ 15.519976] postgresql-pre-start[727]: selecting default "shared_buffers" ... 128MB1693builder # [ 15.600685] systemd-logind[506]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1694server # [ 15.607516] systemd-logind[513]: Watching system buttons on /dev/input/event0 (gpio-keys)1695builder # [ 15.632488] systemd-vconsole-setup[690]: Configuration of first virtual console was skipped, ignoring remaining ones.1696builder # [ 15.637621] systemd[1]: Finished Virtual Console Setup.1697builder # [ 15.697947] dhcpcd[630]: eth0: soliciting a DHCP lease1698builder # [ 15.700909] dhcpcd[630]: eth0: offered 10.0.2.15 from 10.0.2.21699builder # [ 15.708304] dhcpcd[630]: eth0: probing address 10.0.2.15/241700server # [ 15.914917] mousedev: PS/2 mouse device common for all mice1701builder # [ 16.009546] dhcpcd[630]: eth0: soliciting an IPv6 router1702builder # [ 16.012124] dhcpcd[630]: eth0: Router Advertisement from fe80::21703builder # [ 16.013594] dhcpcd[630]: eth0: adding address fec0::5054:ff:fe12:3456/641704builder # [ 16.015173] dhcpcd[630]: eth0: adding route to fec0::/641705builder # [ 16.016538] dhcpcd[630]: eth0: adding default route via fe80::21706server # [ 16.044268] dhcpcd[663]: eth0: soliciting an IPv6 router1707server # [ 16.045227] dhcpcd[663]: eth0: Router Advertisement from fe80::21708server # [ 16.046083] dhcpcd[663]: eth0: adding address fec0::5054:ff:fe12:3456/641709server # [ 16.047111] dhcpcd[663]: eth0: adding route to fec0::/641710server # [ 16.047861] dhcpcd[663]: eth0: adding default route via fe80::21711server # [ 16.064123] systemd-logind[513]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1712server # [ 17.470206] postgresql-pre-start[727]: selecting default time zone ... UTC1713server # [ 17.484304] postgresql-pre-start[727]: creating configuration files ... ok1714server # [ 18.121295] postgresql-pre-start[727]: running bootstrap script ... ok1715server # [ 18.422723] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31716server # [ 19.508735] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1717server # [ 19.512653] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1718server # [ 19.513805] systemd[1]: Stopped Virtual Console Setup.1719server # [ 19.514600] systemd[1]: Stopping Virtual Console Setup...1720server # [ 19.536533] systemd[1]: Starting Virtual Console Setup...1721server # [ 19.600759] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1722server # [ 19.604243] systemd[1]: Stopped Virtual Console Setup.1723server # [ 19.618433] systemd[1]: Starting Virtual Console Setup...1724server # [ 19.756835] systemd-logind[513]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1725server # [ 19.880776] systemd-vconsole-setup[819]: Configuration of first virtual console was skipped, ignoring remaining ones.1726server # [ 19.888179] systemd[1]: Finished Virtual Console Setup.1727server # [ 19.948894] postgresql-pre-start[727]: performing post-bootstrap initialization ... ok1728server # [ 20.065644] postgresql-pre-start[727]: syncing data to disk ... ok1729server # [ 20.068590] postgresql-pre-start[727]: initdb: warning: enabling "trust" authentication for local connections1730server # [ 20.070020] postgresql-pre-start[727]: 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.1731server # [ 20.072383] postgresql-pre-start[727]: Success. You can now start the database server using:1732server # [ 20.073849] postgresql-pre-start[727]: pg_ctl -D /var/lib/postgresql/18 -l logfile start1733server # [ 20.196627] postgres[829]: [829] LOG: starting PostgreSQL 18.6 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit1734server # [ 20.200954] postgres[829]: [829] LOG: listening on IPv6 address "::1", port 54321735server # [ 20.202442] postgres[829]: [829] LOG: listening on IPv4 address "127.0.0.1", port 54321736server # [ 20.204253] postgres[829]: [829] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1737server # [ 20.220841] postgres[838]: [838] LOG: database system was shut down at 2026-10-20 08:18:29 GMT1738server # [ 20.231778] postgres[829]: [829] LOG: database system is ready to accept connections1739server # [ 20.236541] systemd[1]: Started PostgreSQL Server.1740server # [ 20.253771] systemd[1]: Starting PostgreSQL Setup Scripts...1741builder # [ 20.467295] dhcpcd[630]: eth0: leased 10.0.2.15 for 86400 seconds1742builder # [ 20.469615] dhcpcd[630]: eth0: adding route to 10.0.2.0/241743builder # [ 20.470983] dhcpcd[630]: eth0: adding default route via 10.0.2.21744server # [ 20.505769] postgresql-setup-start[849]: CREATE DATABASE1745server # [ 20.551435] postgresql-setup-start[854]: CREATE ROLE1746server # [ 20.571958] postgresql-setup-start[856]: ALTER DATABASE1747builder # [ 20.583661] systemd[1]: Started DHCP Client.1748builder # [ 20.585563] systemd[1]: Reached target Multi-User System.1749builder # [ 20.587014] systemd[1]: Startup finished in 2.498s (kernel) + 5.125s (initrd) + 12.962s (userspace) = 20.586s.1750server # [ 20.579832] systemd[1]: Finished PostgreSQL Setup Scripts.1751server # [ 20.581530] systemd[1]: Reached target PostgreSQL.1752server: (finished: waiting for unit postgresql.service, in 21.22 seconds)1753server: waiting for unit rustfs.service1754server: (finished: waiting for unit rustfs.service, in 0.05 seconds)1755server: waiting for unit rustfs-setup.service1756server # [ 20.978336] dhcpcd[663]: eth0: leased 10.0.2.15 for 86400 seconds1757server # [ 20.980982] dhcpcd[663]: eth0: adding route to 10.0.2.0/241758server # [ 20.986311] dhcpcd[663]: eth0: adding default route via 10.0.2.21759server # [ 21.093071] systemd[1]: Started DHCP Client.1760server # [ 30.251893] rustfs-setup-start[966]: mb s3://niks3-test1761server # [ 30.265679] systemd[1]: Finished Setup RustFS bucket.1762server # [ 30.278786] systemd[1]: Starting niks3 server...1763server: (finished: waiting for unit rustfs-setup.service, in 9.47 seconds)1764server: waiting for unit mock-oidc.service1765server: (finished: waiting for unit mock-oidc.service, in 0.08 seconds)1766server: waiting for unit niks3.service1767server # [ 30.533947] niks3-server[981]: 2026/10/20 08:18:39 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:8080/oidc1768server # [ 30.540745] niks3-server[981]: 2026/10/20 08:18:39 INFO OIDC authentication enabled config=/nix/store/f94czh0qddabanbdhbb264fc54xx8cyh-niks3-oidc.json1769server # [ 30.544953] niks3-server[981]: 2026/10/20 08:18:39 INFO Loaded signing key name=niks3-test-1 path=/nix/store/snw7kmczqsc6g53bbgqlmqjiq9xddgss-niks3-signing-key1770server # [ 30.575850] niks3-server[981]: 2026/10/20 08:18:39 INFO Using socket-activated listener address=0.0.0.0:57511771server # [ 30.581552] niks3-server[981]: 2026/10/20 08:18:39 INFO Using socket-activated listener address=/run/niks3/proxy.sock1772server # [ 30.583204] niks3-server[981]: 2026/10/20 08:18:39 INFO systemd watchdog enabled interval=15s1773server # [ 30.585442] niks3-server[981]: 2026/10/20 08:18:39 INFO Starting HTTP server address=0.0.0.0:57511774server # [ 30.587118] niks3-server[981]: 2026/10/20 08:18:39 INFO Starting HTTP server address=/run/niks3/proxy.sock1775server # [ 30.589444] systemd[1]: Started niks3 server.1776server # [ 30.590402] systemd[1]: Reached target Multi-User System.1777server # [ 30.592233] systemd[1]: Startup finished in 2.490s (kernel) + 5.049s (initrd) + 23.040s (userspace) = 30.579s.1778server: (finished: waiting for unit niks3.service, in 1.12 seconds)1779server: waiting for TCP port 5751 on localhost1780server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded!1781server: (finished: waiting for TCP port 5751 on localhost, in 0.05 seconds)1782server: waiting for TCP port 8080 on localhost1783server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded!1784server: (finished: waiting for TCP port 8080 on localhost, in 0.03 seconds)1785server: waiting for TCP port 9000 on localhost1786server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded!1787server: (finished: waiting for TCP port 9000 on localhost, in 0.02 seconds)1788server: must succeed: mkdir -p /tmp/test-config1789server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.02 seconds)1790server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token1791server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.01 seconds)1792server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.31793server # [ 31.786636] systemd[1]: Created slice Slice /system/nix-daemon.1794server # [ 31.792285] systemd[1]: Started Nix Daemon instance (PID 1029/UID 0).1795server # [ 31.864897] nix-daemon[1031]: remote pid 1029 is unknown user (trusted)1796server # [ 31.891723] systemd[1]: nix-daemon@0-1-1029_1030-0.service: Deactivated successfully.1797server # [ 31.901124] niks3-server[981]: 2026/10/20 08:18:40 INFO Received push request method=POST path=/api/pushes1798server # time=2026-10-20T08:18:41.019Z level=INFO msg="Uploading 5 paths to server (0 already cached)"1799server # time=2026-10-20T08:18:41.022Z level=INFO msg="Uploading gxljc46qyjqzvhrnf5wk5ypyg73qv84n-libidn2-2.3.8 (366.1KB)"1800server # time=2026-10-20T08:18:41.025Z level=INFO msg="Uploading d3xvwgga6f7mchrbvc77g3gap2yw1pd4-libunistring-1.4.2 (2.0MB)"1801server # time=2026-10-20T08:18:41.033Z level=INFO msg="Uploading hx9wvdx2l59iahwf9nmrx7rwkm6cymgs-glibc-2.44-25 (44.8MB)"1802server # time=2026-10-20T08:18:41.036Z level=INFO msg="Uploading 0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3 (287.5KB)"1803server # time=2026-10-20T08:18:41.039Z level=INFO msg="Uploading ficwc08a9q1rigvr4jv9l3xsvmx3yfqn-xgcc-16.2.0-libgcc (150.2KB)"1804server # [ 32.254394] niks3-server[981]: 2026/10/20 08:18:41 INFO Registered completed upload object_key=nar/0ik4x86wi4hx5a19m0hrqb82v8pwywwk872j6xqs6cbsmirmckm4.nar.zst1805server # [ 32.365417] niks3-server[981]: 2026/10/20 08:18:41 INFO Registered completed upload object_key=d3xvwgga6f7mchrbvc77g3gap2yw1pd4.ls1806server # [ 32.448158] niks3-server[981]: 2026/10/20 08:18:41 INFO Registered completed upload object_key=gxljc46qyjqzvhrnf5wk5ypyg73qv84n.ls1807server # [ 32.463538] niks3-server[981]: 2026/10/20 08:18:41 INFO Registered completed upload object_key=0xy6jlccm8kfpwmhkxabz3638lchyi74.ls1808server # [ 32.493008] niks3-server[981]: 2026/10/20 08:18:41 INFO Registered completed upload object_key=nar/18599cw0w0wm2wv1ad31rx00n0643i8z2zgra1j66ikfwcs5q8g0.nar.zst1809server # [ 32.520324] niks3-server[981]: 2026/10/20 08:18:41 INFO Registered completed upload object_key=ficwc08a9q1rigvr4jv9l3xsvmx3yfqn.ls1810server # [ 32.528650] niks3-server[981]: 2026/10/20 08:18:41 INFO Registered completed upload object_key=nar/18yshwpp5jc6x354ipdplqa3ylhpqlbzihvyv9h05j8hpn888058.nar.zst1811server # [ 32.538570] niks3-server[981]: 2026/10/20 08:18:41 INFO Registered completed upload object_key=nar/07qvg0nz70dkqx83m02p283r0jrv8d1schf7y6wkai65w85yzvv0.nar.zst1812server # [ 33.613174] niks3-server[981]: 2026/10/20 08:18:42 INFO Registered completed upload object_key=hx9wvdx2l59iahwf9nmrx7rwkm6cymgs.ls1813server # [ 33.633597] niks3-server[981]: 2026/10/20 08:18:42 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1814server # [ 33.652251] niks3-server[981]: 2026/10/20 08:18:42 INFO Completed multipart upload object_key=nar/1jmx0pk58iv937afmb3f0la32bd0avaixkxq0cx99vzamxdxg5y0.nar.zst upload_id=NzRiMzU0OTUtNTBiNS00ZGQyLWFlNWUtMjkwYmNmYTg4NGUzLmIxYzY1OWJhLTczYzEtNDc5ZC1hN2E1LTQ3YjQzNGVlZTQxYngxNzkyNDg0MzIxMDA2MzE1ODQw parts=11815server # [ 33.660318] niks3-server[981]: 2026/10/20 08:18:42 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign1816server # time=2026-10-20T08:18:42.753Z level=INFO msg="Uploading 5 narinfos"1817server # [ 33.667874] niks3-server[981]: 2026/10/20 08:18:42 INFO Signed narinfos id=1 count=51818server # [ 33.700258] niks3-server[981]: 2026/10/20 08:18:42 INFO Received complete push request method=POST path=/api/pushes/1/complete1819server # [ 33.725700] niks3-server[981]: 2026/10/20 08:18:42 INFO Registered completed upload object_key=gxljc46qyjqzvhrnf5wk5ypyg73qv84n.narinfo1820server # time=2026-10-20T08:18:42.818Z level=INFO msg="Upload complete. (2.004s)"1821server # [ 33.730800] niks3-server[981]: 2026/10/20 08:18:42 INFO Registered completed upload object_key=0xy6jlccm8kfpwmhkxabz3638lchyi74.narinfo1822server # [ 33.736131] niks3-server[981]: 2026/10/20 08:18:42 INFO Registered completed upload object_key=d3xvwgga6f7mchrbvc77g3gap2yw1pd4.narinfo1823server # [ 33.737836] niks3-server[981]: 2026/10/20 08:18:42 INFO Completed push id=1 objects=15 duration=30.27202ms1824server # [ 33.739143] niks3-server[981]: 2026/10/20 08:18:42 INFO Registered completed upload object_key=ficwc08a9q1rigvr4jv9l3xsvmx3yfqn.narinfo1825server # [ 33.747698] niks3-server[981]: 2026/10/20 08:18:42 INFO Registered completed upload object_key=hx9wvdx2l59iahwf9nmrx7rwkm6cymgs.narinfo1826server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3, in 2.14 seconds)1827server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token1828server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.01 seconds)1829server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.31830server # [ 33.831477] niks3-server[981]: 2026/10/20 08:18:42 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]1831server # [ 33.878482] systemd[1]: Started Nix Daemon instance (PID 1064/UID 0).1832server # [ 33.936389] nix-daemon[1066]: remote pid 1064 is unknown user (trusted)1833server # [ 33.953283] systemd[1]: nix-daemon@1-2-1064_1065-0.service: Deactivated successfully.1834server # time=2026-10-20T08:18:43.049Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"1835server # [ 33.962501] niks3-server[981]: 2026/10/20 08:18:43 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]1836server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3, in 0.20 seconds)1837server: waiting for unit nginx.service1838server: (finished: waiting for unit nginx.service, in 0.03 seconds)1839server: waiting for TCP port 443 on localhost1840server # Connection to localhost (::1) 443 port [tcp/https] succeeded!1841server: (finished: waiting for TCP port 443 on localhost, in 0.02 seconds)1842server: must succeed: /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.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/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.31843server # time=2026-10-20T08:18:43.167Z 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.pem1844server # time=2026-10-20T08:18:43.184Z level=INFO msg="All 1 paths already cached"1845server: (finished: must succeed: /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.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/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3, in 0.08 seconds)1846server: 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_closures1847server: (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)1848server: must fail: /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.31849server # time=2026-10-20T08:18:43.242Z 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)"1850server: (finished: must fail: /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3, in 0.02 seconds)1851server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.31852server # time=2026-10-20T08:18:43.314Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem1853server # time=2026-10-20T08:18:43.325Z level=INFO msg="All 1 paths already cached"1854server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3, in 0.08 seconds)1855server: must succeed: cd /etc/niks3-test-certs && /nix/store/m9xpj129716c3dn7mcja91cb84mplpv9-openssl-3.5.8-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client'1856server # -----1857server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/m9xpj129716c3dn7mcja91cb84mplpv9-openssl-3.5.8-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client', in 0.06 seconds)1858server: must succeed: cd /etc/niks3-test-certs && /nix/store/m9xpj129716c3dn7mcja91cb84mplpv9-openssl-3.5.8-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem1859server # Certificate request self-signature ok1860server # subject=CN=other client1861server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/m9xpj129716c3dn7mcja91cb84mplpv9-openssl-3.5.8-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem, in 0.08 seconds)1862server: must fail: /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.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/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.31863server # time=2026-10-20T08:18:43.536Z 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.pem1864server # [ 34.456447] niks3-server[981]: 2026/10/20 08:18:43 WARN mTLS auth: subject not in bound subjects subject="CN=other client"1865server # [ 34.501830] systemd[1]: Started Nix Daemon instance (PID 1131/UID 0).1866server # [ 34.561221] nix-daemon[1133]: remote pid 1131 is unknown user (trusted)1867server # [ 34.578167] systemd[1]: nix-daemon@2-3-1131_1132-0.service: Deactivated successfully.1868server # [ 34.587220] niks3-server[981]: 2026/10/20 08:18:43 WARN mTLS auth: subject not in bound subjects subject="CN=other client"1869server # time=2026-10-20T08:18:43.678Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"1870server: (finished: must fail: /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.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/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3, in 0.21 seconds)1871server: must succeed: mkdir -p /tmp/test-store1872server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.02 seconds)1873server: must succeed: 1874 export AWS_ACCESS_KEY_ID=rustfsadmin1875export AWS_SECRET_ACCESS_KEY=rustfsadmin1876 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.318771878server: (finished: must succeed: 1879 export AWS_ACCESS_KEY_ID=rustfsadmin1880export AWS_SECRET_ACCESS_KEY=rustfsadmin1881 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.31882, in 0.79 seconds)1883server: must succeed: 1884cat > /tmp/test-drv.nix << 'EOF'1885derivation {1886 name = "test-build-log";1887 system = builtins.currentSystem;1888 builder = "/bin/sh";1889 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];1890}1891EOF18921893server: (finished: must succeed: 1894cat > /tmp/test-drv.nix << 'EOF'1895derivation {1896 name = "test-build-log";1897 system = builtins.currentSystem;1898 builder = "/bin/sh";1899 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];1900}1901EOF1902, in 0.02 seconds)1903server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix1904server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring1905server # [ 35.486877] systemd[1]: Started Nix Daemon instance (PID 1175/UID 0).1906server # [ 35.579574] nix-daemon[1179]: remote pid 1175 is unknown user (trusted)1907server # this derivation will be built:1908server # /nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv1909server # building '/nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv'...1910server # test-build-log> test build log output1911server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.41 seconds)1912server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1913server # [ 35.839125] systemd[1]: nix-daemon@3-4-1175_1176-0.service: Deactivated successfully.1914server # [ 35.970594] systemd[1]: Started Nix Daemon instance (PID 1205/UID 0).1915server # [ 36.029118] nix-daemon[1207]: remote pid 1205 is unknown user (trusted)1916server # [ 36.044869] systemd[1]: nix-daemon@4-5-1205_1206-0.service: Deactivated successfully.1917server # [ 36.053676] niks3-server[981]: 2026/10/20 08:18:45 INFO Received push request method=POST path=/api/pushes1918server # time=2026-10-20T08:18:45.148Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1919server # time=2026-10-20T08:18:45.149Z level=INFO msg="Uploading z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log (128B)"1920server # [ 36.093336] niks3-server[981]: 2026/10/20 08:18:45 INFO Registered completed upload object_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.ls1921server # [ 36.101132] niks3-server[981]: 2026/10/20 08:18:45 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign1922server # time=2026-10-20T08:18:45.194Z level=INFO msg="Uploading 1 narinfos"1923server # [ 36.110452] niks3-server[981]: 2026/10/20 08:18:45 INFO Registered completed upload object_key=log/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv1924server # [ 36.116370] niks3-server[981]: 2026/10/20 08:18:45 INFO Signed narinfos id=2 count=11925server # [ 36.121190] niks3-server[981]: 2026/10/20 08:18:45 INFO Registered completed upload object_key=nar/00zns3gj9hwz2a4b0i07y7nmxybq59lh24bl3xsxblcl6333mjil.nar.zst1926server # [ 36.144655] niks3-server[981]: 2026/10/20 08:18:45 INFO Received complete push request method=POST path=/api/pushes/2/complete1927server # time=2026-10-20T08:18:45.255Z level=INFO msg="Upload complete. (244ms)"1928server # [ 36.166973] niks3-server[981]: 2026/10/20 08:18:45 INFO Completed push id=2 objects=4 duration=24.53888ms1929server # [ 36.173704] niks3-server[981]: 2026/10/20 08:18:45 INFO Registered completed upload object_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.narinfo1930server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log, in 0.35 seconds)1931server: must succeed: 1932 export AWS_ACCESS_KEY_ID=rustfsadmin1933export AWS_SECRET_ACCESS_KEY=rustfsadmin1934 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log19351936server: (finished: must succeed: 1937 export AWS_ACCESS_KEY_ID=rustfsadmin1938export AWS_SECRET_ACCESS_KEY=rustfsadmin1939 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1940, in 0.25 seconds)1941subtest: push --stdin streams paths and reports each one1942server: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }'1943server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring1944server # [ 36.497540] systemd[1]: Started Nix Daemon instance (PID 1223/UID 0).1945server # [ 36.560785] nix-daemon[1227]: remote pid 1223 is unknown user (trusted)1946server # this derivation will be built:1947server # /nix/store/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv1948server # building '/nix/store/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv'...1949server: (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.27 seconds)1950server: 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/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --stdin1951server # [ 36.700145] systemd[1]: nix-daemon@5-6-1223_1224-0.service: Deactivated successfully.1952server # [ 36.815449] systemd[1]: Started Nix Daemon instance (PID 1255/UID 0).1953server # [ 36.996984] nix-daemon[1257]: remote pid 1255 is unknown user (trusted)1954server # [ 37.012467] systemd[1]: nix-daemon@6-7-1255_1256-0.service: Deactivated successfully.1955server # [ 37.019846] niks3-server[981]: 2026/10/20 08:18:46 INFO Received push request method=POST path=/api/pushes1956server # time=2026-10-20T08:18:46.114Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1957server # time=2026-10-20T08:18:46.115Z level=INFO msg="Uploading 7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test (120B)"1958server # [ 37.056823] niks3-server[981]: 2026/10/20 08:18:46 INFO Registered completed upload object_key=7j8147s8h4si9jmv6bxa86ql7z6j2m37.ls1959server # [ 37.063840] niks3-server[981]: 2026/10/20 08:18:46 INFO Registered completed upload object_key=nar/1hj1r0g515rfxjx685mfn3dgr6akqbn4gf64snlv6bxaxph69lna.nar.zst1960server # time=2026-10-20T08:18:46.158Z level=INFO msg="Uploading 1 narinfos"1961server # [ 37.072352] niks3-server[981]: 2026/10/20 08:18:46 INFO Received sign narinfos request method=POST path=/api/pushes/3/sign1962server # [ 37.073905] niks3-server[981]: 2026/10/20 08:18:46 INFO Signed narinfos id=3 count=11963server # [ 37.079614] niks3-server[981]: 2026/10/20 08:18:46 INFO Registered completed upload object_key=log/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv1964server # [ 37.092731] niks3-server[981]: 2026/10/20 08:18:46 INFO Received complete push request method=POST path=/api/pushes/3/complete1965server # time=2026-10-20T08:18:46.187Z level=INFO msg="Upload complete. (334ms)"1966server # [ 37.098878] niks3-server[981]: 2026/10/20 08:18:46 INFO Completed push id=3 objects=4 duration=8.54548ms1967server # [ 37.104341] niks3-server[981]: 2026/10/20 08:18:46 INFO Registered completed upload object_key=7j8147s8h4si9jmv6bxa86ql7z6j2m37.narinfo1968server: (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/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --stdin, in 0.41 seconds)1969server: must succeed: 1970 export AWS_ACCESS_KEY_ID=rustfsadmin1971export AWS_SECRET_ACCESS_KEY=rustfsadmin1972 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test1973 1974server: (finished: must succeed: 1975 export AWS_ACCESS_KEY_ID=rustfsadmin1976export AWS_SECRET_ACCESS_KEY=rustfsadmin1977 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test1978 , in 0.25 seconds)1979(finished: subtest: push --stdin streams paths and reports each one, in 0.94 seconds)1980server: must succeed: readlink /etc/niks3-test/symlink-wrapper1981server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.02 seconds)1982server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper1983server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.02 seconds)1984server: must succeed: test -L /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper1985server: (finished: must succeed: test -L /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper, in 0.01 seconds)1986server: must succeed: readlink /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper1987server: (finished: must succeed: readlink /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper, in 0.02 seconds)1988server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper1989server # [ 37.551488] systemd[1]: Started Nix Daemon instance (PID 1302/UID 0).1990server # [ 37.635452] nix-daemon[1304]: remote pid 1302 is unknown user (trusted)1991server # [ 37.650910] systemd[1]: nix-daemon@7-8-1302_1303-0.service: Deactivated successfully.1992server # [ 37.658721] niks3-server[981]: 2026/10/20 08:18:46 INFO Received push request method=POST path=/api/pushes1993server # time=2026-10-20T08:18:46.753Z level=INFO msg="Uploading 2 paths to server (0 already cached)"1994server # time=2026-10-20T08:18:46.754Z level=INFO msg="Uploading l5aiz90gwfr0i2074qb0pk20v27p5v86-base-package (536B)"1995server # time=2026-10-20T08:18:46.756Z level=INFO msg="Uploading qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper (192B)"1996server # [ 37.708613] niks3-server[981]: 2026/10/20 08:18:46 INFO Registered completed upload object_key=qlhg6w8ghvjarrf438qw23mymapxfpdp.ls1997server # [ 37.721285] niks3-server[981]: 2026/10/20 08:18:46 INFO Received sign narinfos request method=POST path=/api/pushes/4/sign1998server # time=2026-10-20T08:18:46.816Z level=INFO msg="Uploading 2 narinfos"1999server # [ 37.729121] niks3-server[981]: 2026/10/20 08:18:46 INFO Signed narinfos id=4 count=22000server # [ 37.735299] niks3-server[981]: 2026/10/20 08:18:46 INFO Registered completed upload object_key=nar/0pls6byi1j0qx38i7wfm9a2mhgj2lwdyl5wmfdzfgvd67yhriq4z.nar.zst2001server # [ 37.741480] niks3-server[981]: 2026/10/20 08:18:46 INFO Registered completed upload object_key=nar/0820av37plmfgrxw6pcbym6phk1sv14dilyrablrnkzb7z1fd5js.nar.zst2002server # [ 37.743453] niks3-server[981]: 2026/10/20 08:18:46 INFO Registered completed upload object_key=l5aiz90gwfr0i2074qb0pk20v27p5v86.ls2003server # [ 37.760431] niks3-server[981]: 2026/10/20 08:18:46 INFO Received complete push request method=POST path=/api/pushes/4/complete2004server # time=2026-10-20T08:18:46.857Z level=INFO msg="Upload complete. (273ms)"2005server # [ 37.769833] niks3-server[981]: 2026/10/20 08:18:46 INFO Completed push id=4 objects=6 duration=9.93526ms2006server # [ 37.775958] niks3-server[981]: 2026/10/20 08:18:46 INFO Registered completed upload object_key=qlhg6w8ghvjarrf438qw23mymapxfpdp.narinfo2007server # [ 37.777744] niks3-server[981]: 2026/10/20 08:18:46 INFO Registered completed upload object_key=l5aiz90gwfr0i2074qb0pk20v27p5v86.narinfo2008server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper, in 0.35 seconds)2009server: must succeed: 2010 export AWS_ACCESS_KEY_ID=rustfsadmin2011export AWS_SECRET_ACCESS_KEY=rustfsadmin2012 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper20132014server: (finished: must succeed: 2015 export AWS_ACCESS_KEY_ID=rustfsadmin2016export AWS_SECRET_ACCESS_KEY=rustfsadmin2017 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper2018, in 0.31 seconds)2019server: must succeed: 2020cat > /tmp/oidc-test.nix << 'EOF'2021derivation {2022 name = "oidc-test";2023 system = builtins.currentSystem;2024 builder = "/bin/sh";2025 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2026}2027EOF20282029server: (finished: must succeed: 2030cat > /tmp/oidc-test.nix << 'EOF'2031derivation {2032 name = "oidc-test";2033 system = builtins.currentSystem;2034 builder = "/bin/sh";2035 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2036}2037EOF2038, in 0.02 seconds)2039server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link2040server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2041server # [ 38.172417] systemd[1]: Started Nix Daemon instance (PID 1331/UID 0).2042server # [ 38.228416] nix-daemon[1335]: remote pid 1331 is unknown user (trusted)2043server # this derivation will be built:2044server # /nix/store/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv2045server # building '/nix/store/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv'...2046server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link, in 0.22 seconds)2047server: 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'2048server # [ 38.346356] systemd[1]: nix-daemon@8-9-1331_1332-0.service: Deactivated successfully.2049server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://server:5751&repository_owner=myorg', in 0.04 seconds)2050server: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IldGR1QzeElJVEVOZXExMmVuVF9Wd2QzYmxyNzJuUmpRWWlJUUxKMFVYeWsiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTI0ODc5MjcsImlhdCI6MTc5MjQ4NDMyNywiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.NuZ0DSMCe9Ph8oW2jYZ8keH_F527SKfzybK66icHu5ywKyLjIAg7FxK7R5OdKxKmcOG8XErXcqyZWoa0Dw-BKNsCZrO-XV-qslpNPepvmb-cYMPyh_10vntjdj0UuUyrtn1kPgRMx1hXggWOQK9_yYtZPg7oYfuAunrpmqaR9Cxq4vLuMl_82kalOh8QESAz3LYF5SPZlRgiPdVuMYK7mdKhKRKm8lVBwKEkbcD6omdz8NnnXVVcz8DfCCF2DnitTWGr4WxHluERUcNaw7aaN0rkyH8onv8BF7jwHqr1HhtvTR7f0YWaQR-h17NdGK-ANnxHpDi04_ukwFIkNu3hLQ' /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test2051server # time=2026-10-20T08:18:47.479Z 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"2052server # [ 38.482608] systemd[1]: Started Nix Daemon instance (PID 1365/UID 0).2053server # [ 38.540456] nix-daemon[1367]: remote pid 1365 is unknown user (trusted)2054server # [ 38.555645] systemd[1]: nix-daemon@9-10-1365_1366-0.service: Deactivated successfully.2055server # [ 38.563075] niks3-server[981]: 2026/10/20 08:18:47 INFO Received push request method=POST path=/api/pushes2056server # time=2026-10-20T08:18:47.658Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2057server # time=2026-10-20T08:18:47.660Z level=INFO msg="Uploading x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test (136B)"2058server # [ 38.597374] niks3-server[981]: 2026/10/20 08:18:47 INFO Registered completed upload object_key=log/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv2059server # [ 38.606179] niks3-server[981]: 2026/10/20 08:18:47 INFO Received sign narinfos request method=POST path=/api/pushes/5/sign2060server # time=2026-10-20T08:18:47.698Z level=INFO msg="Uploading 1 narinfos"2061server # [ 38.613903] niks3-server[981]: 2026/10/20 08:18:47 INFO Signed narinfos id=5 count=12062server # [ 38.616891] niks3-server[981]: 2026/10/20 08:18:47 INFO Registered completed upload object_key=x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj.ls2063server # [ 38.623274] niks3-server[981]: 2026/10/20 08:18:47 INFO Registered completed upload object_key=nar/17h0qgwf5334wia961ibqy9hmpsyvfhrvj5ji9m031klp6sx4v0q.nar.zst2064server # [ 38.632060] niks3-server[981]: 2026/10/20 08:18:47 INFO Received complete push request method=POST path=/api/pushes/5/complete2065server # [ 38.640687] niks3-server[981]: 2026/10/20 08:18:47 INFO Registered completed upload object_key=x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj.narinfo2066server # time=2026-10-20T08:18:47.733Z level=INFO msg="Upload complete. (211ms)"2067server # [ 38.645367] niks3-server[981]: 2026/10/20 08:18:47 INFO Completed push id=5 objects=4 duration=9.43512ms2068server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IldGR1QzeElJVEVOZXExMmVuVF9Wd2QzYmxyNzJuUmpRWWlJUUxKMFVYeWsiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTI0ODc5MjcsImlhdCI6MTc5MjQ4NDMyNywiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.NuZ0DSMCe9Ph8oW2jYZ8keH_F527SKfzybK66icHu5ywKyLjIAg7FxK7R5OdKxKmcOG8XErXcqyZWoa0Dw-BKNsCZrO-XV-qslpNPepvmb-cYMPyh_10vntjdj0UuUyrtn1kPgRMx1hXggWOQK9_yYtZPg7oYfuAunrpmqaR9Cxq4vLuMl_82kalOh8QESAz3LYF5SPZlRgiPdVuMYK7mdKhKRKm8lVBwKEkbcD6omdz8NnnXVVcz8DfCCF2DnitTWGr4WxHluERUcNaw7aaN0rkyH8onv8BF7jwHqr1HhtvTR7f0YWaQR-h17NdGK-ANnxHpDi04_ukwFIkNu3hLQ' /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test, in 0.28 seconds)2069server: must succeed: 2070cat > /tmp/oidc-test2.nix << 'EOF'2071derivation {2072 name = "oidc-test2";2073 system = builtins.currentSystem;2074 builder = "/bin/sh";2075 args = [ "-c" "echo 'OIDC test 2' > $out" ];2076}2077EOF20782079server: (finished: must succeed: 2080cat > /tmp/oidc-test2.nix << 'EOF'2081derivation {2082 name = "oidc-test2";2083 system = builtins.currentSystem;2084 builder = "/bin/sh";2085 args = [ "-c" "echo 'OIDC test 2' > $out" ];2086}2087EOF2088, in 0.02 seconds)2089server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link2090server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2091server # [ 38.728092] systemd[1]: Started Nix Daemon instance (PID 1377/UID 0).2092server # [ 38.783397] nix-daemon[1381]: remote pid 1377 is unknown user (trusted)2093server # this derivation will be built:2094server # /nix/store/ldlfjwl576hkm4b5ag3b447nnn59cwiw-oidc-test2.drv2095server # building '/nix/store/ldlfjwl576hkm4b5ag3b447nnn59cwiw-oidc-test2.drv'...2096server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link, in 0.22 seconds)2097server: 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'2098server # [ 38.890168] systemd[1]: nix-daemon@10-11-1377_1378-0.service: Deactivated successfully.2099server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:otherorg/repo:ref:refs/heads/main&aud=http://server:5751&repository_owner=otherorg', in 0.04 seconds)2100server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IldGR1QzeElJVEVOZXExMmVuVF9Wd2QzYmxyNzJuUmpRWWlJUUxKMFVYeWsiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTI0ODc5MjgsImlhdCI6MTc5MjQ4NDMyOCwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.VQmGa6H64sbyeDnA6aY5jzOqiqNxziEsJ_NVl6vBSlXM_Gtr_rsU7lH0UeWKj0ZYLfU-j7q2Amr_dq0ExWSonfQ-vebHCjd9kJB6YxUFvdkHbgXP5fkwKkAkhGyUq5GBsv_Szjy1ieVx0wprG4YWnKEjmfGAQOFGPrVrUpysEsllBKDVvBcLPY37vSyoSP_5wWirOTT7aCALQrXzpm8hwXXiPL1r0gH5Q4Ey6tcBOx4_U86LFk0INfID6xEMhpJ4iQ6AJP6OVdDdNQ_cCXP2CJzih97w9Un48XwcaBFSEE6G5cPKsKEM68W0cHdEoZX0TqS7sbMm_nVIZv8JjECXGA' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22101server # time=2026-10-20T08:18:48.026Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2102server # [ 38.983110] niks3-server[981]: 2026/10/20 08:18:48 WARN Authentication failed token_preview=eyJhbGciOi...Zv8JjECXGA token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2103server # [ 39.029412] systemd[1]: Started Nix Daemon instance (PID 1410/UID 0).2104server # [ 39.084348] nix-daemon[1412]: remote pid 1410 is unknown user (trusted)2105server # [ 39.099739] systemd[1]: nix-daemon@11-12-1410_1411-0.service: Deactivated successfully.2106server # [ 39.104865] niks3-server[981]: 2026/10/20 08:18:48 WARN Authentication failed token_preview=eyJhbGciOi...Zv8JjECXGA token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2107server # time=2026-10-20T08:18:48.200Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2108server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IldGR1QzeElJVEVOZXExMmVuVF9Wd2QzYmxyNzJuUmpRWWlJUUxKMFVYeWsiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTI0ODc5MjgsImlhdCI6MTc5MjQ4NDMyOCwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.VQmGa6H64sbyeDnA6aY5jzOqiqNxziEsJ_NVl6vBSlXM_Gtr_rsU7lH0UeWKj0ZYLfU-j7q2Amr_dq0ExWSonfQ-vebHCjd9kJB6YxUFvdkHbgXP5fkwKkAkhGyUq5GBsv_Szjy1ieVx0wprG4YWnKEjmfGAQOFGPrVrUpysEsllBKDVvBcLPY37vSyoSP_5wWirOTT7aCALQrXzpm8hwXXiPL1r0gH5Q4Ey6tcBOx4_U86LFk0INfID6xEMhpJ4iQ6AJP6OVdDdNQ_cCXP2CJzih97w9Un48XwcaBFSEE6G5cPKsKEM68W0cHdEoZX0TqS7sbMm_nVIZv8JjECXGA' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.19 seconds)2109server: 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'2110server: (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)2111server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IldGR1QzeElJVEVOZXExMmVuVF9Wd2QzYmxyNzJuUmpRWWlJUUxKMFVYeWsiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MjQ4NzkyOCwiaWF0IjoxNzkyNDg0MzI4LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.LD9iD9GJGe1AZVYUuHVTkX9S3u92mErmQfxBAsfnc1gVXH9iFFIKx5HTjmU2hCYwoy5k3UAqdzSBS-oWmm6FQHOP4QIIqzxf0fmhXGueDiQZtDU6w2Nvu_WTdCVFd10myl-9JVEHY5EUkFMkcghMqmAciG3xDXCDPrLGuX7n8mkQcYZbi34j_pTxTqD2ugDD_lHxwInTSsqWJGs1BxCBINxD_OTLJ5OaZNvRqYZpO8x5GpsmJr24XtqJSUyrZhh-m-cTIZpucmJjNhAO7UTc9rsl_MyM6n0ZtRmvwEotclLnKWs4fAotqZ7Xt66O5ZIPwGeYkUwdet9RbXiqB993VQ' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22112server # time=2026-10-20T08:18:48.251Z 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"2113server # [ 39.208085] niks3-server[981]: 2026/10/20 08:18:48 WARN Authentication failed token_preview=eyJhbGciOi...bXiqB993VQ token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2114server # [ 39.253174] systemd[1]: Started Nix Daemon instance (PID 1432/UID 0).2115server # [ 39.307832] nix-daemon[1434]: remote pid 1432 is unknown user (trusted)2116server # [ 39.323453] systemd[1]: nix-daemon@12-13-1432_1433-0.service: Deactivated successfully.2117server # [ 39.330846] niks3-server[981]: 2026/10/20 08:18:48 WARN Authentication failed token_preview=eyJhbGciOi...bXiqB993VQ token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2118server # time=2026-10-20T08:18:48.423Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2119server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IldGR1QzeElJVEVOZXExMmVuVF9Wd2QzYmxyNzJuUmpRWWlJUUxKMFVYeWsiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MjQ4NzkyOCwiaWF0IjoxNzkyNDg0MzI4LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.LD9iD9GJGe1AZVYUuHVTkX9S3u92mErmQfxBAsfnc1gVXH9iFFIKx5HTjmU2hCYwoy5k3UAqdzSBS-oWmm6FQHOP4QIIqzxf0fmhXGueDiQZtDU6w2Nvu_WTdCVFd10myl-9JVEHY5EUkFMkcghMqmAciG3xDXCDPrLGuX7n8mkQcYZbi34j_pTxTqD2ugDD_lHxwInTSsqWJGs1BxCBINxD_OTLJ5OaZNvRqYZpO8x5GpsmJr24XtqJSUyrZhh-m-cTIZpucmJjNhAO7UTc9rsl_MyM6n0ZtRmvwEotclLnKWs4fAotqZ7Xt66O5ZIPwGeYkUwdet9RbXiqB993VQ' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.19 seconds)2120server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22121server # time=2026-10-20T08:18:48.445Z 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"2122server # [ 39.402889] niks3-server[981]: 2026/10/20 08:18:48 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]2123server # [ 39.450098] systemd[1]: Started Nix Daemon instance (PID 1451/UID 0).2124server # [ 39.524645] nix-daemon[1453]: remote pid 1451 is unknown user (trusted)2125server # [ 39.545461] systemd[1]: nix-daemon@13-14-1451_1452-0.service: Deactivated successfully.2126server # [ 39.555833] niks3-server[981]: 2026/10/20 08:18:48 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]2127server # time=2026-10-20T08:18:48.649Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2128server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.23 seconds)2129server: must succeed: 2130 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins create hello-pin /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.321312132server # [ 39.634665] niks3-server[981]: 2026/10/20 08:18:48 INFO Received create pin request method=POST path=/api/pins/hello-pin2133server # [ 39.652322] niks3-server[981]: 2026/10/20 08:18:48 INFO Created/updated pin name=hello-pin store_path=/nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.3 narinfo_key=0xy6jlccm8kfpwmhkxabz3638lchyi74.narinfo2134server # time=2026-10-20T08:18:48.744Z level=INFO msg="Created pin" name=hello-pin store_path=/nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.32135server: (finished: must succeed: 2136 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins create hello-pin /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.32137, in 0.09 seconds)2138server: must succeed: 2139 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins list21402141server # [ 39.723422] niks3-server[981]: 2026/10/20 08:18:48 INFO Received list pins request method=GET path=/api/pins2142server: (finished: must succeed: 2143 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins list2144, in 0.07 seconds)2145server: must succeed: 2146 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins list --names-only21472148server # [ 39.787918] niks3-server[981]: 2026/10/20 08:18:48 INFO Received list pins request method=GET path=/api/pins2149server: (finished: must succeed: 2150 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins list --names-only2151, in 0.06 seconds)2152server: must succeed: 2153 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins list --json21542155server # [ 39.852423] niks3-server[981]: 2026/10/20 08:18:48 INFO Received list pins request method=GET path=/api/pins2156server: (finished: must succeed: 2157 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins list --json2158, in 0.06 seconds)2159server: must succeed: 2160 export S3_ENDPOINT_URL=http://localhost:90002161 export AWS_ACCESS_KEY_ID=rustfsadmin2162 export AWS_SECRET_ACCESS_KEY=rustfsadmin2163 /nix/store/ilah8j764imsc5p7912a20hyh1lrgzmq-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin21642165server: (finished: must succeed: 2166 export S3_ENDPOINT_URL=http://localhost:90002167 export AWS_ACCESS_KEY_ID=rustfsadmin2168 export AWS_SECRET_ACCESS_KEY=rustfsadmin2169 /nix/store/ilah8j764imsc5p7912a20hyh1lrgzmq-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin2170, in 0.03 seconds)2171server: must succeed: 2172 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --pin ca-pin /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log21732174server # time=2026-10-20T08:18:49.041Z level=INFO msg="All 1 paths already cached"2175server # [ 39.954493] niks3-server[981]: 2026/10/20 08:18:49 INFO Received create pin request method=POST path=/api/pins/ca-pin2176server # time=2026-10-20T08:18:49.056Z level=INFO msg="Created pin" name=ca-pin store_path=/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2177server # [ 39.969453] niks3-server[981]: 2026/10/20 08:18:49 INFO Created/updated pin name=ca-pin store_path=/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log narinfo_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.narinfo2178server: (finished: must succeed: 2179 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 push --pin ca-pin /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2180, in 0.09 seconds)2181server: must succeed: 2182 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins list --names-only21832184server # [ 40.046333] niks3-server[981]: 2026/10/20 08:18:49 INFO Received list pins request method=GET path=/api/pins2185server: (finished: must succeed: 2186 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins list --names-only2187, in 0.07 seconds)2188server: must succeed: 2189 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins delete hello-pin21902191server # [ 40.110815] niks3-server[981]: 2026/10/20 08:18:49 INFO Received delete pin request method=DELETE path=/api/pins/hello-pin2192server # time=2026-10-20T08:18:49.208Z level=INFO msg="Deleted pin" name=hello-pin2193server # [ 40.120801] niks3-server[981]: 2026/10/20 08:18:49 INFO Deleted pin name=hello-pin2194server: (finished: must succeed: 2195 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins delete hello-pin2196, in 0.08 seconds)2197server: must succeed: 2198 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins list --names-only21992200server # [ 40.226602] niks3-server[981]: 2026/10/20 08:18:49 INFO Received list pins request method=GET path=/api/pins2201server: (finished: must succeed: 2202 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins list --names-only2203, in 0.11 seconds)2204server: must fail: 2205 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent22062207server # [ 40.298632] niks3-server[981]: 2026/10/20 08:18:49 INFO Received create pin request method=POST path=/api/pins/bad-pin2208server # time=2026-10-20T08:18:49.390Z level=ERROR msg="Fatal error" error="creating pin: server returned 404: closure not found: store path must be pushed before pinning\n"2209server # [ 40.303451] niks3-server[981]: 2026/10/20 08:18:49 ERROR Failed to get closure for pin narinfo_key=aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.narinfo error="no rows in result set"2210server: (finished: must fail: 2211 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5q61k9gxhfc5amlhir2d6qyvhp0phnqm-niks3-1.15.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent2212, in 0.08 seconds)2213server: must succeed: systemctl start niks3-gc.service2214server # [ 40.336373] systemd[1]: Starting niks3 garbage collection...2215server # [ 40.385162] niks3[1575]: time=2026-10-20T08:18:49.473Z level=INFO msg="Starting garbage collection" older-than=720h failed-uploads-older-than=6h force=false2216server # [ 40.389177] niks3-server[981]: 2026/10/20 08:18:49 INFO Starting cleanup of old closures method=DELETE path=/api/closures2217server # [ 40.392726] niks3[1575]: time=2026-10-20T08:18:49.480Z level=INFO msg="Garbage collection started"2218server # [ 40.395774] niks3-server[981]: 2026/10/20 08:18:49 INFO Aborted multipart uploads count=0 kept=02219server # [ 40.403209] niks3-server[981]: 2026/10/20 08:18:49 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=3 objects-deleted-after-grace-period=0 objects-failed-to-delete=02220server # [ 40.410616] niks3-server[981]: 2026/10/20 08:18:49 INFO Vacuumed table table=pending_closures2221server # [ 40.414709] niks3-server[981]: 2026/10/20 08:18:49 INFO Vacuumed table table=pending_objects2222server # [ 40.418418] niks3-server[981]: 2026/10/20 08:18:49 INFO Vacuumed table table=multipart_uploads2223server # [ 40.421528] niks3-server[981]: 2026/10/20 08:18:49 INFO Vacuumed table table=closures2224server # [ 40.425196] niks3-server[981]: 2026/10/20 08:18:49 INFO Vacuumed table table=objects2225server # [ 42.393579] niks3[1575]: time=2026-10-20T08:18:51.481Z level=INFO msg="Garbage collection progress" phase="" failed_uploads_deleted=0 old_closures_deleted=0 objects_marked=3 objects_deleted=0 objects_failed=02226server # [ 42.393902] niks3[1575]: time=2026-10-20T08:18:51.481Z level=INFO msg="Garbage collection completed successfully" failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=3 objects-deleted-after-grace-period=0 objects-failed-to-delete=02227server # [ 42.409057] systemd[1]: niks3-gc.service: Deactivated successfully.2228server # [ 42.412630] systemd[1]: Finished niks3 garbage collection.2229server # [ 42.414132] systemd[1]: niks3-gc.service: Consumed 43ms CPU time over 2.072s wall clock time, 11.6M memory peak, 1.2K incoming IP traffic, 823B outgoing IP traffic.2230server: (finished: must succeed: systemctl start niks3-gc.service, in 2.12 seconds)2231builder: waiting for unit niks3-auto-upload.socket2232builder: waiting for the VM to finish booting2233builder: Guest shell says: b'Spawning backdoor root shell...\n'2234builder: connected to guest root shell2235builder: (connecting took 0.00 seconds)2236builder: (finished: waiting for the VM to finish booting, in 0.00 seconds)2237builder: (finished: waiting for unit niks3-auto-upload.socket, in 0.05 seconds)2238builder: must succeed: test -S /run/niks3/upload-to-cache.sock2239builder: (finished: must succeed: test -S /run/niks3/upload-to-cache.sock, in 0.02 seconds)2240builder: must succeed: grep post-build-hook /etc/nix/nix.conf2241builder: (finished: must succeed: grep post-build-hook /etc/nix/nix.conf, in 0.02 seconds)2242builder: must succeed: 2243cat > /tmp/test-drv.nix << 'EOF'2244derivation {2245 name = "post-build-hook-test";2246 system = builtins.currentSystem;2247 builder = "/bin/sh";2248 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2249}2250EOF22512252builder: (finished: must succeed: 2253cat > /tmp/test-drv.nix << 'EOF'2254derivation {2255 name = "post-build-hook-test";2256 system = builtins.currentSystem;2257 builder = "/bin/sh";2258 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2259}2260EOF2261, in 0.02 seconds)2262builder: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link2263builder # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2264builder # [ 42.619659] systemd[1]: Created slice Slice /system/nix-daemon.2265builder # [ 42.624965] systemd[1]: Started Nix Daemon instance (PID 775/UID 0).2266builder # [ 42.686885] nix-daemon[779]: remote pid 775 is unknown user (trusted)2267builder # 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 430 ms (attempt 1/5)2268builder # 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 1118 ms (attempt 2/5)2269builder # 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 2100 ms (attempt 3/5)2270builder # 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 4033 ms (attempt 4/5)2271builder # 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)2272builder # this derivation will be built:2273builder # /nix/store/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv2274builder # building '/nix/store/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv'...2275builder # [ 50.612422] systemd[1]: Started niks3 auto-upload daemon.2276builder # [ 50.745988] niks3-hook[805]: time=2026-10-20T08:18:59.833Z 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=0s2277builder # [ 50.754467] niks3-hook[805]: time=2026-10-20T08:18:59.842Z level=INFO msg="Upload queue status" pending=12278builder # [ 50.755880] niks3-hook[805]: time=2026-10-20T08:18:59.842Z level=INFO msg="Uploading batch" count=12279builder: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link, in 8.24 seconds)2280builder: waiting for unit niks3-auto-upload.service2281builder # [ 50.789146] systemd[1]: nix-daemon@0-1-775_776-0.service: Deactivated successfully.2282builder # [ 50.794691] systemd[1]: nix-daemon@0-1-775_776-0.service: Consumed 236ms CPU time over 8.164s wall clock time, 34.8M memory peak, 1.4K outgoing IP traffic.2283builder: (finished: waiting for unit niks3-auto-upload.service, in 0.09 seconds)2284??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2285 File "/nix/store/bsfvpn8q2430b9gbcb0d939kswyw8fyr-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392286builder: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive2287??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2288 File "/nix/store/bsfvpn8q2430b9gbcb0d939kswyw8fyr-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392289builder # [ 50.882807] systemd[1]: Started Nix Daemon instance (PID 816/UID 0).2290builder # [ 50.956552] nix-daemon[826]: remote pid 816 is unknown user (trusted)2291builder # [ 50.972553] systemd[1]: nix-daemon@1-2-816_817-0.service: Deactivated successfully.2292server # [ 50.970015] niks3-server[981]: 2026/10/20 08:19:00 INFO Received push request method=POST path=/api/pushes2293builder # [ 50.988194] niks3-hook[805]: time=2026-10-20T08:19:00.074Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2294builder # [ 50.989841] niks3-hook[805]: time=2026-10-20T08:19:00.074Z level=INFO msg="Uploading 1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test (144B)"2295builder # [ 51.036096] niks3-hook[805]: time=2026-10-20T08:19:00.122Z level=INFO msg="Uploading 1 narinfos"2296server # [ 51.021199] niks3-server[981]: 2026/10/20 08:19:00 INFO Registered completed upload object_key=1qlca7drab1x7rccc3h1pw1fl9c0j7yx.ls2297server # [ 51.031223] niks3-server[981]: 2026/10/20 08:19:00 INFO Received sign narinfos request method=POST path=/api/pushes/6/sign2298server # [ 51.037657] niks3-server[981]: 2026/10/20 08:19:00 INFO Signed narinfos id=6 count=12299server # [ 51.039523] niks3-server[981]: 2026/10/20 08:19:00 INFO Registered completed upload object_key=nar/1a0vfw93shmvlz6lac7d1lv82jxzfdswigh5s32hpvncf74i1i0y.nar.zst2300server # [ 51.048740] niks3-server[981]: 2026/10/20 08:19:00 INFO Registered completed upload object_key=log/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv2301server # [ 51.064679] niks3-server[981]: 2026/10/20 08:19:00 INFO Received complete push request method=POST path=/api/pushes/6/complete2302builder # [ 51.077743] niks3-hook[805]: time=2026-10-20T08:19:00.165Z level=INFO msg="Upload complete. (320ms)"2303server # [ 51.069471] niks3-server[981]: 2026/10/20 08:19:00 INFO Completed push id=6 objects=4 duration=7.32686ms2304server # [ 51.074963] niks3-server[981]: 2026/10/20 08:19:00 INFO Registered completed upload object_key=1qlca7drab1x7rccc3h1pw1fl9c0j7yx.narinfo2305builder # [ 55.757686] niks3-hook[805]: time=2026-10-20T08:19:04.844Z level=INFO msg="Idle timeout reached and queue is empty, shutting down"2306builder # [ 55.761278] niks3-hook[805]: time=2026-10-20T08:19:04.848Z level=INFO msg="niks3-hook serve stopped"2307builder # [ 55.774763] systemd[1]: niks3-auto-upload.service: Deactivated successfully.2308builder # [ 55.780720] systemd[1]: niks3-auto-upload.service: Consumed 170ms CPU time over 5.165s wall clock time, 23.3M memory peak, 68K written to disk, 5.9K incoming IP traffic, 8.9K outgoing IP traffic.2309builder: (finished: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive, in 5.24 seconds)2310server: must succeed: 2311 export AWS_ACCESS_KEY_ID=rustfsadmin2312export AWS_SECRET_ACCESS_KEY=rustfsadmin2313 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-test23142315server: (finished: must succeed: 2316 export AWS_ACCESS_KEY_ID=rustfsadmin2317export AWS_SECRET_ACCESS_KEY=rustfsadmin2318 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-test2319, in 0.28 seconds)2320server: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test2321server: (finished: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test, in 0.06 seconds)2322(finished: run the VM test script, in 59.17 seconds)2323test script finished in 59.28s2324cleanup2325kill QemuMachine (pid 47)2326builder # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2327builder # [2026-10-20T08:19:05Z INFO virtiofsd] Client disconnected, shutting down2328builder # [2026-10-20T08:19:05Z INFO virtiofsd] Client disconnected, shutting down2329builder # [2026-10-20T08:19:05Z INFO virtiofsd] Client disconnected, shutting down2330kill QemuMachine (pid 48)2331server # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2332server # [2026-10-20T08:19:05Z INFO virtiofsd] Client disconnected, shutting down2333server # [2026-10-20T08:19:05Z INFO virtiofsd] Client disconnected, shutting down2334server # [2026-10-20T08:19:05Z INFO virtiofsd] Client disconnected, shutting down2335(finished: cleanup, in 0.72 seconds)2336additionally exposed symbols:2337 builder, server,2338 vlan1,2339 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_ssh2340Hello store path: /nix/store/0xy6jlccm8kfpwmhkxabz3638lchyi74-hello-2.12.32341Test output path: /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2342Symlink wrapper store path: /nix/store/qlhg6w8ghvjarrf438qw23mymapxfpdp-symlink-wrapper2343Symlink wrapper points to: /nix/store/l5aiz90gwfr0i2074qb0pk20v27p5v86-base-package/bin/test-program2344OIDC test store path: /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test2345Valid OIDC token obtained (length=677)2346OIDC push with valid token: SUCCESS2347Invalid OIDC token obtained (wrong org)2348OIDC push with wrong org: correctly rejected2349Wrong audience OIDC token obtained2350OIDC push with wrong audience: correctly rejected2351OIDC push with malformed token: correctly rejected2352All OIDC tests passed!2353All pin tests passed!2354Built store path: /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test2355Post-build-hook pipeline test passed!