vm-test-run-nixos-test-niks3
checks.aarch64-linux.nixos-test-niks3-lix
· build #342
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7start all VMs8builder: starting vm9server: starting vm10server # Disk image does not exist, creating the virtualisation disk image...11server: QEMU running (pid 48)12builder: QEMU running (pid 47)13builder # Disk image does not exist, creating the virtualisation disk image...14server # Formatting '/build/vm-state-server/tmp.i34u0Y3Gc8', fmt=raw size=107374182415builder # Formatting '/build/vm-state-builder/tmp.xbZFZLrySk', fmt=raw size=107374182416server # mke2fs 1.47.4 (6-Mar-2025)17builder # mke2fs 1.47.4 (6-Mar-2025)18server # Discarding device blocks: 0/262144 done19builder # Discarding device blocks: 0/262144 done20server # Creating filesystem with 262144 4k blocks and 65536 inodes21builder # Creating filesystem with 262144 4k blocks and 65536 inodes22server # Filesystem UUID: 811665c3-75b4-4a57-b03f-3960e0c0007723builder # Filesystem UUID: d6d8dda6-7ed0-469e-977e-297a9d80bfeb24server # Superblock backups stored on blocks:25builder # Superblock backups stored on blocks:26server # 32768, 98304, 163840, 22937627builder # 32768, 98304, 163840, 22937628server # 29builder # 30server # Allocating group tables: 0/8 done31builder # Allocating group tables: 0/8 done32server # Writing inode tables: 0/8 done33builder # Writing inode tables: 0/8 done34server # Creating journal (8192 blocks): done35builder # Creating journal (8192 blocks): done36server # Writing superblocks and filesystem accounting information: 0/8 done37builder # Writing superblocks and filesystem accounting information: 0/8 done38server # 39builder # 40server # Virtualisation disk image created.41builder # Virtualisation disk image created.42server # Starting virtiofs daemons...43builder # Starting virtiofs daemons...44server # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)45builder # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)46server # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether47builder # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether48server # [2026-10-01T10:33:28Z INFO virtiofsd] Waiting for vhost-user socket connection...49builder # [2026-10-01T10:33:28Z INFO virtiofsd] Waiting for vhost-user socket connection...50server # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)51builder # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)52server # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether53builder # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether54server # [2026-10-01T10:33:28Z INFO virtiofsd] Waiting for vhost-user socket connection...55builder # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)56server # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)57builder # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether58server # [2026-10-01T10:33:28Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether59builder # [2026-10-01T10:33:28Z INFO virtiofsd] Waiting for vhost-user socket connection...60server # [2026-10-01T10:33:28Z INFO virtiofsd] Waiting for vhost-user socket connection...61builder # [2026-10-01T10:33:28Z INFO virtiofsd] Waiting for vhost-user socket connection...62server # [2026-10-01T10:33:28Z INFO virtiofsd] Client connected, servicing requests63builder # [2026-10-01T10:33:28Z INFO virtiofsd] Client connected, servicing requests64server # [2026-10-01T10:33:28Z INFO virtiofsd] Client connected, servicing requests65builder # [2026-10-01T10:33:28Z INFO virtiofsd] Client connected, servicing requests66server # [2026-10-01T10:33:28Z INFO virtiofsd] Client connected, servicing requests67builder # [2026-10-01T10:33:28Z INFO virtiofsd] Client connected, servicing requests68(finished: start all VMs, in 0.38 seconds)69server: waiting for unit postgresql.service70server: waiting for the VM to finish booting71builder # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]72server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]73builder # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 202674builder # [ 0.000000] KASLR enabled75server # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 202676builder # [ 0.000000] random: crng init done77server # [ 0.000000] KASLR enabled78builder # [ 0.000000] Machine model: linux,dummy-virt79server # [ 0.000000] random: crng init done80builder # [ 0.000000] efi: UEFI not found.81server # [ 0.000000] Machine model: linux,dummy-virt82server # [ 0.000000] efi: UEFI not found.83builder # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT84server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT85builder # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]86server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]87builder # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f]88builder # [ 0.000000] Zone ranges:89server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f]90server # [ 0.000000] Zone ranges:91builder # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]92builder # [ 0.000000] DMA32 empty93server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]94builder # [ 0.000000] Normal empty95server # [ 0.000000] DMA32 empty96builder # [ 0.000000] Device empty97server # [ 0.000000] Normal empty98builder # [ 0.000000] Movable zone start for each node99server # [ 0.000000] Device empty100builder # [ 0.000000] Early memory node ranges101server # [ 0.000000] Movable zone start for each node102server # [ 0.000000] Early memory node ranges103builder # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]104server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]105builder # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]106server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]107builder # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000108server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000109builder # [ 0.000000] psci: probing for conduit method from DT.110server # [ 0.000000] psci: probing for conduit method from DT.111builder # [ 0.000000] psci: PSCIv1.3 detected in firmware.112server # [ 0.000000] psci: PSCIv1.3 detected in firmware.113builder # [ 0.000000] psci: Using standard PSCI v0.2 function IDs114server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs115builder # [ 0.000000] psci: Trusted OS migration not required116server # [ 0.000000] psci: Trusted OS migration not required117builder # [ 0.000000] psci: SMC Calling Convention v1.1118server # [ 0.000000] psci: SMC Calling Convention v1.1119builder # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)120server # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)121builder # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296122server # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296123builder # [ 0.000000] Detected PIPT I-cache on CPU0124server # [ 0.000000] Detected PIPT I-cache on CPU0125builder # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)126server # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)127builder # [ 0.000000] CPU features: detected: GICv3 CPU interface128server # [ 0.000000] CPU features: detected: GICv3 CPU interface129builder # [ 0.000000] CPU features: detected: Spectre-v4130server # [ 0.000000] CPU features: detected: Spectre-v4131builder # [ 0.000000] CPU features: detected: Spectre-BHB132server # [ 0.000000] CPU features: detected: Spectre-BHB133builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38134server # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38135builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23136server # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23137builder # [ 0.000000] alternatives: applying boot alternatives138server # [ 0.000000] alternatives: applying boot alternatives139builder # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/ps81lgv9798c7qwl9q77am5i39ab1lc1-nixos-system-builder-test/init regInfo=/nix/.ro-store/cpdccvwwvkpas0hhmz1zkc20ykvghx6y-closure-info/registration console=ttyAMA0,115200n8 console=tty0140server # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/k66cp5bym2y2x0gahgxdnb3hdjs131vh-nixos-system-server-test/init regInfo=/nix/.ro-store/cvzlm8dfx27rdkmm3w7ng4adlvk0fkb8-closure-info/registration console=ttyAMA0,115200n8 console=tty0141builder # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/cpdccvwwvkpas0hhmz1zkc20ykvghx6y-closure-info/registration", will be passed to user space.142builder # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes143server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/cvzlm8dfx27rdkmm3w7ng4adlvk0fkb8-closure-info/registration", will be passed to user space.144builder # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)145server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes146builder # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)147server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)148builder # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB149server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)150builder # [ 0.000000] software IO TLB: area num 1.151server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB152builder # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)153server # [ 0.000000] software IO TLB: area num 1.154builder # [ 0.000000] Fallback order for Node 0: 0155server # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)156builder # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144157server # [ 0.000000] Fallback order for Node 0: 0158builder # [ 0.000000] Policy zone: DMA159server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144160builder # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off161server # [ 0.000000] Policy zone: DMA162builder # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1163server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off164builder # [ 0.000000] allocated 2097152 bytes of page_ext165server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1166builder # [ 0.000000] ftrace: allocating 74966 entries in 294 pages167server # [ 0.000000] allocated 2097152 bytes of page_ext168builder # [ 0.000000] ftrace: allocated 294 pages with 4 groups169server # [ 0.000000] ftrace: allocating 74966 entries in 294 pages170builder # [ 0.000000] rcu: Hierarchical RCU implementation.171server # [ 0.000000] ftrace: allocated 294 pages with 4 groups172builder # [ 0.000000] rcu: RCU event tracing is enabled.173server # [ 0.000000] rcu: Hierarchical RCU implementation.174builder # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.175server # [ 0.000000] rcu: RCU event tracing is enabled.176builder # [ 0.000000] Trampoline variant of Tasks RCU enabled.177server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.178builder # [ 0.000000] Rude variant of Tasks RCU enabled.179server # [ 0.000000] Trampoline variant of Tasks RCU enabled.180builder # [ 0.000000] Tracing variant of Tasks RCU enabled.181server # [ 0.000000] Rude variant of Tasks RCU enabled.182server # [ 0.000000] Tracing variant of Tasks RCU enabled.183builder # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.184builder # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1185server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.186server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1187builder # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.188server # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.189builder # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.190server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.191builder # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.192builder # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0193server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.194builder # [ 0.000000] GICv3: 256 SPIs implemented195server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0196builder # [ 0.000000] GICv3: 0 Extended SPIs implemented197server # [ 0.000000] GICv3: 256 SPIs implemented198builder # [ 0.000000] Root IRQ handler: gic_handle_irq199server # [ 0.000000] GICv3: 0 Extended SPIs implemented200builder # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI201server # [ 0.000000] Root IRQ handler: gic_handle_irq202builder # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0203server # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI204server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0205builder # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000206builder # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]207server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000208server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]209builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)210server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)211builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)212server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)213builder # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000214server # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000215builder # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000216server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000217builder # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.218server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.219builder # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns220server # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns221builder # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).222server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).223builder # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns224server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns225builder # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns226builder # [ 0.000031] arm-pv: using stolen time PV227server # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns228server # [ 0.000031] arm-pv: using stolen time PV229builder # [ 0.000418] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)230server # [ 0.000430] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)231builder # [ 0.000616] Console: colour dummy device 80x25232server # [ 0.000628] Console: colour dummy device 80x25233builder # [ 0.000624] printk: legacy console [tty0] enabled234server # [ 0.000636] printk: legacy console [tty0] enabled235builder # [ 0.000820] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)236server # [ 0.000824] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)237builder # [ 0.000826] pid_max: default: 32768 minimum: 301238server # [ 0.000830] pid_max: default: 32768 minimum: 301239builder # [ 0.000901] LSM: initializing lsm=capability,landlock,yama,bpf,ima240builder # [ 0.001039] landlock: Up and running.241server # [ 0.000913] LSM: initializing lsm=capability,landlock,yama,bpf,ima242builder # [ 0.001043] Yama: becoming mindful.243server # [ 0.001057] landlock: Up and running.244builder # [ 0.001542] LSM support for eBPF active245server # [ 0.001060] Yama: becoming mindful.246server # [ 0.001496] LSM support for eBPF active247builder # [ 0.001660] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)248server # [ 0.001606] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)249builder # [ 0.001681] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)250server # [ 0.001628] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)251builder # [ 0.003628] rcu: Hierarchical SRCU implementation.252server # [ 0.003575] rcu: Hierarchical SRCU implementation.253builder # [ 0.003633] rcu: Max phase no-delay instances is 1000.254server # [ 0.003581] rcu: Max phase no-delay instances is 1000.255builder # [ 0.005020] fsl-mc MSI: its@8080000 domain created256server # [ 0.004893] fsl-mc MSI: its@8080000 domain created257builder # [ 0.005143] EFI services will not be available.258server # [ 0.005015] EFI services will not be available.259server # [ 0.005138] smp: Bringing up secondary CPUs ...260builder # [ 0.005221] smp: Bringing up secondary CPUs ...261server # [ 0.005147] smp: Brought up 1 node, 1 CPU262builder # [ 0.005230] smp: Brought up 1 node, 1 CPU263server # [ 0.005150] SMP: Total of 1 processors activated.264builder # [ 0.005233] SMP: Total of 1 processors activated.265server # [ 0.005153] CPU: All CPU(s) started at EL1266builder # [ 0.005236] CPU: All CPU(s) started at EL1267server # [ 0.005166] CPU features: detected: Branch Target Identification268builder # [ 0.005248] CPU features: detected: Branch Target Identification269server # [ 0.005171] CPU features: detected: ARMv8.4 Translation Table Level270builder # [ 0.005253] CPU features: detected: ARMv8.4 Translation Table Level271server # [ 0.005174] CPU features: detected: Instruction cache invalidation not required for I/D coherence272builder # [ 0.005256] CPU features: detected: Instruction cache invalidation not required for I/D coherence273server # [ 0.005178] CPU features: detected: Data cache clean to the PoU not required for I/D coherence274builder # [ 0.005260] CPU features: detected: Data cache clean to the PoU not required for I/D coherence275server # [ 0.005181] CPU features: detected: Common not Private translations276builder # [ 0.005264] CPU features: detected: Common not Private translations277server # [ 0.005185] CPU features: detected: CRC32 instructions278builder # [ 0.005267] CPU features: detected: CRC32 instructions279server # [ 0.005188] CPU features: detected: Data cache clean to Point of Deep Persistence280builder # [ 0.005270] CPU features: detected: Data cache clean to Point of Deep Persistence281server # [ 0.005191] CPU features: detected: Data cache clean to Point of Persistence282builder # [ 0.005273] CPU features: detected: Data cache clean to Point of Persistence283server # [ 0.005194] CPU features: detected: Data independent timing control (DIT)284builder # [ 0.005276] CPU features: detected: Data independent timing control (DIT)285server # [ 0.005197] CPU features: detected: E0PD286builder # [ 0.005279] CPU features: detected: E0PD287server # [ 0.005199] CPU features: detected: Enhanced Counter Virtualization288builder # [ 0.005281] CPU features: detected: Enhanced Counter Virtualization289server # [ 0.005203] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)290builder # [ 0.005285] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)291server # [ 0.005206] CPU features: detected: Enhanced Virtualization Traps292builder # [ 0.005288] CPU features: detected: Enhanced Virtualization Traps293server # [ 0.005210] CPU features: detected: Fine Grained Traps294builder # [ 0.005291] CPU features: detected: Fine Grained Traps295server # [ 0.005214] CPU features: detected: Generic authentication (architected QARMA5 algorithm)296builder # [ 0.005295] CPU features: detected: Generic authentication (architected QARMA5 algorithm)297server # [ 0.005220] CPU features: detected: RCpc load-acquire (LDAPR)298builder # [ 0.005300] CPU features: detected: RCpc load-acquire (LDAPR)299server # [ 0.005223] CPU features: detected: LSE atomic instructions300builder # [ 0.005303] CPU features: detected: LSE atomic instructions301server # [ 0.005226] CPU features: detected: Privileged Access Never302builder # [ 0.005306] CPU features: detected: Privileged Access Never303server # [ 0.005228] CPU features: detected: PMUv3304builder # [ 0.005309] CPU features: detected: PMUv3305server # [ 0.005232] CPU features: detected: RAS Extension Support306builder # [ 0.005311] CPU features: detected: RAS Extension Support307server # [ 0.005235] CPU features: detected: RASv1p1 Extension Support308builder # [ 0.005314] CPU features: detected: RASv1p1 Extension Support309server # [ 0.005237] CPU features: detected: Random Number Generator310builder # [ 0.005317] CPU features: detected: Random Number Generator311server # [ 0.005240] CPU features: detected: Speculation barrier (SB)312builder # [ 0.005320] CPU features: detected: Speculation barrier (SB)313server # [ 0.005243] CPU features: detected: Stage-2 Force Write-Back314builder # [ 0.005322] CPU features: detected: Stage-2 Force Write-Back315server # [ 0.005246] CPU features: detected: TLB range maintenance instructions316builder # [ 0.005325] CPU features: detected: TLB range maintenance instructions317server # [ 0.005250] CPU features: detected: Speculative Store Bypassing Safe (SSBS)318builder # [ 0.005330] CPU features: detected: Speculative Store Bypassing Safe (SSBS)319server # [ 0.005287] alternatives: applying system-wide alternatives320builder # [ 0.005368] alternatives: applying system-wide alternatives321server # [ 0.008403] CPU features: detected: BBM Level 2 without TLB conflict abort322builder # [ 0.008381] CPU features: detected: BBM Level 2 without TLB conflict abort323server # [ 0.008559] Memory: 893216K/1048576K available (24448K kernel code, 7094K rwdata, 26596K rodata, 4736K init, 1107K bss, 114056K reserved, 32768K cma-reserved)324builder # [ 0.008566] Memory: 893184K/1048576K available (24448K kernel code, 7094K rwdata, 26596K rodata, 4736K init, 1107K bss, 114084K reserved, 32768K cma-reserved)325builder # [ 0.008918] devtmpfs: initialized326builder # [ 0.010678] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)327builder # [ 0.010702] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).328builder # [ 0.010899] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL329builder # [ 0.010909] 0 pages in range for non-PLT usage330builder # [ 0.010910] 508224 pages in range for PLT usage331builder # [ 0.011026] pinctrl core: initialized pinctrl subsystem332builder # [ 0.011840] DMI not present or invalid.333builder # [ 0.015262] NET: Registered PF_NETLINK/PF_ROUTE protocol family334builder # [ 0.017670] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations335builder # [ 0.017812] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations336server # [ 0.008920] devtmpfs: initialized337server # [ 0.010689] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)338builder # [ 0.017972] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations339builder # [ 0.017993] audit: initializing netlink subsys (disabled)340server # [ 0.010712] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).341builder # [ 0.018575] thermal_sys: Registered thermal governor 'fair_share'342server # [ 0.010948] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL343builder # [ 0.018577] thermal_sys: Registered thermal governor 'bang_bang'344server # [ 0.010955] 0 pages in range for non-PLT usage345server # [ 0.010957] 508224 pages in range for PLT usage346builder # [ 0.018581] thermal_sys: Registered thermal governor 'step_wise'347server # [ 0.011077] pinctrl core: initialized pinctrl subsystem348builder # [ 0.018584] thermal_sys: Registered thermal governor 'user_space'349server # [ 0.011844] DMI not present or invalid.350builder # [ 0.018586] thermal_sys: Registered thermal governor 'power_allocator'351server # [ 0.015081] NET: Registered PF_NETLINK/PF_ROUTE protocol family352builder # [ 0.018621] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1353server # [ 0.017446] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations354builder # [ 0.018629] cpuidle: using governor ladder355builder # [ 0.018635] cpuidle: using governor menu356server # [ 0.017604] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations357builder # [ 0.018865] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.358server # [ 0.017759] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations359builder # [ 0.018882] ASID allocator initialised with 65536 entries360server # [ 0.017781] audit: initializing netlink subsys (disabled)361builder # [ 0.020233] Serial: AMBA PL011 UART driver362server # [ 0.018366] thermal_sys: Registered thermal governor 'fair_share'363builder # [ 0.025822] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1364server # [ 0.018368] thermal_sys: Registered thermal governor 'bang_bang'365builder # [ 0.025983] printk: console [ttyAMA0] enabled366server # [ 0.018371] thermal_sys: Registered thermal governor 'step_wise'367server # [ 0.018374] thermal_sys: Registered thermal governor 'user_space'368server # [ 0.018377] thermal_sys: Registered thermal governor 'power_allocator'369server # [ 0.018403] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1370server # [ 0.018411] cpuidle: using governor ladder371server # [ 0.018416] cpuidle: using governor menu372server # [ 0.018629] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.373server # [ 0.018643] ASID allocator initialised with 65536 entries374server # [ 0.019874] Serial: AMBA PL011 UART driver375server # [ 0.025443] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1376server # [ 0.025596] printk: console [ttyAMA0] enabled377server # [ 0.158119] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages378builder # [ 0.157809] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages379server # [ 0.158129] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page380builder # [ 0.157818] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page381server # [ 0.158132] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages382builder # [ 0.157821] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages383server # [ 0.158135] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page384builder # [ 0.157824] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page385server # [ 0.158137] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages386builder # [ 0.157826] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages387server # [ 0.158140] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page388builder # [ 0.157829] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page389server # [ 0.158143] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages390builder # [ 0.157832] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages391server # [ 0.158145] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page392builder # [ 0.157835] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page393server # [ 0.159398] fbcon: Taking over console394builder # [ 0.159006] fbcon: Taking over console395server # [ 0.159408] ACPI: Interpreter disabled.396builder # [ 0.159015] ACPI: Interpreter disabled.397server # [ 0.168208] iommu: Default domain type: Translated398builder # [ 0.169437] iommu: Default domain type: Translated399server # [ 0.168213] iommu: DMA domain TLB invalidation policy: strict mode400builder # [ 0.169442] iommu: DMA domain TLB invalidation policy: strict mode401builder # [ 0.169835] SCSI subsystem initialized402server # [ 0.170011] SCSI subsystem initialized403builder # [ 0.172001] usbcore: registered new interface driver usbfs404server # [ 0.170191] usbcore: registered new interface driver usbfs405builder # [ 0.172037] usbcore: registered new interface driver hub406server # [ 0.170220] usbcore: registered new interface driver hub407builder # [ 0.172053] usbcore: registered new device driver usb408server # [ 0.170235] usbcore: registered new device driver usb409builder # [ 0.172347] pps_core: LinuxPPS API ver. 1 registered410server # [ 0.170543] pps_core: LinuxPPS API ver. 1 registered411builder # [ 0.172351] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>412server # [ 0.170547] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>413builder # [ 0.172359] PTP clock support registered414server # [ 0.170556] PTP clock support registered415builder # [ 0.172407] EDAC MC: Ver: 3.0.0416server # [ 0.170595] EDAC MC: Ver: 3.0.0417builder # [ 0.172579] scmi_core: SCMI protocol bus registered418server # [ 0.177948] scmi_core: SCMI protocol bus registered419builder # [ 0.179533] FPGA manager framework420server # [ 0.178316] FPGA manager framework421builder # [ 0.180149] vgaarb: loaded422server # [ 0.178865] vgaarb: loaded423builder # [ 0.182384] clocksource: Switched to clocksource arch_sys_counter424server # [ 0.179129] clocksource: Switched to clocksource arch_sys_counter425server # [ 0.187256] VFS: Disk quotas dquot_6.6.0426builder # [ 0.189237] VFS: Disk quotas dquot_6.6.0427server # [ 0.187281] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)428builder # [ 0.189268] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)429server # [ 0.189756] netfs: FS-Cache loaded430builder # [ 0.191679] netfs: FS-Cache loaded431server # [ 0.189873] pnp: PnP ACPI: disabled432builder # [ 0.191802] pnp: PnP ACPI: disabled433server # [ 0.195600] NET: Registered PF_INET protocol family434builder # [ 0.197369] NET: Registered PF_INET protocol family435server # [ 0.195752] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)436builder # [ 0.197533] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)437builder # [ 0.228951] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)438builder # [ 0.228992] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)439builder # [ 0.229016] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)440builder # [ 0.229058] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)441server # [ 0.225444] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)442builder # [ 0.229130] TCP: Hash tables configured (established 8192 bind 8192)443server # [ 0.225480] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)444builder # [ 0.229214] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)445server # [ 0.225503] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)446builder # [ 0.229243] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)447builder # [ 0.229301] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)448server # [ 0.225544] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)449builder # [ 0.229391] NET: Registered PF_UNIX/PF_LOCAL protocol family450server # [ 0.225616] TCP: Hash tables configured (established 8192 bind 8192)451builder # [ 0.229413] NET: Registered PF_XDP protocol family452server # [ 0.225696] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)453builder # [ 0.229429] PCI: CLS 0 bytes, default 64454builder # [ 0.229762] Trying to unpack rootfs image as initramfs...455server # [ 0.225723] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)456server # [ 0.225746] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)457server # [ 0.225820] NET: Registered PF_UNIX/PF_LOCAL protocol family458builder # [ 0.243322] kvm [1]: HYP mode not available459server # [ 0.225842] NET: Registered PF_XDP protocol family460server # [ 0.225857] PCI: CLS 0 bytes, default 64461server # [ 0.226127] Trying to unpack rootfs image as initramfs...462server # [ 0.245914] kvm [1]: HYP mode not available463builder # [ 0.339775] Initialise system trusted keyrings464server # [ 0.339632] Initialise system trusted keyrings465builder # [ 0.341105] workingset: timestamp_bits=42 max_order=18 bucket_order=0466server # [ 0.339773] workingset: timestamp_bits=42 max_order=18 bucket_order=0467server # [ 0.340177] squashfs: version 4.0 (2009/01/31) Phillip Lougher468server # [ 0.340239] 9p: Installing v9fs 9p2000 file system support469builder # [ 0.345616] squashfs: version 4.0 (2009/01/31) Phillip Lougher470builder # [ 0.345697] 9p: Installing v9fs 9p2000 file system support471server # [ 0.368299] Key type asymmetric registered472server # [ 0.368315] Asymmetric key parser 'x509' registered473server # [ 0.368382] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)474server # [ 0.368513] io scheduler mq-deadline registered475server # [ 0.368517] io scheduler kyber registered476builder # [ 0.373668] Key type asymmetric registered477builder # [ 0.373683] Asymmetric key parser 'x509' registered478builder # [ 0.373751] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)479builder # [ 0.373910] io scheduler mq-deadline registered480builder # [ 0.373915] io scheduler kyber registered481server # [ 0.380809] pl061_gpio 9030000.pl061: PL061 GPIO chip registered482server # [ 0.381381] ledtrig-cpu: registered to indicate activity on CPUs483server # [ 0.381771] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:484server # [ 0.381785] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000485server # [ 0.381794] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000486server # [ 0.381800] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000487server # [ 0.381824] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits488server # [ 0.381844] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]489builder # [ 0.388592] pl061_gpio 9030000.pl061: PL061 GPIO chip registered490server # [ 0.381917] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00491builder # [ 0.389282] ledtrig-cpu: registered to indicate activity on CPUs492server # [ 0.381923] pci_bus 0000:00: root bus resource [bus 00-ff]493server # [ 0.381928] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]494builder # [ 0.389660] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:495server # [ 0.381931] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]496builder # [ 0.389673] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000497server # [ 0.381934] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]498builder # [ 0.389683] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000499server # [ 0.381999] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint500builder # [ 0.389689] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000501server # [ 0.382456] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint502server # [ 0.382645] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]503builder # [ 0.389719] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits504server # [ 0.382657] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]505builder # [ 0.389743] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]506server # [ 0.382684] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]507builder # [ 0.389854] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00508server # [ 0.382696] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]509builder # [ 0.389861] pci_bus 0000:00: root bus resource [bus 00-ff]510builder # [ 0.389866] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]511builder # [ 0.389869] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]512builder # [ 0.389872] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]513builder # [ 0.389959] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint514builder # [ 0.390423] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint515builder # [ 0.390614] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]516builder # [ 0.390626] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]517builder # [ 0.390653] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]518builder # [ 0.390665] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]519builder # [ 0.391166] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint520builder # [ 0.391370] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]521builder # [ 0.391381] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]522server # [ 0.407384] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint523server # [ 0.407593] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]524builder # [ 0.391407] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]525server # [ 0.407606] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]526builder # [ 0.391912] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint527server # [ 0.407633] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]528builder # [ 0.392101] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]529builder # [ 0.392112] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]530server # [ 0.408146] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint531server # [ 0.408336] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]532builder # [ 0.392138] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]533server # [ 0.408348] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]534server # [ 0.408374] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]535server # [ 0.408847] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint536server # [ 0.409039] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]537builder # [ 0.417855] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint538server # [ 0.409051] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]539builder # [ 0.418047] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]540server # [ 0.409077] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]541builder # [ 0.418058] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]542server # [ 0.409089] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]543builder # [ 0.418085] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]544server # [ 0.409567] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint545builder # [ 0.418096] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]546server # [ 0.409763] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]547builder # [ 0.418593] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint548server # [ 0.409789] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]549builder # [ 0.418786] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]550server # [ 0.410264] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint551builder # [ 0.418813] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]552server # [ 0.410459] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]553server # [ 0.410485] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]554builder # [ 0.419299] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint555builder # [ 0.419493] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]556server # [ 0.410900] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint557builder # [ 0.419519] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]558server # [ 0.411087] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]559builder # [ 0.419984] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint560builder # [ 0.420172] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]561server # [ 0.429554] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint562server # [ 0.429749] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]563server # [ 0.429775] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]564server # [ 0.430254] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint565server # [ 0.430447] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]566server # [ 0.430473] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]567server # [ 0.431001] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint568builder # [ 0.436551] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint569builder # [ 0.436761] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]570builder # [ 0.436788] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]571builder # [ 0.437338] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint572builder # [ 0.437533] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]573builder # [ 0.437560] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]574builder # [ 0.438060] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint575builder # [ 0.438254] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]576builder # [ 0.438280] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]577server # [ 0.443255] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]578builder # [ 0.438781] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint579server # [ 0.443288] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]580builder # [ 0.439071] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]581server # [ 0.443817] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint582builder # [ 0.439083] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]583server # [ 0.444085] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]584builder # [ 0.439109] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]585server # [ 0.444097] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]586server # [ 0.444124] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]587builder # [ 0.439595] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint588builder # [ 0.439781] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]589server # [ 0.444639] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint590builder # [ 0.439793] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]591server # [ 0.444833] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]592builder # [ 0.439819] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]593server # [ 0.444845] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]594server # [ 0.444872] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]595builder # [ 0.455772] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned596server # [ 0.445503] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned597builder # [ 0.455779] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned598server # [ 0.445509] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned599builder # [ 0.455783] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned600server # [ 0.445512] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned601builder # [ 0.455831] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned602server # [ 0.445559] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned603builder # [ 0.455881] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned604server # [ 0.445605] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned605builder # [ 0.455930] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned606server # [ 0.445652] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned607builder # [ 0.455977] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned608server # [ 0.445698] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned609builder # [ 0.456023] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned610server # [ 0.445743] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned611builder # [ 0.456070] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned612server # [ 0.445790] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned613builder # [ 0.456114] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned614server # [ 0.445835] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned615builder # [ 0.456162] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned616server # [ 0.445881] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned617builder # [ 0.456211] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned618server # [ 0.445926] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned619builder # [ 0.456293] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned620server # [ 0.445992] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned621builder # [ 0.456338] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned622server # [ 0.446036] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned623builder # [ 0.456356] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned624server # [ 0.446055] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned625builder # [ 0.456374] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned626server # [ 0.446073] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned627builder # [ 0.456392] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned628server # [ 0.446090] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned629builder # [ 0.456411] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned630server # [ 0.446108] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned631server # [ 0.446127] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned632server # [ 0.446145] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned633server # [ 0.446164] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned634server # [ 0.446186] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned635server # [ 0.446205] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned636server # [ 0.446224] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned637server # [ 0.446243] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned638server # [ 0.446261] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned639server # [ 0.446278] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned640server # [ 0.446296] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned641server # [ 0.446314] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned642server # [ 0.446332] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned643builder # [ 0.480471] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned644server # [ 0.446349] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned645builder # [ 0.480520] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned646server # [ 0.446372] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]647builder # [ 0.480541] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned648server # [ 0.446377] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]649builder # [ 0.480563] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned650server # [ 0.446380] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]651builder # [ 0.480582] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned652builder # [ 0.480601] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned653builder # [ 0.480621] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned654builder # [ 0.480639] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned655builder # [ 0.480658] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned656server # [ 0.487531] pci 0000:00:07.0: enabling device (0000 -> 0002)657builder # [ 0.480676] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned658builder # [ 0.480695] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned659builder # [ 0.480714] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned660builder # [ 0.480732] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned661builder # [ 0.480759] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]662builder # [ 0.480764] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]663builder # [ 0.480767] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]664builder # [ 0.481682] pci 0000:00:07.0: enabling device (0000 -> 0002)665server # [ 0.517967] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)666builder # [ 0.517406] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)667builder # [ 0.519502] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)668server # [ 0.521332] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)669builder # [ 0.529875] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)670builder # [ 0.531999] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)671server # [ 0.531388] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)672server # [ 0.533386] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)673server # [ 0.537114] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)674builder # [ 0.541884] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)675builder # [ 0.543822] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)676server # [ 0.540192] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)677server # [ 0.542823] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)678builder # [ 0.554168] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)679server # [ 0.552409] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)680builder # [ 0.556048] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)681server # [ 0.554234] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)682builder # [ 0.559616] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)683server # [ 0.564559] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)684server # [ 0.566816] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)685builder # [ 0.569910] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)686builder # [ 0.572202] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)687server # [ 0.584369] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled688builder # [ 0.585738] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled689server # [ 0.586173] msm_serial: driver initialized690builder # [ 0.587606] msm_serial: driver initialized691server # [ 0.586309] SuperH (H)SCI(F) driver initialized692builder # [ 0.587739] SuperH (H)SCI(F) driver initialized693server # [ 0.586366] STM32 USART driver initialized694builder # [ 0.587796] STM32 USART driver initialized695builder # [ 0.621253] loop: module loaded696builder # [ 0.621417] virtio_blk virtio2: 1/0/0 default/read/poll queues697builder # [ 0.622244] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)698server # [ 0.624374] loop: module loaded699server # [ 0.624561] virtio_blk virtio2: 1/0/0 default/read/poll queues700server # [ 0.625365] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)701server # [ 0.629859] megasas: 07.734.00.00-rc1702builder # [ 0.629041] megasas: 07.734.00.00-rc1703server # [ 0.630542] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]704builder # [ 0.629728] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]705builder # [ 0.631593] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000706builder # [ 0.631609] Intel/Sharp Extended Query Table at 0x0031707server # [ 0.634110] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000708server # [ 0.634125] Intel/Sharp Extended Query Table at 0x0031709server # [ 0.643783] Using buffer write method710builder # [ 0.645564] Using buffer write method711server # [ 0.643843] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]712builder # [ 0.645602] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]713server # [ 0.645527] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000714builder # [ 0.647249] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000715server # [ 0.645541] Intel/Sharp Extended Query Table at 0x0031716builder # [ 0.647264] Intel/Sharp Extended Query Table at 0x0031717server # [ 0.650725] Using buffer write method718server # [ 0.650738] Concatenating MTD devices:719server # [ 0.650740] (0): "0.flash"720server # [ 0.650742] (1): "0.flash"721server # [ 0.650744] into device "0.flash"722builder # [ 0.652763] Using buffer write method723builder # [ 0.652777] Concatenating MTD devices:724builder # [ 0.652779] (0): "0.flash"725builder # [ 0.652781] (1): "0.flash"726builder # [ 0.652783] into device "0.flash"727server # [ 0.915416] Freeing initrd memory: 26944K728server # [ 0.921640] tun: Universal TUN/TAP device driver, 1.6729server # [ 0.925465] thunder_xcv, ver 1.0730server # [ 0.925492] thunder_bgx, ver 1.0731builder # [ 0.927307] Freeing initrd memory: 26972K732server # [ 0.925511] nicpf, ver 1.0733server # [ 0.926083] e1000: Intel(R) PRO/1000 Network Driver734server # [ 0.926087] e1000: Copyright (c) 1999-2006 Intel Corporation.735server # [ 0.926109] e1000e: Intel(R) PRO/1000 Network Driver736server # [ 0.926115] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.737server # [ 0.926141] igb: Intel(R) Gigabit Ethernet Network Driver738server # [ 0.926145] igb: Copyright (c) 2007-2014 Intel Corporation.739server # [ 0.926169] igbvf: Intel(R) Gigabit Virtual Function Network Driver740server # [ 0.926173] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.741server # [ 0.926308] sky2: driver version 1.30742builder # [ 0.933602] tun: Universal TUN/TAP device driver, 1.6743builder # [ 0.937461] thunder_xcv, ver 1.0744server # [ 0.935642] usbcore: registered new interface driver usb-storage745builder # [ 0.937486] thunder_bgx, ver 1.0746builder # [ 0.937506] nicpf, ver 1.0747server # [ 0.935687] usbcore: registered new interface driver usbserial_generic748builder # [ 0.938075] e1000: Intel(R) PRO/1000 Network Driver749server # [ 0.935698] usbserial: USB Serial support registered for generic750builder # [ 0.938080] e1000: Copyright (c) 1999-2006 Intel Corporation.751server # [ 0.936304] hv_vmbus: registering driver hyperv_keyboard752builder # [ 0.938105] e1000e: Intel(R) PRO/1000 Network Driver753server # [ 0.937625] ehci-pci 0000:00:07.0: EHCI Host Controller754builder # [ 0.938113] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.755server # [ 0.937642] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1756builder # [ 0.938136] igb: Intel(R) Gigabit Ethernet Network Driver757server # [ 0.937879] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000758builder # [ 0.938140] igb: Copyright (c) 2007-2014 Intel Corporation.759server # [ 0.938186] rtc-pl031 9010000.pl031: registered as rtc0760builder # [ 0.938160] igbvf: Intel(R) Gigabit Virtual Function Network Driver761server # [ 0.938213] rtc-pl031 9010000.pl031: setting system clock to 2026-10-01T10:33:30 UTC (1790850810)762builder # [ 0.938164] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.763server # [ 0.938552] i2c_dev: i2c /dev entries driver764builder # [ 0.938299] sky2: driver version 1.30765builder # [ 0.940044] usbcore: registered new interface driver usb-storage766builder # [ 0.940154] usbcore: registered new interface driver usbserial_generic767builder # [ 0.940165] usbserial: USB Serial support registered for generic768server # [ 0.947395] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00769builder # [ 0.951323] ehci-pci 0000:00:07.0: EHCI Host Controller770server # [ 0.947666] hub 1-0:1.0: USB hub found771server # [ 0.947681] hub 1-0:1.0: 6 ports detected772builder # [ 0.951341] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1773server # [ 0.950465] sdhci: Secure Digital Host Controller Interface driver774builder # [ 0.951554] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000775server # [ 0.950470] sdhci: Copyright(c) Pierre Ossman776builder # [ 0.952023] hv_vmbus: registering driver hyperv_keyboard777server # [ 0.950748] Synopsys Designware Multimedia Card Interface Driver778builder # [ 0.956108] rtc-pl031 9010000.pl031: registered as rtc0779builder # [ 0.956132] rtc-pl031 9010000.pl031: setting system clock to 2026-10-01T10:33:30 UTC (1790850810)780server # [ 0.955174] sdhci-pltfm: SDHCI platform and OF driver helper781server # [ 0.956744] hid: raw HID events driver (C) Jiri Kosina782builder # [ 0.958299] i2c_dev: i2c /dev entries driver783server # [ 0.956977] usbcore: registered new interface driver usbhid784builder # [ 0.960483] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00785server # [ 0.956984] usbhid: USB HID core driver786builder # [ 0.960769] hub 1-0:1.0: USB hub found787builder # [ 0.960784] hub 1-0:1.0: 6 ports detected788server # [ 0.958139] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available789server # [ 0.963651] drop_monitor: Initializing network drop monitor service790builder # [ 0.964723] sdhci: Secure Digital Host Controller Interface driver791server # [ 0.963800] NET: Registered PF_INET6 protocol family792builder # [ 0.964728] sdhci: Copyright(c) Pierre Ossman793server # [ 0.964372] Segment Routing with IPv6794server # [ 0.964383] In-situ OAM (IOAM) with IPv6795builder # [ 0.965014] Synopsys Designware Multimedia Card Interface Driver796server # [ 0.964409] NET: Registered PF_PACKET protocol family797builder # [ 0.965401] sdhci-pltfm: SDHCI platform and OF driver helper798server # [ 0.964466] 9pnet: Installing 9P2000 support799builder # [ 0.966971] hid: raw HID events driver (C) Jiri Kosina800server # [ 0.964503] Key type dns_resolver registered801builder # [ 0.967214] usbcore: registered new interface driver usbhid802builder # [ 0.967221] usbhid: USB HID core driver803builder # [ 0.968379] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available804builder # [ 0.976136] drop_monitor: Initializing network drop monitor service805builder # [ 0.976303] NET: Registered PF_INET6 protocol family806builder # [ 0.978317] Segment Routing with IPv6807server # [ 0.974935] registered taskstats version 1808builder # [ 0.978329] In-situ OAM (IOAM) with IPv6809server # [ 0.975096] Loading compiled-in X.509 certificates810builder # [ 0.978355] NET: Registered PF_PACKET protocol family811builder # [ 0.978408] 9pnet: Installing 9P2000 support812builder # [ 0.978446] Key type dns_resolver registered813server # [ 0.983661] Demotion targets for Node 0: null814builder # [ 0.987699] registered taskstats version 1815server # [ 0.983755] Key type .fscrypt registered816builder # [ 0.987908] Loading compiled-in X.509 certificates817server # [ 0.983762] Key type fscrypt-provisioning registered818server # [ 0.983861] ima: No TPM chip found, activating TPM-bypass!819server # [ 0.983878] ima: Allocated hash algorithm: sha1820server # [ 0.983902] ima: No architecture policies found821server # [ 0.984471] input: gpio-keys as /devices/platform/gpio-keys/input/input0822builder # [ 0.996837] Demotion targets for Node 0: null823builder # [ 0.996935] Key type .fscrypt registered824builder # [ 0.996941] Key type fscrypt-provisioning registered825builder # [ 0.997039] ima: No TPM chip found, activating TPM-bypass!826builder # [ 0.997056] ima: Allocated hash algorithm: sha1827builder # [ 0.997074] ima: No architecture policies found828builder # [ 0.997659] input: gpio-keys as /devices/platform/gpio-keys/input/input0829server # [ 1.006759] clk: Disabling unused clocks830server # [ 1.006775] PM: genpd: Disabling unused power domains831server # [ 1.011087] Freeing unused kernel memory: 4736K832server # [ 1.011902] Run /init as init process833builder # [ 1.020499] clk: Disabling unused clocks834builder # [ 1.020513] PM: genpd: Disabling unused power domains835builder # [ 1.023535] Freeing unused kernel memory: 4736K836builder # [ 1.023713] Run /init as init process837server # [ 1.025434] systemd[1]: Successfully made /usr/ read-only.838builder # [ 1.042739] systemd[1]: Successfully made /usr/ read-only.839server # [ 1.195185] usb 1-1: new high-speed USB device number 2 using ehci-pci840builder # [ 1.208485] usb 1-1: new high-speed USB device number 2 using ehci-pci841server # [ 1.344821] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1842builder # [ 1.358060] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1843server # [ 1.359327] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)844server # [ 1.359350] systemd[1]: Detected virtualization qemu.845server # [ 1.359388] systemd[1]: Detected architecture arm64.846server # [ 1.359396] systemd[1]: Running in initrd.847server # [ 1.359907] systemd[1]: Initializing machine ID from random generator.848server # [ 1.360052] systemd[1]: Hostname set to <server>.849builder # [ 1.376698] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)850builder # [ 1.376722] systemd[1]: Detected virtualization qemu.851builder # [ 1.376764] systemd[1]: Detected architecture arm64.852builder # [ 1.376773] systemd[1]: Running in initrd.853builder # [ 1.377301] systemd[1]: Initializing machine ID from random generator.854builder # [ 1.377449] systemd[1]: Hostname set to <builder>.855server # [ 1.435350] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0856builder # [ 1.444625] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0857server # [ 1.555195] usb 1-2: new high-speed USB device number 3 using ehci-pci858builder # [ 1.564485] usb 1-2: new high-speed USB device number 3 using ehci-pci859server # [ 1.631365] systemd[1]: bpf-restrict-fs: LSM BPF program attached860builder # [ 1.648868] systemd[1]: bpf-restrict-fs: LSM BPF program attached861server # [ 1.704361] systemd[1]: Queued start job for default target Initrd Default Target.862server # [ 1.710799] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2863server # [ 1.710956] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0864server # [ 1.718603] systemd[1]: Created slice Slice /system/modprobe.865server # [ 1.718815] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.866server # [ 1.718867] systemd[1]: Expecting device /dev/disk/by-label/nixos...867server # [ 1.718894] systemd[1]: Reached target Path Units.868builder # [ 1.723207] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2869server # [ 1.718911] systemd[1]: Reached target Slice Units.870server # [ 1.718929] systemd[1]: Reached target Swaps.871server # [ 1.718947] systemd[1]: Reached target Timer Units.872server # [ 1.723434] systemd[1]: Listening on D-Bus System Message Bus Socket.873builder # [ 1.727018] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0874server # [ 1.723621] systemd[1]: Listening on Journal Socket (/dev/log).875server # [ 1.723761] systemd[1]: Listening on Journal Sockets.876server # [ 1.723893] systemd[1]: Listening on udev Control Socket.877server # [ 1.724007] systemd[1]: Listening on udev Kernel Socket.878server # [ 1.724026] systemd[1]: Reached target Socket Units.879server # [ 1.733592] systemd[1]: Starting Create List of Static Device Nodes...880builder # [ 1.733192] systemd[1]: Queued start job for default target Initrd Default Target.881server # [ 1.733662] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs882builder # [ 1.743058] systemd[1]: Created slice Slice /system/modprobe.883builder # [ 1.743281] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.884builder # [ 1.743314] systemd[1]: Expecting device /dev/disk/by-label/nixos...885builder # [ 1.743338] systemd[1]: Reached target Path Units.886server # [ 1.743296] systemd[1]: Mounting Kernel Configuration File System...887builder # [ 1.743354] systemd[1]: Reached target Slice Units.888builder # [ 1.743371] systemd[1]: Reached target Swaps.889builder # [ 1.743386] systemd[1]: Reached target Timer Units.890builder # [ 1.743578] systemd[1]: Listening on D-Bus System Message Bus Socket.891builder # [ 1.743747] systemd[1]: Listening on Journal Socket (/dev/log).892builder # [ 1.744045] systemd[1]: Listening on Journal Sockets.893builder # [ 1.744179] systemd[1]: Listening on udev Control Socket.894server # [ 1.752499] systemd[1]: Starting Journal Service...895builder # [ 1.744259] systemd[1]: Listening on udev Kernel Socket.896builder # [ 1.744275] systemd[1]: Reached target Socket Units.897builder # [ 1.758611] systemd[1]: Starting Create List of Static Device Nodes...898builder # [ 1.758682] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs899builder # [ 1.768347] systemd[1]: Mounting Kernel Configuration File System...900server # [ 1.767503] systemd[1]: Starting Load Kernel Modules...901server # [ 1.767602] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os902builder # [ 1.784593] systemd[1]: Starting Journal Service...903builder # [ 1.789267] systemd[1]: Starting Load Kernel Modules...904builder # [ 1.789367] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os905builder # [ 1.800101] systemd[1]: Starting Coldplug All udev Devices...906server # [ 1.808170] systemd-journald[71]: Collecting audit messages is disabled.907server # [ 1.811304] systemd[1]: Starting Coldplug All udev Devices...908server # [ 1.818333] systemd[1]: Finished Create List of Static Device Nodes.909server # [ 1.819065] systemd[1]: Mounted Kernel Configuration File System.910builder # [ 1.824594] systemd[1]: Finished Create List of Static Device Nodes.911builder # [ 1.829820] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...912server # [ 1.831486] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...913builder # [ 1.839008] systemd-journald[71]: Collecting audit messages is disabled.914builder # [ 1.846718] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.915builder # [ 1.852804] systemd[1]: Mounted Kernel Configuration File System.916builder # [ 1.857450] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev917builder # [ 1.875966] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0918builder # [ 1.876212] [drm] features: -virgl +edid -resource_blob -host_visible919builder # [ 1.876215] [drm] features: -context_init920server # [ 1.876972] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.921server # [ 1.877800] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.922server # [ 1.879484] systemd[1]: Starting Create Static Device Nodes in /dev...923builder # [ 1.889112] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.924builder # [ 1.890509] systemd[1]: Starting Create Static Device Nodes in /dev...925builder # [ 1.897148] [drm] number of scanouts: 1926builder # [ 1.897163] [drm] number of cap sets: 0927builder # [ 1.897680] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic928builder # [ 1.897689] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0929server # [ 1.895251] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev930server # [ 1.909862] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0931server # [ 1.910097] [drm] features: -virgl +edid -resource_blob -host_visible932server # [ 1.910100] [drm] features: -context_init933server # [ 1.910823] [drm] number of scanouts: 1934server # [ 1.910898] [drm] number of cap sets: 0935builder # [ 1.924873] Console: switching to colour frame buffer device 160x50936server # [ 1.927782] systemd[1]: Finished Create Static Device Nodes in /dev.937server # [ 1.928122] systemd[1]: Reached target Preparation for Local File Systems.938server # [ 1.928147] systemd[1]: Reached target Local File Systems.939server # [ 1.929660] systemd[1]: Starting Rule-based Manager for Device Events and Files...940server # [ 1.935533] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic941server # [ 1.935543] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0942builder # [ 1.945124] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device943builder # [ 1.946511] systemd[1]: Finished Create Static Device Nodes in /dev.944builder # [ 1.946691] systemd[1]: Reached target Preparation for Local File Systems.945builder # [ 1.946722] systemd[1]: Reached target Local File Systems.946builder # [ 1.952132] systemd[1]: Starting Rule-based Manager for Device Events and Files...947server # [ 1.967507] Console: switching to colour frame buffer device 160x50948builder # [ 1.976596] systemd[1]: Finished Load Kernel Modules.949builder # [ 1.978280] systemd[1]: Starting Apply Kernel Variables...950server # [ 1.974817] systemd-modules-load[73]: Inserted module 'dm_mod'951server # [ 1.987605] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device952server # [ 1.987903] systemd[1]: Started Journal Service.953server # [ 1.984550] systemd-modules-load[73]: Module 'virtio_balloon' is built in954server # [ 1.986478] systemd-modules-load[73]: Module 'virtio_console' is built in955server # [ 1.996348] systemd[1]: Starting Create System Files and Directories...956server # [ 1.998550] systemd-modules-load[73]: Inserted module 'virtio_gpu'957server # [ 2.000451] systemd-modules-load[73]: Module 'virtio_rng' is built in958builder # [ 2.003203] systemd-modules-load[72]: Inserted module 'dm_mod'959server # [ 2.004284] systemd[1]: Finished Load Kernel Modules.960builder # [ 2.006706] systemd-modules-load[72]: Module 'virtio_balloon' is built in961builder # [ 2.020874] systemd[1]: Started Journal Service.962builder # [ 2.017156] systemd-modules-load[72]: Module 'virtio_console' is built in963builder # [ 2.020388] systemd-modules-load[72]: Inserted module 'virtio_gpu'964builder # [ 2.022547] systemd-modules-load[72]: Module 'virtio_rng' is built in965server # [ 2.024120] systemd[1]: Starting Apply Kernel Variables...966server # [ 2.029473] systemd-udevd[77]: Using default interface naming scheme 'v261'.967builder # [ 2.032324] systemd[1]: Starting Create System Files and Directories...968builder # [ 2.035669] systemd[1]: Finished Apply Kernel Variables.969builder # [ 2.040600] systemd-udevd[78]: Using default interface naming scheme 'v261'.970server # [ 2.042988] systemd[1]: Finished Create System Files and Directories.971builder # [ 2.073465] systemd[1]: Finished Create System Files and Directories.972server # [ 2.074439] systemd[1]: Finished Apply Kernel Variables.973server # [ 2.076128] systemd[1]: Started Rule-based Manager for Device Events and Files.974builder # [ 2.079547] systemd[1]: Started Rule-based Manager for Device Events and Files.975server # [ 2.136101] systemd[1]: Starting Virtual Console Setup...976builder # [ 2.144140] systemd[1]: Starting Virtual Console Setup...977server # [ 2.180439] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.978server # [ 2.183993] systemd[1]: Finished Virtual Console Setup.979builder # [ 2.192478] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones.980builder # [ 2.196044] systemd[1]: Finished Virtual Console Setup.981server # [ 2.794127] systemd[1]: Finished Coldplug All udev Devices.982server # [ 2.795109] systemd[1]: Reached target System Initialization.983server # [ 2.795978] systemd[1]: Reached target Basic System.984builder # [ 2.807307] systemd[1]: Finished Coldplug All udev Devices.985builder # [ 2.808390] systemd[1]: Reached target System Initialization.986builder # [ 2.809239] systemd[1]: Reached target Basic System.987server # [ 2.931757] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.988builder # [ 2.936750] (udev-worker)[99]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.989server # [ 2.960273] (udev-worker)[100]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.990server # [ 2.969044] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line.991builder # [ 2.979947] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.992builder # [ 2.996236] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.993server # [ 3.037926] systemd[1]: Found device /dev/disk/by-label/nixos.994builder # [ 3.046543] systemd[1]: Found device /dev/disk/by-label/nixos.995builder # [ 3.049198] systemd[1]: Reached target Initrd Root Device.996server # [ 3.049870] systemd[1]: Reached target Initrd Root Device.997builder # [ 3.052891] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...998server # [ 3.054360] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...999server # [ 3.099309] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks1000server # [ 3.106716] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1001builder # [ 3.107585] systemd-fsck[108]: nixos: clean, 12/65536 files, 13019/262144 blocks1002server # [ 3.111986] systemd[1]: Mounting /sysroot...1003builder # [ 3.115898] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1004builder # [ 3.125252] systemd[1]: Mounting /sysroot...1005server # [ 3.152926] EXT4-fs (vda): mounted filesystem 811665c3-75b4-4a57-b03f-3960e0c00077 r/w with ordered data mode. Quota mode: none.1006server # [ 3.145006] systemd[1]: Mounted /sysroot.1007server # [ 3.146408] systemd[1]: Reached target Initrd Root File System.1008server # [ 3.156082] systemd[1]: Starting Mountpoints Configured in the Real Root...1009builder # [ 3.169007] EXT4-fs (vda): mounted filesystem d6d8dda6-7ed0-469e-977e-297a9d80bfeb r/w with ordered data mode. Quota mode: none.1010builder # [ 3.159097] systemd[1]: Mounted /sysroot.1011builder # [ 3.160429] systemd[1]: Reached target Initrd Root File System.1012builder # [ 3.164376] systemd[1]: Starting Mountpoints Configured in the Real Root...1013server # [ 3.175665] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.1014server # [ 3.181661] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...1015server # [ 3.187411] systemd[1]: Reloading...1016builder # [ 3.191069] systemd-sysroot-fstab-check[116]: /sysroot should be mounted in the initrd, will request daemon-reload.1017builder # [ 3.199316] systemd[1]: Reload requested from client PID 116 ('systemd-sysroot') (unit initrd-parse-etc.service)...1018builder # [ 3.202977] systemd[1]: Reloading...1019server # [ 3.396558] systemd[1]: Reloading finished in 212 ms.1020builder # [ 3.408511] systemd[1]: Reloading finished in 208 ms.1021server # [ 3.428355] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...1022server # [ 3.432215] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...1023server # [ 3.439310] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1024builder # [ 3.440594] systemd-sysroot-fstab-check[116]: Requesting initrd-fs.target/start/replace...1025server # [ 3.442597] systemd[1]: Finished Mountpoints Configured in the Real Root.1026builder # [ 3.444904] systemd-sysroot-fstab-check[116]: Requesting swap.target/start/replace...1027server # [ 3.447360] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1028builder # [ 3.451347] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1029builder # [ 3.454199] systemd[1]: Finished Mountpoints Configured in the Real Root.1030builder # [ 3.456408] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1031server # [ 3.805286] systemd[1]: Mounting /sysroot/nix/.ro-store...1032builder # [ 3.810369] systemd[1]: Mounting /sysroot/nix/.ro-store...1033server # [ 3.823445] systemd[1]: Mounting /sysroot/nix/.rw-store...1034server # [ 3.827706] systemd[1]: Mounting /sysroot/run...1035builder # [ 3.827711] systemd[1]: Mounting /sysroot/nix/.rw-store...1036builder # [ 3.832335] systemd[1]: Mounting /sysroot/run...1037server # [ 3.839637] systemd[1]: Mounting /sysroot/tmp/shared...1038builder # [ 3.861596] systemd[1]: Mounting /sysroot/tmp/shared...1039server # [ 3.870170] systemd[1]: Mounting /sysroot/tmp/xchg...1040builder # [ 3.871995] systemd[1]: Mounting /sysroot/tmp/xchg...1041builder # [ 3.923735] fuse: init (API version 7.45)1042server # [ 3.932069] fuse: init (API version 7.45)1043server # [ 3.922290] systemd[1]: Mounted /sysroot/nix/.rw-store.1044builder # [ 3.940598] virtiofs virtio6: discovered new tag: nix-store1045server # [ 3.941755] virtiofs virtio6: discovered new tag: nix-store1046builder # [ 3.941405] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1047server # [ 3.942548] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1048builder # [ 3.932858] systemd[1]: Mounted /sysroot/nix/.rw-store.1049builder # [ 3.958051] virtiofs virtio7: discovered new tag: shared1050server # [ 3.956827] virtiofs virtio7: discovered new tag: shared1051builder # [ 3.958822] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1052server # [ 3.957593] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1053builder # [ 3.963917] virtiofs virtio8: discovered new tag: xchg1054server # [ 3.965409] virtiofs virtio8: discovered new tag: xchg1055server # [ 3.966184] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1056server # [ 3.956136] systemd[1]: Starting rw-sysroot-nix-store.service...1057builder # [ 3.974140] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1058builder # [ 3.965172] systemd[1]: Starting rw-sysroot-nix-store.service...1059server # [ 3.970900] systemd[1]: Mounted /sysroot/run.1060builder # [ 3.976101] systemd[1]: Mounted /sysroot/run.1061server # [ 3.977531] systemd[1]: Mounted /sysroot/nix/.ro-store.1062server # [ 3.981514] systemd[1]: Mounted /sysroot/tmp/shared.1063server # [ 3.984100] systemd[1]: Mounted /sysroot/tmp/xchg.1064builder # [ 3.994686] systemd[1]: Mounted /sysroot/nix/.ro-store.1065builder # [ 3.997570] systemd[1]: Mounted /sysroot/tmp/shared.1066server # [ 4.001581] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1067server # [ 4.004187] systemd[1]: Finished rw-sysroot-nix-store.service.1068builder # [ 4.006375] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1069server # [ 4.008165] systemd[1]: Mounting /sysroot/nix/store...1070builder # [ 4.010936] systemd[1]: Finished rw-sysroot-nix-store.service.1071builder # [ 4.012694] systemd[1]: Mounted /sysroot/tmp/xchg.1072server # [ 4.050613] systemd[1]: Mounted /sysroot/nix/store.1073server # [ 4.052570] systemd[1]: Reached target Initrd File Systems.1074server # [ 4.054572] systemd[1]: Starting Find NixOS closure...1075server # [ 4.065769] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1076server # [ 4.086531] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1077server # [ 4.105802] systemd[1]: Finished Find NixOS closure.1078server # [ 4.109157] systemd[1]: Reached target Initrd Default Target.1079server # [ 4.112206] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1080server # [ 4.146384] systemd[1]: Stopped target Initrd Default Target.1081server # [ 4.151108] systemd[1]: Stopped target Basic System.1082server # [ 4.152697] systemd[1]: Stopped target Initrd Root Device.1083server # [ 4.154683] systemd[1]: Stopped target Path Units.1084server # [ 4.155957] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1085server # [ 4.159809] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1086server # [ 4.165671] systemd[1]: Stopped target Slice Units.1087server # [ 4.167866] systemd[1]: Stopped target Socket Units.1088server # [ 4.170594] systemd[1]: Stopped target System Initialization.1089server # [ 4.173002] systemd[1]: Stopped target Swaps.1090server # [ 4.177577] systemd[1]: Stopped target Timer Units.1091server # [ 4.178399] systemd[1]: dbus.socket: Deactivated successfully.1092server # [ 4.181397] systemd[1]: Closed D-Bus System Message Bus Socket.1093server # [ 4.184149] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1094server # [ 4.187605] systemd[1]: Stopped Find NixOS closure.1095server # [ 4.189762] systemd[1]: Starting rw-sysroot-nix-store.service...1096server # [ 4.194323] systemd[1]: systemd-sysctl.service: Deactivated successfully.1097server # [ 4.195467] systemd[1]: Stopped Apply Kernel Variables.1098server # [ 4.200397] systemd[1]: systemd-modules-load.service: Deactivated successfully.1099server # [ 4.204324] systemd[1]: Stopped Load Kernel Modules.1100server # [ 4.207593] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1101server # [ 4.211906] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1102server # [ 4.213169] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1103server # [ 4.214222] systemd[1]: Stopped Create System Files and Directories.1104server # [ 4.215131] systemd[1]: Stopped target Local File Systems.1105server # [ 4.223650] systemd[1]: Stopped target Preparation for Local File Systems.1106server # [ 4.224898] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1107server # [ 4.225997] systemd[1]: Stopped Coldplug All udev Devices.1108server # [ 4.226799] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1109server # [ 4.227863] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1110server # [ 4.230020] systemd[1]: Stopped Virtual Console Setup.1111server # [ 4.230807] systemd[1]: initrd-cleanup.service: Deactivated successfully.1112server # [ 4.231853] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1113server # [ 4.232936] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1114server # [ 4.233964] systemd[1]: Finished rw-sysroot-nix-store.service.1115server # [ 4.234798] systemd[1]: systemd-udevd.service: Deactivated successfully.1116server # [ 4.235744] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1117server # [ 4.244287] systemd[1]: systemd-udevd.service: Consumed 1.126s CPU time over 2.303s wall clock time, 17.9M memory peak.1118server # [ 4.245807] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1119server # [ 4.248380] systemd[1]: Closed udev Control Socket.1120server # [ 4.252173] systemd[1]: Starting Cleanup udev Database...1121server # [ 4.252993] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1122server # [ 4.254083] systemd[1]: Stopped Create Static Device Nodes in /dev.1123server # [ 4.256214] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1124server # [ 4.260358] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1125server # [ 4.261422] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1126server # [ 4.264190] systemd[1]: Stopped Create List of Static Device Nodes.1127server # [ 4.281488] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1128server # [ 4.284877] systemd[1]: Finished Cleanup udev Database.1129server # [ 4.285708] systemd[1]: Reached target Switch Root.1130server # [ 4.288159] systemd[1]: Starting NixOS Activation...1131server # [ 4.357072] initrd-nixos-activation-start[166]: booting system configuration /nix/store/k66cp5bym2y2x0gahgxdnb3hdjs131vh-nixos-system-server-test1132server # [ 4.385920] initrd-nixos-activation-start[166]: running activation script...1133builder # [ 4.386378] (udev-worker)[90]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory1134builder # [ 4.395382] (udev-worker)[90]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1135builder # [ 4.422638] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1136builder # [ 4.424490] systemd[1]: Stopped Virtual Console Setup.1137builder # [ 4.428179] systemd[1]: Stopping Virtual Console Setup...1138builder # [ 4.429074] systemd[1]: Starting Virtual Console Setup...1139builder # [ 4.444932] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1140builder # [ 4.446458] systemd[1]: Stopped Virtual Console Setup.1141builder # [ 4.449700] systemd[1]: Starting Virtual Console Setup...1142builder # [ 4.476996] systemd-vconsole-setup[151]: Configuration of first virtual console was skipped, ignoring remaining ones.1143builder # [ 4.480332] systemd[1]: Finished Virtual Console Setup.1144server # [ 4.582824] initrd-nixos-activation-start[189]: setting up /etc...1145server # [ 4.690912] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1146server # [ 4.693744] systemd[1]: Finished NixOS Activation.1147server # [ 4.694941] systemd[1]: Starting Switch Root...1148server # [ 4.710574] systemd[1]: Switching root.1149builder # [ 4.808918] systemd[1]: Mounting /sysroot/nix/store...1150builder # [ 4.854661] systemd[1]: Mounted /sysroot/nix/store.1151builder # [ 4.857894] systemd[1]: Reached target Initrd File Systems.1152builder # [ 4.860342] systemd[1]: Starting Find NixOS closure...1153builder # [ 4.865707] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1154server # [ 4.896558] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1155builder # [ 4.908145] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1156builder # [ 4.909879] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1157builder # [ 4.922765] systemd[1]: Finished Find NixOS closure.1158builder # [ 4.925490] systemd[1]: Reached target Initrd Default Target.1159builder # [ 4.927143] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1160builder # [ 4.955234] systemd[1]: Stopped target Initrd Default Target.1161builder # [ 4.957188] systemd[1]: Stopped target Basic System.1162builder # [ 4.960427] systemd[1]: Stopped target Initrd Root Device.1163builder # [ 4.961523] systemd[1]: Stopped target Path Units.1164builder # [ 4.963304] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1165builder # [ 4.966466] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1166builder # [ 4.969779] systemd[1]: Stopped target Slice Units.1167builder # [ 4.972150] systemd[1]: Stopped target Socket Units.1168builder # [ 4.973821] systemd[1]: Stopped target System Initialization.1169builder # [ 4.979114] systemd[1]: Stopped target Swaps.1170builder # [ 4.980977] systemd[1]: Stopped target Timer Units.1171builder # [ 4.982786] systemd[1]: dbus.socket: Deactivated successfully.1172builder # [ 4.992277] systemd[1]: Closed D-Bus System Message Bus Socket.1173builder # [ 4.994102] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1174builder # [ 4.998446] systemd[1]: Stopped Find NixOS closure.1175builder # [ 5.003119] systemd[1]: Starting rw-sysroot-nix-store.service...1176builder # [ 5.006552] systemd[1]: systemd-sysctl.service: Deactivated successfully.1177builder # [ 5.007565] systemd[1]: Stopped Apply Kernel Variables.1178builder # [ 5.010671] systemd[1]: systemd-modules-load.service: Deactivated successfully.1179builder # [ 5.015123] systemd[1]: Stopped Load Kernel Modules.1180builder # [ 5.015956] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1181builder # [ 5.017287] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1182builder # [ 5.020514] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1183builder # [ 5.022348] systemd[1]: Stopped Create System Files and Directories.1184builder # [ 5.024182] systemd[1]: Stopped target Local File Systems.1185builder # [ 5.026800] systemd[1]: Stopped target Preparation for Local File Systems.1186builder # [ 5.027963] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1187builder # [ 5.029224] systemd[1]: Stopped Coldplug All udev Devices.1188builder # [ 5.030032] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1189builder # [ 5.031100] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1190builder # [ 5.036343] systemd[1]: Stopped Virtual Console Setup.1191builder # [ 5.037164] systemd[1]: initrd-cleanup.service: Deactivated successfully.1192builder # [ 5.040119] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1193builder # [ 5.041242] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1194builder # [ 5.044345] systemd[1]: Finished rw-sysroot-nix-store.service.1195builder # [ 5.048123] systemd[1]: systemd-udevd.service: Deactivated successfully.1196builder # [ 5.049107] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1197builder # [ 5.050139] systemd[1]: systemd-udevd.service: Consumed 1.434s CPU time over 3.074s wall clock time, 21.9M memory peak.1198builder # [ 5.056337] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1199builder # [ 5.057414] systemd[1]: Closed udev Control Socket.1200builder # [ 5.058152] systemd[1]: Starting Cleanup udev Database...1201builder # [ 5.058980] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1202builder # [ 5.064467] systemd[1]: Stopped Create Static Device Nodes in /dev.1203builder # [ 5.065381] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1204builder # [ 5.066526] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1205builder # [ 5.067612] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1206builder # [ 5.072130] systemd[1]: Stopped Create List of Static Device Nodes.1207builder # [ 5.088383] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1208builder # [ 5.091034] systemd[1]: Finished Cleanup udev Database.1209builder # [ 5.096280] systemd[1]: Reached target Switch Root.1210builder # [ 5.097091] systemd[1]: Starting NixOS Activation...1211builder # [ 5.167910] initrd-nixos-activation-start[174]: booting system configuration /nix/store/ps81lgv9798c7qwl9q77am5i39ab1lc1-nixos-system-builder-test1212builder # [ 5.198325] initrd-nixos-activation-start[174]: running activation script...1213server # [ 5.387552] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1214server # [ 5.387611] systemd[1]: Detected virtualization qemu.1215server # [ 5.387666] systemd[1]: Detected architecture arm64.1216server # [ 5.387768] systemd[1]: Detected first boot.1217server # [ 5.389397] systemd[1]: Initializing machine ID from random generator.1218builder # [ 5.389170] initrd-nixos-activation-start[197]: setting up /etc...1219builder # [ 5.498087] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1220builder # [ 5.500981] systemd[1]: Finished NixOS Activation.1221builder # [ 5.504188] systemd[1]: Starting Switch Root...1222builder # [ 5.519466] systemd[1]: Switching root.1223server # [ 5.665600] systemd[1]: bpf-restrict-fs: LSM BPF program attached1224builder # [ 5.697642] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1225server # [ 5.800464] systemd[1]: Applying preset policy.1226server # [ 5.998199] systemd[1]: Populated /etc with preset unit settings.1227builder # [ 6.176583] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1228builder # [ 6.176640] systemd[1]: Detected virtualization qemu.1229builder # [ 6.176691] systemd[1]: Detected architecture arm64.1230builder # [ 6.176784] systemd[1]: Detected first boot.1231builder # [ 6.178592] systemd[1]: Initializing machine ID from random generator.1232server # [ 6.211012] systemd[1]: initrd-switch-root.service: Deactivated successfully.1233server # [ 6.213405] systemd[1]: Stopped initrd-switch-root.service.1234server # [ 6.217497] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1235server # [ 6.220588] systemd[1]: Created slice Slice /system/getty.1236server # [ 6.222102] systemd[1]: Created slice User and Session Slice.1237server # [ 6.222670] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1238server # [ 6.226646] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1239server # [ 6.227018] systemd[1]: Expecting device /dev/hvc0...1240server # [ 6.229026] systemd[1]: Expecting device /dev/ttyAMA0...1241server # [ 6.229682] systemd[1]: Reached target Local Encrypted Volumes.1242server # [ 6.230310] systemd[1]: Stopped target initrd-fs.target.1243server # [ 6.230995] systemd[1]: Stopped target initrd-root-fs.target.1244server # [ 6.234504] systemd[1]: Stopped target initrd-switch-root.target.1245server # [ 6.235113] systemd[1]: Reached target Virtual Machines and Containers.1246server # [ 6.237387] systemd[1]: Reached target Path Units.1247server # [ 6.237978] systemd[1]: Reached target Remote File Systems.1248server # [ 6.238555] systemd[1]: Reached target Slice Units.1249server # [ 6.241702] systemd[1]: Reached target Swaps.1250server # [ 6.243531] systemd[1]: Listening on Query the User Interactively for a Password.1251server # [ 6.247048] systemd[1]: Listening on Process Core Dump Socket.1252server # [ 6.249190] systemd[1]: Listening on Credential Encryption/Decryption.1253server # [ 6.250643] systemd[1]: Listening on Factory Reset Management.1254server # [ 6.253728] systemd[1]: Listening on Hostname Service Socket.1255server # [ 6.257447] systemd[1]: Starting Journal Log Access Socket...1256server # [ 6.258946] systemd[1]: Listening on Journal Audit Socket.1257server # [ 6.262416] systemd[1]: Listening on Console Output Muting Service Socket.1258server # [ 6.263104] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1259server # [ 6.265402] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1260server # [ 6.266649] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1261server # [ 6.273544] systemd[1]: Listening on Disk Repartitioning Service Socket.1262server # [ 6.274072] systemd[1]: Listening on udev Control Socket.1263server # [ 6.274619] systemd[1]: Listening on udev Varlink Socket.1264server # [ 6.279765] systemd[1]: Mounting Huge Pages File System...1265server # [ 6.282449] systemd[1]: Mounting POSIX Message Queue File System...1266server # [ 6.297993] systemd[1]: Mounting Kernel Debug File System...1267server # [ 6.308487] systemd[1]: Mounting Kernel Trace File System...1268server # [ 6.323702] systemd[1]: Starting Create List of Static Device Nodes...1269server # [ 6.324076] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1270server # [ 6.335988] systemd[1]: Mounting Kernel Configuration File System...1271server # [ 6.336393] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1272server # [ 6.336665] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1273server # [ 6.336935] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1274server # [ 6.361075] systemd[1]: Mounting FUSE Control File System...1275server # [ 6.361509] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671276server # [ 6.395076] systemd[1]: Starting Journal Service...1277server # [ 6.413490] systemd[1]: Starting Load Kernel Modules...1278server # [ 6.433606] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1279server # [ 6.439262] systemd[1]: Starting Remount Root and Kernel File Systems...1280server # [ 6.439944] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1281server # [ 6.446144] systemd[1]: Starting Coldplug All udev Devices...1282server # [ 6.450736] systemd[1]: Listening on Journal Log Access Socket.1283server # [ 6.457021] systemd[1]: Mounted Huge Pages File System.1284server # [ 6.457591] systemd[1]: Mounted POSIX Message Queue File System.1285builder # [ 6.459930] systemd[1]: bpf-restrict-fs: LSM BPF program attached1286server # [ 6.458286] systemd[1]: Mounted Kernel Debug File System.1287server # [ 6.458981] systemd[1]: Mounted Kernel Trace File System.1288server # [ 6.463208] systemd[1]: Mounted Kernel Configuration File System.1289server # [ 6.463843] systemd[1]: Mounted FUSE Control File System.1290server # [ 6.491509] systemd[1]: Finished Create List of Static Device Nodes.1291server # [ 6.500325] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1292server # [ 6.556631] systemd-journald[260]: Collecting audit messages is enabled.1293server # [ 6.576642] EXT4-fs (vda): re-mounted 811665c3-75b4-4a57-b03f-3960e0c00077.1294server # [ 6.585330] systemd[1]: Finished Remount Root and Kernel File Systems.1295server # [ 6.586045] systemd[1]: Listening on Disk Image Download Service Socket.1296server # [ 6.586591] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1297server # [ 6.596286] systemd[1]: Starting Load/Save OS Random Seed...1298server # [ 6.597362] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1299server # [ 6.598954] systemd[1]: Finished Load Kernel Modules.1300server # [ 6.594047] systemd[1]: Queued start job for default target Multi-User System.1301builder # [ 6.612402] systemd[1]: Applying preset policy.1302server # [ 6.615667] systemd[1]: Starting Firewall...1303server # [ 6.611624] systemd[1]: systemd-journald.service: Deactivated successfully.[ 6.628486] systemd[1]: Starting Apply Kernel Variables...1304server # [ 6.629668] systemd[1]: Started Journal Service.1305server # 1306server # [ 6.625368] systemd-modules-load[261]: Module 'atkbd' is built in1307server # [ 6.638287] systemd-modules-load[261]: Module 'loop' is built in1308server # [ 6.648934] systemd-modules-load[261]: Inserted module 'tls'1309server # [ 6.656482] systemd-modules-load[261]: Module 'tun' is built in1310server # [ 6.667128] systemd[1]: Starting Flush Journal to Persistent Storage...1311server # [ 6.718276] systemd[1]: Finished Load/Save OS Random Seed.1312server # [ 6.726979] systemd[1]: Reached target First Boot Complete.1313server # [ 6.736547] systemd-oomd[263]: No swap; memory pressure usage will be degraded1314server # [ 6.760409] systemd-journald[260]: Received client request to flush runtime journal.1315builder # [ 6.810277] systemd[1]: Populated /etc with preset unit settings.1316server # [ 6.813429] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1317server # [ 6.814546] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1318server # [ 6.822461] systemd[1]: Starting Create Static Device Nodes in /dev...1319server # [ 6.823528] systemd[1]: Finished Apply Kernel Variables.1320server # [ 6.824674] systemd[1]: Finished Flush Journal to Persistent Storage.1321server # [ 6.923203] systemd[1]: Finished Create Static Device Nodes in /dev.1322server # [ 6.928305] systemd[1]: Reached target Preparation for Local File Systems.1323server # [ 6.936815] systemd[1]: Starting Rule-based Manager for Device Events and Files...1324builder # [ 7.015896] systemd[1]: initrd-switch-root.service: Deactivated successfully.1325builder # [ 7.018511] systemd[1]: Stopped initrd-switch-root.service.1326builder # [ 7.022658] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1327builder # [ 7.026098] systemd[1]: Created slice Slice /system/getty.1328builder # [ 7.027348] systemd[1]: Created slice User and Session Slice.1329builder # [ 7.027725] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1330builder # [ 7.028071] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1331builder # [ 7.028335] systemd[1]: Expecting device /dev/hvc0...1332builder # [ 7.033511] systemd[1]: Expecting device /dev/ttyAMA0...1333builder # [ 7.033922] systemd[1]: Reached target Local Encrypted Volumes.1334builder # [ 7.034551] systemd[1]: Stopped target initrd-fs.target.1335builder # [ 7.035144] systemd[1]: Stopped target initrd-root-fs.target.1336builder # [ 7.035729] systemd[1]: Stopped target initrd-switch-root.target.1337builder # [ 7.036358] systemd[1]: Reached target Virtual Machines and Containers.1338builder # [ 7.042127] systemd[1]: Reached target Path Units.1339builder # [ 7.042514] systemd[1]: Reached target Remote File Systems.1340builder # [ 7.043105] systemd[1]: Reached target Slice Units.1341builder # [ 7.043720] systemd[1]: Reached target Swaps.1342builder # [ 7.048655] systemd[1]: Listening on Query the User Interactively for a Password.1343builder # [ 7.050514] systemd[1]: Listening on Process Core Dump Socket.1344builder # [ 7.051978] systemd[1]: Listening on Credential Encryption/Decryption.1345builder # [ 7.056565] systemd[1]: Listening on Factory Reset Management.1346builder # [ 7.057055] systemd[1]: Listening on Hostname Service Socket.1347builder # [ 7.060219] systemd[1]: Starting Journal Log Access Socket...1348builder # [ 7.063945] systemd[1]: Listening on Journal Audit Socket.1349builder # [ 7.067129] systemd[1]: Listening on Console Output Muting Service Socket.1350builder # [ 7.067849] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1351builder # [ 7.068319] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1352builder # [ 7.072096] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1353builder # [ 7.078848] systemd[1]: Listening on Disk Repartitioning Service Socket.1354builder # [ 7.079379] systemd[1]: Listening on udev Control Socket.1355builder # [ 7.080030] systemd[1]: Listening on udev Varlink Socket.1356builder # [ 7.085518] systemd[1]: Mounting Huge Pages File System...1357builder # [ 7.092595] systemd[1]: Mounting POSIX Message Queue File System...1358builder # [ 7.100589] systemd[1]: Mounting Kernel Debug File System...1359builder # [ 7.108656] systemd[1]: Mounting Kernel Trace File System...1360builder # [ 7.120713] systemd[1]: Starting Create List of Static Device Nodes...1361builder # [ 7.121050] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1362builder # [ 7.138302] systemd[1]: Mounting Kernel Configuration File System...1363builder # [ 7.141503] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1364builder # [ 7.144421] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1365builder # [ 7.149652] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1366builder # [ 7.161486] systemd[1]: Mounting FUSE Control File System...1367builder # [ 7.161853] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671368server # [ 7.161906] systemd-udevd[302]: Using default interface naming scheme 'v261'.1369builder # [ 7.204977] systemd[1]: Starting Journal Service...1370server # [ 7.200000] systemd[1]: Mounting /run/wrappers...1371builder # [ 7.227199] systemd[1]: Starting Load Kernel Modules...1372builder # [ 7.241328] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1373builder # [ 7.246111] systemd[1]: Starting Remount Root and Kernel File Systems...1374builder # [ 7.246846] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1375builder # [ 7.257128] systemd[1]: Starting Coldplug All udev Devices...1376builder # [ 7.263717] systemd[1]: Listening on Journal Log Access Socket.1377builder # [ 7.266042] systemd[1]: Mounted Huge Pages File System.1378builder # [ 7.267278] systemd[1]: Mounted POSIX Message Queue File System.1379server # [ 7.257023] systemd[1]: Mounted /run/wrappers.1380builder # [ 7.270222] systemd[1]: Mounted Kernel Debug File System.1381builder # [ 7.271338] systemd[1]: Mounted Kernel Trace File System.1382builder # [ 7.272395] systemd[1]: Mounted Kernel Configuration File System.1383builder # [ 7.276781] systemd[1]: Mounted FUSE Control File System.1384server # [ 7.264430] systemd[1]: Reached target Local File Systems.1385server # [ 7.276414] systemd[1]: Listening on Boot Loader Control Service Socket.1386builder # [ 7.294898] systemd[1]: Finished Create List of Static Device Nodes.1387builder # [ 7.300237] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1388server # [ 7.293726] systemd[1]: Starting register-nix-paths.service...1389server # [ 7.301329] systemd[1]: Starting Create SUID/SGID Wrappers...1390server # [ 7.306324] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1391server # [ 7.312648] systemd[1]: Starting Save Transient machine-id to Disk...1392server # [ 7.319479] systemd[1]: Starting Create System Files and Directories...1393builder # [ 7.375583] systemd[1]: Finished Load Kernel Modules.1394builder # [ 7.379516] systemd-journald[267]: Collecting audit messages is enabled.1395builder # [ 7.383872] systemd[1]: Starting Firewall...1396builder # [ 7.393115] EXT4-fs (vda): re-mounted d6d8dda6-7ed0-469e-977e-297a9d80bfeb.1397builder # [ 7.395615] systemd[1]: Starting Apply Kernel Variables...1398builder # [ 7.408684] systemd[1]: Finished Remount Root and Kernel File Systems.1399builder # [ 7.409376] systemd[1]: Listening on Disk Image Download Service Socket.1400builder # [ 7.409831] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1401builder # [ 7.424585] systemd[1]: Starting Load/Save OS Random Seed...1402builder # [ 7.425604] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1403server # [ 7.415579] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1404builder # [ 7.415775] systemd[1]: Queued start job for default target Multi-User System.1405builder # [ 7.431568] systemd[1]: Started Journal Service.1406builder # [ 7.421088] systemd[1]: systemd-journald.service: Deactivated successfully.1407server # [ 7.423111] systemd[1]: Started Rule-based Manager for Device Events and Files.1408server # [ 7.436419] systemd[1]: Finished Save Transient machine-id to Disk.1409builder # [ 7.436475] systemd-modules-load[268]: Module 'atkbd' is built in1410builder # [ 7.450190] systemd-modules-load[268]: Module 'loop' is built in1411builder # [ 7.460968] systemd-modules-load[268]: Module 'tun' is built in1412builder # [ 7.469349] systemd[1]: Starting Flush Journal to Persistent Storage...1413server # [ 7.542226] systemd[1]: Finished Create System Files and Directories.1414builder # [ 7.550336] systemd-oomd[270]: No swap; memory pressure usage will be degraded1415server # [ 7.551300] systemd[1]: Starting Rebuild Journal Catalog...1416server # [ 7.568904] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1417builder # [ 7.569865] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1418builder # [ 7.594828] systemd-journald[267]: Received client request to flush runtime journal.1419builder # [ 7.640793] systemd[1]: Starting Create Static Device Nodes in /dev...1420builder # [ 7.641968] systemd[1]: Finished Load/Save OS Random Seed.1421builder # [ 7.648296] systemd[1]: Reached target First Boot Complete.1422builder # [ 7.649209] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1423builder # [ 7.650164] systemd[1]: Finished Apply Kernel Variables.1424builder # [ 7.650965] systemd[1]: Finished Flush Journal to Persistent Storage.1425server # [ 7.701479] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1426builder # [ 7.731723] systemd[1]: Finished Create Static Device Nodes in /dev.1427builder # [ 7.733677] systemd[1]: Reached target Preparation for Local File Systems.1428builder # [ 7.738037] systemd[1]: Starting Rule-based Manager for Device Events and Files...1429server # [ 7.778652] systemd[1]: Finished Rebuild Journal Catalog.1430server # [ 7.782551] systemd[1]: Starting Update is Completed...1431builder # [ 7.858346] systemd-udevd[305]: Using default interface naming scheme 'v261'.1432server # [ 7.914965] systemd[1]: Finished Update is Completed.1433builder # [ 7.973438] systemd[1]: Started Rule-based Manager for Device Events and Files.1434builder # [ 8.011180] systemd[1]: Mounting /run/wrappers...1435builder # [ 8.065414] systemd[1]: Mounted /run/wrappers.1436builder # [ 8.066244] systemd[1]: Reached target Local File Systems.1437builder # [ 8.070159] systemd[1]: Listening on Boot Loader Control Service Socket.1438builder # [ 8.079470] systemd[1]: Starting register-nix-paths.service...1439builder # [ 8.083340] systemd[1]: Starting Create SUID/SGID Wrappers...1440builder # [ 8.094783] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1441builder # [ 8.102925] systemd[1]: Starting Save Transient machine-id to Disk...1442builder # [ 8.110112] systemd[1]: Starting Create System Files and Directories...1443builder # [ 8.211979] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1444builder # [ 8.221875] systemd[1]: Finished Save Transient machine-id to Disk.1445server # [ 8.318476] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1446server # [ 8.325439] systemd[1]: Finished Create SUID/SGID Wrappers.1447builder # [ 8.326974] systemd[1]: Finished Create System Files and Directories.1448builder # [ 8.332445] systemd[1]: Starting Rebuild Journal Catalog...1449builder # [ 8.346110] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1450builder # [ 8.453972] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1451server # [ 8.506198] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31452server # [ 8.499115] systemd[1]: Finished register-nix-paths.service.1453builder # [ 8.514660] systemd[1]: Finished Rebuild Journal Catalog.1454builder # [ 8.518848] systemd[1]: Starting Update is Completed...1455server # [ 8.578987] systemd[1]: Finished Firewall.1456builder # [ 8.620672] systemd[1]: Finished Update is Completed.1457server # [ 8.844595] systemd[1]: Finished Coldplug All udev Devices.1458server # [ 8.845787] systemd[1]: Reached target System Initialization.1459server # [ 8.846707] systemd[1]: Started Discard unused filesystem blocks once a week.1460server # [ 8.852353] systemd[1]: Started niks3 garbage collection timer.1461server # [ 8.853300] systemd[1]: Started Daily Cleanup of Temporary Directories.1462server # [ 8.856703] systemd[1]: Reached target Timer Units.1463server # [ 8.858970] systemd[1]: Listening on D-Bus System Message Bus Socket.1464server # [ 8.864545] systemd[1]: Starting niks3 server proxy socket...1465server # [ 8.865466] systemd[1]: Listening on niks3 server socket.1466server # [ 8.897534] systemd[1]: Listening on Nix Daemon Socket.1467server # [ 8.898474] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1468server # [ 8.899733] systemd[1]: Listening on niks3 server proxy socket.1469server # [ 8.909361] systemd[1]: Reached target Socket Units.1470server # [ 8.912146] systemd[1]: Starting D-Bus System Message Bus...1471builder # [ 8.930700] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1472builder # [ 8.933341] systemd[1]: Finished Create SUID/SGID Wrappers.1473server # [ 8.981062] dbus-broker-launch[488]: Looking up NSS user entry for 'systemd-timesync'...1474server # [ 8.984382] dbus-broker-launch[488]: NSS returned no entry for 'systemd-timesync'1475server # [ 8.986587] dbus-broker-launch[488]: Invalid user-name in /nix/store/kz4xz6652a26fgram8mjcavl37zj2ng4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1476server # [ 8.998515] systemd[1]: Started D-Bus System Message Bus.1477server # [ 9.004640] systemd[1]: Reached target Basic System.1478server # [ 9.018373] systemd[1]: Starting Import lastlog data into lastlog2 database...1479server # [ 9.030880] systemd[1]: Starting Generate test mTLS certs...1480server # [ 9.036238] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1481server # [ 9.038543] systemd[1]: Starting Post-Boot Actions...1482server # [ 9.039568] systemd[1]: Started Reset console on configuration changes.1483server # [ 9.057266] systemd[1]: Starting resolvconf update...1484builder # [ 9.074847] systemd[1]: Finished Firewall.1485builder # [ 9.105355] systemd[1]: Finished register-nix-paths.service.1486server # [ 9.143958] dbus-broker-launch[488]: Ready1487server # [ 9.151783] systemd[1]: Finished Post-Boot Actions.1488server # [ 9.196836] systemd[1]: Started Name Service Cache Daemon (nsncd).1489server # [ 9.198178] nsncd[492]: Oct 01 10:33:38.769 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1490server # [ 9.217225] systemd[1]: Reached target Host and Network Name Lookups.1491server # [ 9.223692] systemd[1]: Reached target User and Group Name Lookups.1492server # [ 9.234476] systemd[1]: Starting User Login Management...1493server # [ 9.239939] systemd[1]: Finished Import lastlog data into lastlog2 database.1494server # [ 9.266885] niks3-test-certs-start[502]: -----1495server # [ 9.300361] niks3-test-certs-start[526]: -----1496builder # [ 9.398185] systemd[1]: Finished Coldplug All udev Devices.1497builder # [ 9.405312] systemd[1]: Reached target System Initialization.1498builder # [ 9.406279] systemd[1]: Started Discard unused filesystem blocks once a week.1499builder # [ 9.407322] systemd[1]: Started Daily Cleanup of Temporary Directories.1500server # [ 9.410038] systemd-logind[505]: New seat seat0.1501builder # [ 9.412314] systemd[1]: Reached target Timer Units.1502builder # [ 9.413107] systemd[1]: Listening on D-Bus System Message Bus Socket.1503builder # [ 9.414059] systemd[1]: Starting niks3 auto-upload socket...1504server # [ 9.419893] systemd[1]: Started User Login Management.1505server # [ 9.427508] systemd[1]: Stopped target Host and Network Name Lookups.1506builder # [ 9.429894] systemd[1]: Listening on Nix Daemon Socket.1507builder # [ 9.432277] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1508builder # [ 9.436926] systemd[1]: Listening on niks3 auto-upload socket.1509server # [ 9.438822] niks3-test-certs-start[537]: Certificate request self-signature ok1510builder # [ 9.441178] systemd[1]: Reached target Socket Units.1511builder # [ 9.442536] systemd[1]: Starting D-Bus System Message Bus...1512builder # [ 9.449529] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1513server # [ 9.440006] niks3-test-certs-start[537]: subject=CN=server1514server # [ 9.458498] systemd[1]: Stopping Host and Network Name Lookups...1515server # [ 9.464874] systemd[1]: Stopped target User and Group Name Lookups.1516server # [ 9.470393] systemd[1]: Stopping User and Group Name Lookups...1517server # [ 9.473922] systemd[1]: Starting linger-users.service...1518builder # [ 9.478100] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1519server # [ 9.483317] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1520server # [ 9.489427] systemd[1]: nscd.service: Deactivated successfully.1521server # [ 9.495974] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1522server # [ 9.501595] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1523builder # [ 9.503678] dbus-broker-launch[481]: Looking up NSS user entry for 'systemd-timesync'...1524builder # [ 9.508310] dbus-broker-launch[481]: NSS returned no entry for 'systemd-timesync'1525server # [ 9.511926] niks3-test-certs-start[567]: -----1526builder # [ 9.511491] dbus-broker-launch[481]: Invalid user-name in /nix/store/py4078kyw8b4r0flwgcxav1zmw5s5yi8-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1527builder # [ 9.522275] systemd[1]: Started D-Bus System Message Bus.1528builder # [ 9.530051] systemd[1]: Reached target Basic System.1529server # [ 9.539891] systemd[1]: linger-users.service: Deactivated successfully.1530builder # [ 9.540398] systemd[1]: Starting Import lastlog data into lastlog2 database...1531server # [ 9.545608] systemd[1]: Finished linger-users.service.1532builder # [ 9.557197] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1533server # [ 9.560291] systemd[1]: Started Name Service Cache Daemon (nsncd).1534builder # [ 9.561730] systemd[1]: Starting Post-Boot Actions...1535server # [ 9.564501] nsncd[561]: Oct 01 10:33:39.135 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1536builder # [ 9.569280] systemd[1]: Started Reset console on configuration changes.1537builder # [ 9.573826] systemd[1]: Starting resolvconf update...1538server # [ 9.576780] systemd[1]: Reached target Host and Network Name Lookups.1539server # [ 9.579673] systemd[1]: Reached target User and Group Name Lookups.1540server # [ 9.603233] systemd[1]: Finished resolvconf update.1541server # [ 9.605433] systemd[1]: Reached target Preparation for Network.1542server # [ 9.612357] systemd[1]: Starting DHCP Client...1543server # [ 9.617712] systemd[1]: Starting Extra networking commands....1544builder # [ 9.631258] dbus-broker-launch[481]: Ready1545server # [ 9.639345] niks3-test-certs-start[574]: Certificate request self-signature ok1546server # [ 9.644825] niks3-test-certs-start[574]: subject=CN=niks3 test client1547builder # [ 9.690930] systemd[1]: Finished Post-Boot Actions.1548server # [ 9.691562] systemd[1]: Finished Generate test mTLS certs.1549builder # [ 9.716960] systemd[1]: Started Name Service Cache Daemon (nsncd).1550builder # [ 9.729046] nsncd[484]: Oct 01 10:33:39.278 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1551builder # [ 9.739103] systemd[1]: Reached target Host and Network Name Lookups.1552builder # [ 9.743556] systemd[1]: Reached target User and Group Name Lookups.1553builder # [ 9.749151] systemd[1]: Starting User Login Management...1554builder # [ 9.764385] systemd[1]: Finished Import lastlog data into lastlog2 database.1555builder # [ 9.835770] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1556server # [ 9.868486] dhcpcd[599]: dhcpcd-10.3.2 starting1557server # [ 9.879385] dhcpcd[646]: dev: loaded udev1558builder # [ 9.882541] systemd-logind[495]: New seat seat0.1559server # [ 9.884349] dhcpcd[646]: no valid interfaces found1560builder # [ 9.885926] systemd[1]: Started User Login Management.1561server # [ 9.886448] dhcpcd[646]: no valid interfaces found1562builder # [ 9.893024] systemd[1]: Starting linger-users.service...1563server # [ 9.901263] systemd[1]: Finished Extra networking commands..1564server # [ 9.911608] systemd[1]: Reached target Network.1565builder # [ 9.912360] systemd[1]: Stopped target Host and Network Name Lookups.1566builder # [ 9.914340] systemd[1]: Stopping Host and Network Name Lookups...1567builder # [ 9.918637] systemd[1]: Stopped target User and Group Name Lookups.1568server # [ 9.920190] systemd[1]: Started Mock OIDC server for testing.1569server # [ 9.924231] systemd[1]: Starting Nginx Web Server...1570builder # [ 9.924184] systemd[1]: Stopping User and Group Name Lookups...1571server # [ 9.928392] systemd[1]: Starting PostgreSQL Server...1572builder # [ 9.932410] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1573builder # [ 9.934711] systemd[1]: nscd.service: Deactivated successfully.1574server # [ 9.940508] systemd[1]: Started RustFS S3-compatible object storage.1575builder # [ 9.941408] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1576builder # [ 9.948625] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1577server # [ 9.951445] systemd[1]: Starting Setup RustFS bucket...1578builder # [ 9.957066] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1579server # [ 9.958265] systemd[1]: Starting Permit User Sessions...1580builder # [ 9.968492] systemd[1]: Started backdoor.service.1581builder # [ 10.009417] systemd[1]: linger-users.service: Deactivated successfully.1582builder # [ 10.014750] systemd[1]: Finished linger-users.service.1583server # [ 10.022679] systemd[1]: Starting Virtual Console Setup...1584builder # [ 10.027911] systemd[1]: Started Name Service Cache Daemon (nsncd).1585builder # [ 10.032583] systemd[1]: Reached target Host and Network Name Lookups.1586builder # [ 10.037266] nsncd[550]: Oct 01 10:33:39.590 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1587builder # [ 10.046522] systemd[1]: Reached target User and Group Name Lookups.1588builder # connecting to host...1589server # [ 10.075549] systemd[1]: Finished Permit User Sessions.1590builder # [ 10.082279] systemd[1]: Finished resolvconf update.1591server # [ 10.084654] systemd[1]: Started Getty on tty1.1592builder # [ 10.090958] systemd[1]: Reached target Preparation for Network.1593builder # [ 10.091969] systemd[1]: Starting DHCP Client...1594server # [ 10.096510] systemd[1]: Reached target Login Prompts.1595builder # [ 10.104389] systemd[1]: Starting Extra networking commands....1596server # [ 10.107385] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1597server # [ 10.114141] systemd[1]: Stopped Virtual Console Setup.1598server # [ 10.127491] systemd[1]: Starting Virtual Console Setup...1599builder # [ 10.216731] (udev-worker)[338]: Network interface NamePolicy= disabled on kernel command line.1600builder # [ 10.236739] (udev-worker)[336]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1601builder # [ 10.254222] (udev-worker)[336]: Network interface NamePolicy= disabled on kernel command line.1602builder # [ 10.319796] dhcpcd[589]: dhcpcd-10.3.2 starting1603builder # [ 10.328687] dhcpcd[629]: dev: loaded udev1604builder # [ 10.373302] 8021q: 802.1Q VLAN Support v1.81605builder # [ 10.436522] systemd[1]: Finished Extra networking commands..1606builder # [ 10.437457] systemd[1]: Reached target Network.1607builder # [ 10.452907] systemd[1]: Starting Permit User Sessions...1608builder # [ 10.480323] cfg80211: Loading compiled-in X.509 certificates for regulatory database1609builder # [ 10.512769] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1610builder # [ 10.513255] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1611builder # [ 10.514814] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21612builder # [ 10.515132] cfg80211: failed to load regulatory.db1613builder # [ 10.515591] systemd[1]: Finished Permit User Sessions.1614builder # [ 10.522691] systemd[1]: Started Getty on tty1.1615builder # [ 10.523570] systemd[1]: Reached target Login Prompts.1616builder # [ 10.591190] 8021q: adding VLAN 0 to HW filter on device eth01617builder # [ 10.578386] dhcpcd[629]: eth0: waiting for carrier1618builder # [ 10.579803] dhcpcd[629]: libudev: received NULL device1619builder # [ 10.582750] dhcpcd[629]: libudev: received NULL device1620builder # [ 10.583684] dhcpcd[629]: eth0: carrier acquired1621server # [ 10.591178] nginx-pre-start[680]: nginx: the configuration file /nix/store/yp5jb2wj0rv7jdsw17jpq148jhgkfqdb-nginx.conf syntax is ok1622builder # [ 10.591798] dhcpcd[629]: DUID 00:01:00:01:32:50:f3:84:52:54:00:12:34:561623builder # [ 10.596695] dhcpcd[629]: eth0: IAID 00:12:34:561624builder # [ 10.597403] dhcpcd[629]: eth0: adding address fe80::5054:ff:fe12:34561625builder # [ 10.598296] dhcpcd[629]: eth0: IAID 00:12:34:561626builder # [ 10.606174] systemd[1]: Condition check resulted in Virtio network device being skipped.1627builder # [ 10.607408] systemd[1]: Starting Address configuration of eth1...1628server # [ 10.603467] nginx-pre-start[680]: nginx: configuration file /nix/store/yp5jb2wj0rv7jdsw17jpq148jhgkfqdb-nginx.conf test is successful1629server # [ 10.620701] systemd[1]: Started Nginx Web Server.1630server # [ 10.654555] postgresql-pre-start[683]: The files belonging to this database system will be owned by user "postgres".1631server # [ 10.666462] postgresql-pre-start[683]: This user must also own the server process.1632builder # [ 10.683697] 8021q: adding VLAN 0 to HW filter on device eth11633server # [ 10.674387] postgresql-pre-start[683]: The database cluster will be initialized with locale "en_US.UTF-8".1634builder # [ 10.681380] network-addresses-eth1-start[659]: adding address 192.168.1.1/24... done1635server # [ 10.688380] postgresql-pre-start[683]: The default database encoding has accordingly been set to "UTF8".1636builder # [ 10.691947] network-addresses-eth1-start[659]: adding address 2001:db8:1::1/64... done1637server # [ 10.694484] postgresql-pre-start[683]: The default text search configuration will be set to "english".1638server # [ 10.701263] postgresql-pre-start[683]: Data page checksums are enabled.1639server # [ 10.706820] postgresql-pre-start[683]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok1640builder # [ 10.707782] systemd[1]: Finished Address configuration of eth1.1641builder # [ 10.710496] systemd-logind[495]: Watching system buttons on /dev/input/event0 (gpio-keys)1642server # [ 10.717306] postgresql-pre-start[683]: creating subdirectories ... ok1643server # [ 10.723234] postgresql-pre-start[683]: selecting dynamic shared memory implementation ... posix1644server # [ 10.731781] systemd-vconsole-setup[668]: Configuration of first virtual console was skipped, ignoring remaining ones.1645server # [ 10.741490] systemd[1]: Finished Virtual Console Setup.1646server # [ 10.751107] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1647builder # [ 10.792539] mousedev: PS/2 mouse device common for all mice1648builder # [ 10.856118] systemd-logind[495]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1649server # [ 10.945645] postgresql-pre-start[683]: selecting default "max_connections" ... 1001650server # [ 11.004194] mock-oidc-server[652]: Mock OIDC Server running1651server # [ 11.006251] mock-oidc-server[652]: OIDC Address: 127.0.0.1:80801652server # [ 11.012346] mock-oidc-server[652]: Issue Address: 127.0.0.1:80811653server # [ 11.014775] mock-oidc-server[652]: Issuer: http://127.0.0.1:8080/oidc1654server # [ 11.017269] mock-oidc-server[652]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json1655server # [ 11.021825] mock-oidc-server[652]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration1656server # [ 11.027885] mock-oidc-server[652]: Issue tokens: http://127.0.0.1:8081/issue?sub=...1657server # [ 11.157503] postgresql-pre-start[683]: selecting default "shared_buffers" ... 128MB1658server # [ 11.204200] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1659server # [ 11.306615] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1660server # [ 11.316220] systemd[1]: Started backdoor.service.1661builder # [ 11.338736] dhcpcd[629]: eth0: soliciting a DHCP lease1662builder # [ 11.344825] dhcpcd[629]: eth0: offered 10.0.2.15 from 10.0.2.21663builder # [ 11.352740] dhcpcd[629]: eth0: probing address 10.0.2.15/241664server # [ 11.439641] 8021q: 802.1Q VLAN Support v1.81665server # connecting to host...1666server # [ 11.494796] (udev-worker)[687]: Network interface NamePolicy= disabled on kernel command line.1667server # [ 11.532880] cfg80211: Loading compiled-in X.509 certificates for regulatory database1668server: Guest shell says: b'Spawning backdoor root shell...\n'1669server # [ 11.559936] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1670server # [ 11.560399] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1671server # [ 11.561408] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21672server # [ 11.561717] cfg80211: failed to load regulatory.db1673server: connected to guest root shell1674server: (connecting took 11.92 seconds)1675server: (finished: waiting for the VM to finish booting, in 11.92 seconds)1676builder # [ 11.588791] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31677server # [ 11.606135] (udev-worker)[690]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1678server # [ 11.617229] (udev-worker)[690]: Network interface NamePolicy= disabled on kernel command line.1679server # [ 11.825502] systemd-logind[505]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1680builder # [ 11.861236] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1681builder # [ 11.869173] systemd[1]: Starting Virtual Console Setup...1682builder # [ 11.893928] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1683builder # [ 11.895075] systemd[1]: Stopped Virtual Console Setup.1684builder # [ 11.897867] systemd[1]: Starting Virtual Console Setup...1685server # [ 11.953841] 8021q: adding VLAN 0 to HW filter on device eth01686server # [ 11.942423] dhcpcd[646]: eth0: waiting for carrier1687server # [ 11.947582] dhcpcd[646]: eth0: carrier acquired1688server # [ 11.961286] systemd[1]: Condition check resulted in Virtio network device being skipped.1689server # [ 11.967703] dhcpcd[646]: DUID 00:01:00:01:32:50:f3:85:52:54:00:12:34:561690server # [ 11.975527] systemd[1]: Starting Address configuration of eth1...1691server # [ 11.977212] dhcpcd[646]: eth0: IAID 00:12:34:561692builder # [ 11.978524] systemd-logind[495]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1693server # [ 11.979831] dhcpcd[646]: eth0: adding address fe80::5054:ff:fe12:34561694builder # [ 12.033538] systemd-vconsole-setup[686]: Configuration of first virtual console was skipped, ignoring remaining ones.1695builder # [ 12.037826] systemd[1]: Finished Virtual Console Setup.1696builder # [ 12.045569] dhcpcd[629]: eth0: soliciting an IPv6 router1697builder # [ 12.046979] dhcpcd[629]: eth0: Router Advertisement from fe80::21698builder # [ 12.047951] dhcpcd[629]: eth0: adding address fec0::5054:ff:fe12:3456/641699builder # [ 12.049070] dhcpcd[629]: eth0: adding route to fec0::/641700builder # [ 12.049825] dhcpcd[629]: eth0: adding default route via fe80::21701server # [ 12.069345] 8021q: adding VLAN 0 to HW filter on device eth11702server # [ 12.071953] network-addresses-eth1-start[749]: adding address 192.168.1.2/24... done1703server # [ 12.087508] network-addresses-eth1-start[749]: adding address 2001:db8:1::2/64... done1704server # [ 12.106627] systemd[1]: Finished Address configuration of eth1.1705server # [ 12.156420] systemd-logind[505]: Watching system buttons on /dev/input/event0 (gpio-keys)1706server # [ 12.379171] mousedev: PS/2 mouse device common for all mice1707server # [ 12.412495] systemd-logind[505]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1708server # [ 12.667038] dhcpcd[646]: eth0: soliciting a DHCP lease1709server # [ 12.674998] dhcpcd[646]: eth0: offered 10.0.2.15 from 10.0.2.21710server # [ 12.680192] dhcpcd[646]: eth0: probing address 10.0.2.15/241711server # [ 12.751488] postgresql-pre-start[683]: selecting default time zone ... UTC1712server # [ 12.757508] postgresql-pre-start[683]: creating configuration files ... ok1713server # [ 13.230446] postgresql-pre-start[683]: running bootstrap script ... ok1714server # [ 14.299933] postgresql-pre-start[683]: performing post-bootstrap initialization ... ok1715server # [ 14.397739] dhcpcd[646]: eth0: soliciting an IPv6 router1716server # [ 14.399085] dhcpcd[646]: eth0: Router Advertisement from fe80::21717server # [ 14.401157] dhcpcd[646]: eth0: adding address fec0::5054:ff:fe12:3456/641718server # [ 14.404383] dhcpcd[646]: eth0: adding route to fec0::/641719server # [ 14.405300] dhcpcd[646]: eth0: adding default route via fe80::21720server # [ 14.485117] postgresql-pre-start[683]: syncing data to disk ... ok1721server # [ 14.486050] postgresql-pre-start[683]: initdb: warning: enabling "trust" authentication for local connections1722server # [ 14.487340] postgresql-pre-start[683]: initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.1723server # [ 14.497733] postgresql-pre-start[683]: Success. You can now start the database server using:1724server # [ 14.501265] postgresql-pre-start[683]: pg_ctl -D /var/lib/postgresql/18 -l logfile start1725server # [ 14.665962] postgres[787]: [787] LOG: starting PostgreSQL 18.6 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit1726server # [ 14.673099] postgres[787]: [787] LOG: listening on IPv6 address "::1", port 54321727server # [ 14.674201] postgres[787]: [787] LOG: listening on IPv4 address "127.0.0.1", port 54321728server # [ 14.677793] postgres[787]: [787] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1729server # [ 14.689843] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1730server # [ 14.690936] systemd[1]: Stopped Virtual Console Setup.1731server # [ 14.691690] systemd[1]: Stopping Virtual Console Setup...1732server # [ 14.703765] systemd[1]: Starting Virtual Console Setup...1733server # [ 14.709710] postgres[800]: [800] LOG: database system was shut down at 2026-10-01 10:33:43 GMT1734server # [ 14.719167] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1735server # [ 14.724180] systemd[1]: Stopped Virtual Console Setup.1736server # [ 14.726173] postgres[787]: [787] LOG: database system is ready to accept connections1737server # [ 14.736929] systemd[1]: Started PostgreSQL Server.1738server # [ 14.737682] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1739server # [ 14.755157] systemd[1]: Starting PostgreSQL Setup Scripts...1740server # [ 14.756101] systemd[1]: Starting Virtual Console Setup...1741server: (finished: waiting for unit postgresql.service, in 15.29 seconds)1742server: waiting for unit rustfs.service1743server # [ 14.985670] systemd-vconsole-setup[805]: Configuration of first virtual console was skipped, ignoring remaining ones.1744server # [ 14.995960] systemd[1]: Finished Virtual Console Setup.1745server: (finished: waiting for unit rustfs.service, in 0.07 seconds)1746server: waiting for unit rustfs-setup.service1747server # [ 15.107579] postgresql-setup-start[815]: CREATE DATABASE1748server # [ 15.163026] postgresql-setup-start[831]: CREATE ROLE1749server # [ 15.178587] postgresql-setup-start[833]: ALTER DATABASE1750server # [ 15.184880] systemd[1]: Finished PostgreSQL Setup Scripts.1751server # [ 15.186701] systemd[1]: Reached target PostgreSQL.1752builder # [ 16.088571] dhcpcd[629]: eth0: leased 10.0.2.15 for 86400 seconds1753builder # [ 16.088756] dhcpcd[629]: eth0: adding route to 10.0.2.0/241754builder # [ 16.088854] dhcpcd[629]: eth0: adding default route via 10.0.2.21755builder # [ 16.194137] systemd[1]: Started DHCP Client.1756builder # [ 16.196143] systemd[1]: Reached target Multi-User System.1757builder # [ 16.197022] systemd[1]: Startup finished in 1.013s (kernel) + 4.743s (initrd) + 10.438s (userspace) = 16.195s.1758server # [ 17.317139] dhcpcd[646]: eth0: leased 10.0.2.15 for 86400 seconds1759server # [ 17.317326] dhcpcd[646]: eth0: adding route to 10.0.2.0/241760server # [ 17.317394] dhcpcd[646]: eth0: adding default route via 10.0.2.21761server # [ 17.420792] systemd[1]: Started DHCP Client.1762server # [ 27.171650] rustfs-setup-start[943]: mb s3://niks3-test1763server # [ 27.184907] systemd[1]: Finished Setup RustFS bucket.1764server # [ 27.192597] systemd[1]: Starting niks3 server...1765server # [ 27.359781] niks3-server[953]: 2026/10/01 10:33:56 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:8080/oidc1766server # [ 27.367090] niks3-server[953]: 2026/10/01 10:33:56 INFO OIDC authentication enabled config=/nix/store/z019gvf710g6d8396v9cvsyidwg4d4yk-niks3-oidc.json1767server # [ 27.369903] niks3-server[953]: 2026/10/01 10:33:56 INFO Loaded signing key name=niks3-test-1 path=/nix/store/6jgpl7z0sjpvlynzpcik37wwz06qi4np-niks3-signing-key1768server # [ 27.389115] niks3-server[953]: 2026/10/01 10:33:56 INFO Using socket-activated listener address=0.0.0.0:57511769server # [ 27.390515] niks3-server[953]: 2026/10/01 10:33:56 INFO Using socket-activated listener address=/run/niks3/proxy.sock1770server # [ 27.396782] niks3-server[953]: 2026/10/01 10:33:56 INFO systemd watchdog enabled interval=15s1771server # [ 27.398129] niks3-server[953]: 2026/10/01 10:33:56 INFO Starting HTTP server address=0.0.0.0:57511772server # [ 27.399849] niks3-server[953]: 2026/10/01 10:33:56 INFO Starting HTTP server address=/run/niks3/proxy.sock1773server # [ 27.401427] systemd[1]: Started niks3 server.1774server # [ 27.402911] systemd[1]: Reached target Multi-User System.1775server # [ 27.403742] systemd[1]: Startup finished in 1.000s (kernel) + 3.962s (initrd) + 22.430s (userspace) = 27.394s.1776server: (finished: waiting for unit rustfs-setup.service, in 12.53 seconds)1777server: waiting for unit mock-oidc.service1778server: (finished: waiting for unit mock-oidc.service, in 0.03 seconds)1779server: waiting for unit niks3.service1780server: (finished: waiting for unit niks3.service, in 0.03 seconds)1781server: waiting for TCP port 5751 on localhost1782server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded!1783server: (finished: waiting for TCP port 5751 on localhost, in 0.03 seconds)1784server: waiting for TCP port 8080 on localhost1785server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded!1786server: (finished: waiting for TCP port 8080 on localhost, in 0.02 seconds)1787server: waiting for TCP port 9000 on localhost1788server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded!1789server: (finished: waiting for TCP port 9000 on localhost, in 0.02 seconds)1790server: must succeed: mkdir -p /tmp/test-config1791server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.01 seconds)1792server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token1793server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.01 seconds)1794server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31795server # [ 27.825789] systemd[1]: Created slice Slice /system/nix-daemon.1796server # [ 27.829946] systemd[1]: Started Nix Daemon instance (PID 1002/UID 0).1797server # [ 27.884186] nix-daemon[1004]: remote pid 1002 is unknown user (trusted)1798server # [ 27.901213] niks3-server[953]: 2026/10/01 10:33:57 INFO Received push request method=POST path=/api/pushes1799server # [ 27.902626] systemd[1]: nix-daemon@0-1-1002_1003-0.service: Deactivated successfully.1800server # time=2026-10-01T10:33:57.497Z level=INFO msg="Uploading 5 paths to server (0 already cached)"1801server # time=2026-10-01T10:33:57.499Z level=INFO msg="Uploading kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 (287.5KB)"1802server # time=2026-10-01T10:33:57.501Z level=INFO msg="Uploading m54cs0m994hc3n9lax7sg98sgy729qsa-glibc-2.42-84 (44.4MB)"1803server # time=2026-10-01T10:33:57.502Z level=INFO msg="Uploading 0s40b0an0cz4vypbji92cb7qbr65icl4-libidn2-2.3.8 (366.1KB)"1804server # time=2026-10-01T10:33:57.505Z level=INFO msg="Uploading h0hd048jzxs7fx9a6g75jwrrhbg50bp8-libunistring-1.4.2 (2.0MB)"1805server # time=2026-10-01T10:33:57.508Z level=INFO msg="Uploading waax852balprvsvdziyaf1h7r3ldvakg-xgcc-15.3.0-libgcc (150.1KB)"1806server # [ 28.199774] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=kwhxkl8yn5y8wqiq11jsybagw3fbc4iv.ls1807server # [ 28.218946] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=nar/16z5dldwcmmhv0ij0pz8h0z98mv285jvqw0ly64fsg0s9lbffamb.nar.zst1808server # [ 28.241244] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=nar/0rbj231dr19n59qcahf76cdwmqlqv239j85j94g8c89kf9kf5p04.nar.zst1809server # [ 28.263436] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=h0hd048jzxs7fx9a6g75jwrrhbg50bp8.ls1810server # [ 28.273544] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=nar/07lydviym1nzmxy9y72apc8lgzy9v6x110r4vxac3kk2xslg0gcg.nar.zst1811server # [ 28.310552] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=nar/0x48iv4crw96wayixvwmbhz9znvm9djawdf9j0wszdqdxpn92kw1.nar.zst1812server # [ 28.315564] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=0s40b0an0cz4vypbji92cb7qbr65icl4.ls1813server # [ 28.320118] niks3-server[953]: 2026/10/01 10:33:57 INFO Registered completed upload object_key=waax852balprvsvdziyaf1h7r3ldvakg.ls1814server # [ 28.927844] niks3-server[953]: 2026/10/01 10:33:58 INFO Registered completed upload object_key=m54cs0m994hc3n9lax7sg98sgy729qsa.ls1815server # [ 28.957536] niks3-server[953]: 2026/10/01 10:33:58 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1816server # [ 28.972583] niks3-server[953]: 2026/10/01 10:33:58 INFO Completed multipart upload object_key=nar/1vzbqdhwmbhwll0g4bniam5qjj71a1ci2n6lc100n2yfpbpygw8c.nar.zst upload_id=M2MxZDliNzctZDE0ZS00NzBkLTliMzItODY2MDAwMzYzYmMyLjIwZWRkZmE2LTU5NWItNDgxNC1iNTAxLTRmMTQwNGExOTlkNHgxNzkwODUwODM3NDkxMjk0NDQw parts=11817server # time=2026-10-01T10:33:58.556Z level=INFO msg="Uploading 5 narinfos"1818server # [ 28.981220] niks3-server[953]: 2026/10/01 10:33:58 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign1819server # [ 28.986074] niks3-server[953]: 2026/10/01 10:33:58 INFO Signed narinfos id=1 count=51820server # [ 29.009207] niks3-server[953]: 2026/10/01 10:33:58 INFO Received complete push request method=POST path=/api/pushes/1/complete1821server # [ 29.015362] niks3-server[953]: 2026/10/01 10:33:58 INFO Registered completed upload object_key=m54cs0m994hc3n9lax7sg98sgy729qsa.narinfo1822server # [ 29.026164] niks3-server[953]: 2026/10/01 10:33:58 INFO Registered completed upload object_key=waax852balprvsvdziyaf1h7r3ldvakg.narinfo1823server # [ 29.030482] niks3-server[953]: 2026/10/01 10:33:58 INFO Registered completed upload object_key=h0hd048jzxs7fx9a6g75jwrrhbg50bp8.narinfo1824server # [ 29.034707] niks3-server[953]: 2026/10/01 10:33:58 INFO Registered completed upload object_key=0s40b0an0cz4vypbji92cb7qbr65icl4.narinfo1825server # time=2026-10-01T10:33:58.615Z level=INFO msg="Upload complete. (1.258s)"1826server # [ 29.044062] niks3-server[953]: 2026/10/01 10:33:58 INFO Registered completed upload object_key=kwhxkl8yn5y8wqiq11jsybagw3fbc4iv.narinfo1827server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 1.35 seconds)1828server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token1829server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.01 seconds)1830server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31831server # [ 29.122531] niks3-server[953]: 2026/10/01 10:33:58 WARN Authentication failed token_preview=invalid-token token_length=13 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]1832server # [ 29.165902] systemd[1]: Started Nix Daemon instance (PID 1034/UID 0).1833server # [ 29.220366] nix-daemon[1036]: remote pid 1034 is unknown user (trusted)1834server # [ 29.234096] systemd[1]: nix-daemon@1-2-1034_1035-0.service: Deactivated successfully.1835server # [ 29.240404] niks3-server[953]: 2026/10/01 10:33:58 WARN Authentication failed token_preview=invalid-token token_length=13 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]1836server # time=2026-10-01T10:33:58.820Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"1837server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.18 seconds)1838server: waiting for unit nginx.service1839server: (finished: waiting for unit nginx.service, in 0.03 seconds)1840server: waiting for TCP port 443 on localhost1841server # Connection to localhost (::1) 443 port [tcp/https] succeeded!1842server: (finished: waiting for TCP port 443 on localhost, in 0.02 seconds)1843server: must succeed: /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31844server # time=2026-10-01T10:33:58.925Z level=INFO msg="Configuring client TLS" cert=/etc/niks3-test-certs/client.pem key=/etc/niks3-test-certs/client.key ca=/etc/niks3-test-certs/ca.pem1845server # time=2026-10-01T10:33:58.939Z level=INFO msg="All 1 paths already cached"1846server: (finished: must succeed: /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.07 seconds)1847server: 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_closures1848server: (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)1849server: must fail: /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31850server # time=2026-10-01T10:33:58.994Z level=ERROR msg="Fatal error" error="auth token is required (use --auth-token-path, --auth-token-script, NIKS3_AUTH_TOKEN_FILE, or $XDG_CONFIG_HOME/niks3/auth-token)"1851server: (finished: must fail: /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.02 seconds)1852server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31853server # time=2026-10-01T10:33:59.052Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem1854server # time=2026-10-01T10:33:59.060Z level=INFO msg="All 1 paths already cached"1855server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.07 seconds)1856server: must succeed: cd /etc/niks3-test-certs && /nix/store/ai0jh6nymysikgd4liv2k8iv9kmipcva-openssl-3.6.4-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client'1857server # -----1858server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/ai0jh6nymysikgd4liv2k8iv9kmipcva-openssl-3.6.4-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client', in 0.02 seconds)1859server: must succeed: cd /etc/niks3-test-certs && /nix/store/ai0jh6nymysikgd4liv2k8iv9kmipcva-openssl-3.6.4-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem1860server # Certificate request self-signature ok1861server # subject=CN=other client1862server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/ai0jh6nymysikgd4liv2k8iv9kmipcva-openssl-3.6.4-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem, in 0.04 seconds)1863server: must fail: /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31864server # time=2026-10-01T10:33:59.181Z level=INFO msg="Configuring client TLS" cert=/etc/niks3-test-certs/other.pem key=/etc/niks3-test-certs/other.key ca=/etc/niks3-test-certs/ca.pem1865server # [ 29.613088] niks3-server[953]: 2026/10/01 10:33:59 WARN mTLS auth: subject not in bound subjects subject="CN=other client"1866server # [ 29.656401] systemd[1]: Started Nix Daemon instance (PID 1101/UID 0).1867server # [ 29.705516] nix-daemon[1103]: remote pid 1101 is unknown user (trusted)1868server # [ 29.720836] systemd[1]: nix-daemon@2-3-1101_1102-0.service: Deactivated successfully.1869server # [ 29.728523] niks3-server[953]: 2026/10/01 10:33:59 WARN mTLS auth: subject not in bound subjects subject="CN=other client"1870server # time=2026-10-01T10:33:59.307Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"1871server: (finished: must fail: /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3, in 0.18 seconds)1872server: must succeed: mkdir -p /tmp/test-store1873server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.02 seconds)1874server: must succeed: 1875 export AWS_ACCESS_KEY_ID=rustfsadmin1876export AWS_SECRET_ACCESS_KEY=rustfsadmin1877 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.318781879server: (finished: must succeed: 1880 export AWS_ACCESS_KEY_ID=rustfsadmin1881export AWS_SECRET_ACCESS_KEY=rustfsadmin1882 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.31883, in 0.68 seconds)1884server: must succeed: 1885cat > /tmp/test-drv.nix << 'EOF'1886derivation {1887 name = "test-build-log";1888 system = builtins.currentSystem;1889 builder = "/bin/sh";1890 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];1891}1892EOF18931894server: (finished: must succeed: 1895cat > /tmp/test-drv.nix << 'EOF'1896derivation {1897 name = "test-build-log";1898 system = builtins.currentSystem;1899 builder = "/bin/sh";1900 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];1901}1902EOF1903, in 0.02 seconds)1904server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix1905server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring1906server # [ 30.501703] systemd[1]: Started Nix Daemon instance (PID 1145/UID 0).1907server # [ 30.552230] nix-daemon[1149]: remote pid 1145 is unknown user (trusted)1908server # this derivation will be built:1909server # /nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv1910server # building '/nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv'...1911server # test-build-log> test build log output1912server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.20 seconds)1913server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1914server # [ 30.650015] systemd[1]: nix-daemon@3-4-1145_1146-0.service: Deactivated successfully.1915server # [ 30.746087] systemd[1]: Started Nix Daemon instance (PID 1175/UID 0).1916server # [ 30.799746] nix-daemon[1177]: remote pid 1175 is unknown user (trusted)1917server # [ 30.812567] systemd[1]: nix-daemon@4-5-1175_1176-0.service: Deactivated successfully.1918server # [ 30.819346] niks3-server[953]: 2026/10/01 10:34:00 INFO Received push request method=POST path=/api/pushes1919server # time=2026-10-01T10:34:00.401Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1920server # time=2026-10-01T10:34:00.402Z level=INFO msg="Uploading z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log (128B)"1921server # [ 30.846219] niks3-server[953]: 2026/10/01 10:34:00 INFO Registered completed upload object_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.ls1922server # time=2026-10-01T10:34:00.430Z level=INFO msg="Uploading 1 narinfos"1923server # [ 30.855896] niks3-server[953]: 2026/10/01 10:34:00 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign1924server # [ 30.860909] niks3-server[953]: 2026/10/01 10:34:00 INFO Signed narinfos id=2 count=11925server # [ 30.863624] niks3-server[953]: 2026/10/01 10:34:00 INFO Registered completed upload object_key=log/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv1926server # [ 30.868686] niks3-server[953]: 2026/10/01 10:34:00 INFO Registered completed upload object_key=nar/00zns3gj9hwz2a4b0i07y7nmxybq59lh24bl3xsxblcl6333mjil.nar.zst1927server # [ 30.875067] niks3-server[953]: 2026/10/01 10:34:00 INFO Received complete push request method=POST path=/api/pushes/2/complete1928server # time=2026-10-01T10:34:00.458Z level=INFO msg="Upload complete. (178ms)"1929server # [ 30.882808] niks3-server[953]: 2026/10/01 10:34:00 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/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log, in 0.24 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.16 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 # [ 31.105042] systemd[1]: Started Nix Daemon instance (PID 1193/UID 0).1945server # [ 31.157224] nix-daemon[1197]: remote pid 1193 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.20 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/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --stdin1951server # [ 31.253589] systemd[1]: nix-daemon@5-6-1193_1194-0.service: Deactivated successfully.1952server # [ 31.352934] systemd[1]: Started Nix Daemon instance (PID 1225/UID 0).1953server # [ 31.404379] nix-daemon[1227]: remote pid 1225 is unknown user (trusted)1954server # [ 31.417384] systemd[1]: nix-daemon@6-7-1225_1226-0.service: Deactivated successfully.1955server # [ 31.425016] niks3-server[953]: 2026/10/01 10:34:01 INFO Received push request method=POST path=/api/pushes1956server # time=2026-10-01T10:34:01.004Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1957server # time=2026-10-01T10:34:01.006Z level=INFO msg="Uploading 7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test (120B)"1958server # [ 31.454758] niks3-server[953]: 2026/10/01 10:34:01 INFO Received sign narinfos request method=POST path=/api/pushes/3/sign1959server # time=2026-10-01T10:34:01.036Z level=INFO msg="Uploading 1 narinfos"1960server # [ 31.463616] niks3-server[953]: 2026/10/01 10:34:01 INFO Signed narinfos id=3 count=11961server # [ 31.469272] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=7j8147s8h4si9jmv6bxa86ql7z6j2m37.ls1962server # [ 31.475366] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=nar/1hj1r0g515rfxjx685mfn3dgr6akqbn4gf64snlv6bxaxph69lna.nar.zst1963server # [ 31.481359] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=log/nw68n44n5z31m0d7a0s2mnhrkndz212d-stdin-test.drv1964server # [ 31.489352] niks3-server[953]: 2026/10/01 10:34:01 INFO Received complete push request method=POST path=/api/pushes/3/complete1965server # time=2026-10-01T10:34:01.071Z level=INFO msg="Upload complete. (185ms)"1966server # [ 31.497025] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=7j8147s8h4si9jmv6bxa86ql7z6j2m37.narinfo1967server: (finished: must succeed: printf '%s\n\n%s\n' /nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log | NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --stdin, in 0.26 seconds)1968server: must succeed: 1969 export AWS_ACCESS_KEY_ID=rustfsadmin1970export AWS_SECRET_ACCESS_KEY=rustfsadmin1971 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test1972 1973server: (finished: must succeed: 1974 export AWS_ACCESS_KEY_ID=rustfsadmin1975export AWS_SECRET_ACCESS_KEY=rustfsadmin1976 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/7j8147s8h4si9jmv6bxa86ql7z6j2m37-stdin-test1977 , in 0.21 seconds)1978(finished: subtest: push --stdin streams paths and reports each one, in 0.67 seconds)1979server: must succeed: readlink /etc/niks3-test/symlink-wrapper1980server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.02 seconds)1981server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper1982server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.01 seconds)1983server: must succeed: test -L /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper1984server: (finished: must succeed: test -L /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper, in 0.01 seconds)1985server: must succeed: readlink /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper1986server: (finished: must succeed: readlink /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper, in 0.01 seconds)1987server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper1988server # [ 31.869499] systemd[1]: Started Nix Daemon instance (PID 1272/UID 0).1989server # [ 31.918937] nix-daemon[1274]: remote pid 1272 is unknown user (trusted)1990server # [ 31.932872] systemd[1]: nix-daemon@7-8-1272_1273-0.service: Deactivated successfully.1991server # [ 31.938762] niks3-server[953]: 2026/10/01 10:34:01 INFO Received push request method=POST path=/api/pushes1992server # time=2026-10-01T10:34:01.519Z level=INFO msg="Uploading 2 paths to server (0 already cached)"1993server # time=2026-10-01T10:34:01.520Z level=INFO msg="Uploading 0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper (192B)"1994server # time=2026-10-01T10:34:01.522Z level=INFO msg="Uploading xafv64bljflg1v8hnf22lyk0w4gma3v5-base-package (536B)"1995server # [ 31.966396] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=0caxmbk1mdnhh6zbyh0sz4faqqy5f08r.ls1996server # [ 31.995024] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=xafv64bljflg1v8hnf22lyk0w4gma3v5.ls1997server # time=2026-10-01T10:34:01.587Z level=INFO msg="Uploading 2 narinfos"1998server # [ 32.017598] niks3-server[953]: 2026/10/01 10:34:01 INFO Received sign narinfos request method=POST path=/api/pushes/4/sign1999server # [ 32.019192] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=nar/1ncc9lqll9lyam30m8vdy4yyaym0q7sps47afadhzvyqsw833fy8.nar.zst2000server # [ 32.028792] niks3-server[953]: 2026/10/01 10:34:01 INFO Signed narinfos id=4 count=22001server # [ 32.032926] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=nar/0820av37plmfgrxw6pcbym6phk1sv14dilyrablrnkzb7z1fd5js.nar.zst2002server # [ 32.039324] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=xafv64bljflg1v8hnf22lyk0w4gma3v5.narinfo2003server # [ 32.044523] niks3-server[953]: 2026/10/01 10:34:01 INFO Received complete push request method=POST path=/api/pushes/4/complete2004server # time=2026-10-01T10:34:01.624Z level=INFO msg="Upload complete. (220ms)"2005server # [ 32.051750] niks3-server[953]: 2026/10/01 10:34:01 INFO Registered completed upload object_key=0caxmbk1mdnhh6zbyh0sz4faqqy5f08r.narinfo2006server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper, in 0.28 seconds)2007server: must succeed: 2008 export AWS_ACCESS_KEY_ID=rustfsadmin2009export AWS_SECRET_ACCESS_KEY=rustfsadmin2010 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper20112012server: (finished: must succeed: 2013 export AWS_ACCESS_KEY_ID=rustfsadmin2014export AWS_SECRET_ACCESS_KEY=rustfsadmin2015 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper2016, in 0.18 seconds)2017server: must succeed: 2018cat > /tmp/oidc-test.nix << 'EOF'2019derivation {2020 name = "oidc-test";2021 system = builtins.currentSystem;2022 builder = "/bin/sh";2023 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2024}2025EOF20262027server: (finished: must succeed: 2028cat > /tmp/oidc-test.nix << 'EOF'2029derivation {2030 name = "oidc-test";2031 system = builtins.currentSystem;2032 builder = "/bin/sh";2033 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2034}2035EOF2036, in 0.02 seconds)2037server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link2038server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2039server # [ 32.309389] systemd[1]: Started Nix Daemon instance (PID 1301/UID 0).2040server # [ 32.361753] nix-daemon[1305]: remote pid 1301 is unknown user (trusted)2041server # this derivation will be built:2042server # /nix/store/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv2043server # building '/nix/store/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv'...2044server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link, in 0.20 seconds)2045server: 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'2046server # [ 32.458414] systemd[1]: nix-daemon@8-9-1301_1302-0.service: Deactivated successfully.2047server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://server:5751&repository_owner=myorg', in 0.03 seconds)2048server: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Ik5uTVFBRGpHUFMxbkprZ1p0VVVPR3Jqd1QxZHZhUFcxVUtuazlFX3RIY1UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTA4NTQ0NDIsImlhdCI6MTc5MDg1MDg0MiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.IFCz-6is6z-YjlLmDa9k9laKifLssYGZN97JDxiSdAsXbUrUI95e1jpX0zATwKHLHV4ijmUNjROXeMEfTK-SrpvHkiONYNttWtWRPepTX7YFjCHBRvMSQBEHW7dbPQysjUqv1Yf1W5nxkkuyzRjPbKVOnq4K9lJ_oMOKJ46b8oFlIh7KC7FohaQ3QDwTGQSiwDT_0yX5xslkCG61rI4KMgCfQzMGf9sT1gfF77FVNsDCDI0o-avdvUSfYjR5sAtpLxsuBCYBjednaaQXLGZyUwupOsTg5q4GGAOzbI_GxuKMbcd-LIBaxNaObCPgwI5wypRKPCuhJjP0W2MT3wX_ag' /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test2049server # time=2026-10-01T10:34:02.081Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2050server # [ 32.588941] systemd[1]: Started Nix Daemon instance (PID 1334/UID 0).2051server # [ 32.641952] nix-daemon[1336]: remote pid 1334 is unknown user (trusted)2052server # [ 32.655904] systemd[1]: nix-daemon@9-10-1334_1335-0.service: Deactivated successfully.2053server # [ 32.662801] niks3-server[953]: 2026/10/01 10:34:02 INFO Received push request method=POST path=/api/pushes2054server # time=2026-10-01T10:34:02.243Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2055server # time=2026-10-01T10:34:02.245Z level=INFO msg="Uploading x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test (136B)"2056server # [ 32.691217] niks3-server[953]: 2026/10/01 10:34:02 INFO Registered completed upload object_key=x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj.ls2057server # time=2026-10-01T10:34:02.274Z level=INFO msg="Uploading 1 narinfos"2058server # [ 32.700214] niks3-server[953]: 2026/10/01 10:34:02 INFO Received sign narinfos request method=POST path=/api/pushes/5/sign2059server # [ 32.701709] niks3-server[953]: 2026/10/01 10:34:02 INFO Signed narinfos id=5 count=12060server # [ 32.707192] niks3-server[953]: 2026/10/01 10:34:02 INFO Registered completed upload object_key=nar/17h0qgwf5334wia961ibqy9hmpsyvfhrvj5ji9m031klp6sx4v0q.nar.zst2061server # [ 32.716638] niks3-server[953]: 2026/10/01 10:34:02 INFO Registered completed upload object_key=log/i8di75gyikk8ykgks7k6q09blyqm3g2x-oidc-test.drv2062server # [ 32.718343] niks3-server[953]: 2026/10/01 10:34:02 INFO Received complete push request method=POST path=/api/pushes/5/complete2063server # time=2026-10-01T10:34:02.300Z level=INFO msg="Upload complete. (179ms)"2064server # [ 32.729085] niks3-server[953]: 2026/10/01 10:34:02 INFO Registered completed upload object_key=x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj.narinfo2065server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Ik5uTVFBRGpHUFMxbkprZ1p0VVVPR3Jqd1QxZHZhUFcxVUtuazlFX3RIY1UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTA4NTQ0NDIsImlhdCI6MTc5MDg1MDg0MiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.IFCz-6is6z-YjlLmDa9k9laKifLssYGZN97JDxiSdAsXbUrUI95e1jpX0zATwKHLHV4ijmUNjROXeMEfTK-SrpvHkiONYNttWtWRPepTX7YFjCHBRvMSQBEHW7dbPQysjUqv1Yf1W5nxkkuyzRjPbKVOnq4K9lJ_oMOKJ46b8oFlIh7KC7FohaQ3QDwTGQSiwDT_0yX5xslkCG61rI4KMgCfQzMGf9sT1gfF77FVNsDCDI0o-avdvUSfYjR5sAtpLxsuBCYBjednaaQXLGZyUwupOsTg5q4GGAOzbI_GxuKMbcd-LIBaxNaObCPgwI5wypRKPCuhJjP0W2MT3wX_ag' /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test, in 0.24 seconds)2066server: must succeed: 2067cat > /tmp/oidc-test2.nix << 'EOF'2068derivation {2069 name = "oidc-test2";2070 system = builtins.currentSystem;2071 builder = "/bin/sh";2072 args = [ "-c" "echo 'OIDC test 2' > $out" ];2073}2074EOF20752076server: (finished: must succeed: 2077cat > /tmp/oidc-test2.nix << 'EOF'2078derivation {2079 name = "oidc-test2";2080 system = builtins.currentSystem;2081 builder = "/bin/sh";2082 args = [ "-c" "echo 'OIDC test 2' > $out" ];2083}2084EOF2085, in 0.02 seconds)2086server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link2087server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2088server # [ 32.805306] systemd[1]: Started Nix Daemon instance (PID 1347/UID 0).2089server # [ 32.852827] nix-daemon[1351]: remote pid 1347 is unknown user (trusted)2090server # this derivation will be built:2091server # /nix/store/ldlfjwl576hkm4b5ag3b447nnn59cwiw-oidc-test2.drv2092server # building '/nix/store/ldlfjwl576hkm4b5ag3b447nnn59cwiw-oidc-test2.drv'...2093server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link, in 0.20 seconds)2094server: 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'2095server # [ 32.951540] systemd[1]: nix-daemon@10-11-1347_1348-0.service: Deactivated successfully.2096server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:otherorg/repo:ref:refs/heads/main&aud=http://server:5751&repository_owner=otherorg', in 0.03 seconds)2097server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Ik5uTVFBRGpHUFMxbkprZ1p0VVVPR3Jqd1QxZHZhUFcxVUtuazlFX3RIY1UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTA4NTQ0NDIsImlhdCI6MTc5MDg1MDg0MiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.SrPJawZli2BxJ5avzfSmqYUFCRtM6yR3mxzXUDDI2IYsX7NK9N4sboPJOId6R0nCPQi5DQtSE91fIxnJSpGW9PmRQRiczU9KKW6865IgS0o2an2uuK05Yh39R6SPRVj4LjGzyjc7oQ6Ser9PuEtn2bvYW394oratUcWfzglzZQWIsYNkz_aZaUyZIUkfquugLpq9BQaxl9E9UqyrY6xl2wNHWrwsqnY-GC_sz8civP8M75DDVaUwjOB2NQmogGejpM3tWB7aUeo9DMg5Q_qt340-rbQWi1txkSOizHp5jE9xtGXpgIgZC-yEH6gzIwW6t44pdv44xPzO3bMLEoKd8w' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22098server # time=2026-10-01T10:34:02.574Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2099server # [ 33.040272] niks3-server[953]: 2026/10/01 10:34:02 WARN Authentication failed token_preview=eyJhbGciOi...3bMLEoKd8w token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2100server # [ 33.082804] systemd[1]: Started Nix Daemon instance (PID 1380/UID 0).2101server # [ 33.138039] nix-daemon[1382]: remote pid 1380 is unknown user (trusted)2102server # [ 33.151863] systemd[1]: nix-daemon@11-12-1380_1381-0.service: Deactivated successfully.2103server # [ 33.157880] niks3-server[953]: 2026/10/01 10:34:02 WARN Authentication failed token_preview=eyJhbGciOi...3bMLEoKd8w token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2104server # time=2026-10-01T10:34:02.738Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2105server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Ik5uTVFBRGpHUFMxbkprZ1p0VVVPR3Jqd1QxZHZhUFcxVUtuazlFX3RIY1UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTA4NTQ0NDIsImlhdCI6MTc5MDg1MDg0MiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.SrPJawZli2BxJ5avzfSmqYUFCRtM6yR3mxzXUDDI2IYsX7NK9N4sboPJOId6R0nCPQi5DQtSE91fIxnJSpGW9PmRQRiczU9KKW6865IgS0o2an2uuK05Yh39R6SPRVj4LjGzyjc7oQ6Ser9PuEtn2bvYW394oratUcWfzglzZQWIsYNkz_aZaUyZIUkfquugLpq9BQaxl9E9UqyrY6xl2wNHWrwsqnY-GC_sz8civP8M75DDVaUwjOB2NQmogGejpM3tWB7aUeo9DMg5Q_qt340-rbQWi1txkSOizHp5jE9xtGXpgIgZC-yEH6gzIwW6t44pdv44xPzO3bMLEoKd8w' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.18 seconds)2106server: 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'2107server: (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)2108server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Ik5uTVFBRGpHUFMxbkprZ1p0VVVPR3Jqd1QxZHZhUFcxVUtuazlFX3RIY1UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MDg1NDQ0MiwiaWF0IjoxNzkwODUwODQyLCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.a3Rz8z-L216Dllb_aLZO4ThJfj6vD1NlOHCFqN8PJjOq_bqHr_HGPu-ysZ5Jc0-ujLurOooZUVDMRnmrY2kBgE2V8LtiKYr1pWaJvjs6HMJhI-LzXFA-oON9N1kx0jBqN44IgoNogXk6Rt7Q0pxMQMopeoSrgYR2C5NW-AkEeRL39LLBa3-YbBJLqI6HbXjKpAzmsGB9Fpgsi2aqBVCl-q8QJrN1JEe-HbwUQIoG69pnuWDwVECeIXBfFDcudLCxN3QlxyBrluHpOGD7QptDBL3RMBjV5nsmWy0uwKJZ_C3H2nWzerJvRT6tlOHctJXIiRRrm_WEGxsBf82I2SAaig' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22109server # time=2026-10-01T10:34:02.784Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2110server # [ 33.251159] niks3-server[953]: 2026/10/01 10:34:02 WARN Authentication failed token_preview=eyJhbGciOi...f82I2SAaig token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2111server # [ 33.293386] systemd[1]: Started Nix Daemon instance (PID 1402/UID 0).2112server # [ 33.339796] nix-daemon[1404]: remote pid 1402 is unknown user (trusted)2113server # [ 33.353286] systemd[1]: nix-daemon@12-13-1402_1403-0.service: Deactivated successfully.2114server # [ 33.359988] niks3-server[953]: 2026/10/01 10:34:02 WARN Authentication failed token_preview=eyJhbGciOi...f82I2SAaig token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2115server # time=2026-10-01T10:34:02.939Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2116server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Ik5uTVFBRGpHUFMxbkprZ1p0VVVPR3Jqd1QxZHZhUFcxVUtuazlFX3RIY1UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MDg1NDQ0MiwiaWF0IjoxNzkwODUwODQyLCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.a3Rz8z-L216Dllb_aLZO4ThJfj6vD1NlOHCFqN8PJjOq_bqHr_HGPu-ysZ5Jc0-ujLurOooZUVDMRnmrY2kBgE2V8LtiKYr1pWaJvjs6HMJhI-LzXFA-oON9N1kx0jBqN44IgoNogXk6Rt7Q0pxMQMopeoSrgYR2C5NW-AkEeRL39LLBa3-YbBJLqI6HbXjKpAzmsGB9Fpgsi2aqBVCl-q8QJrN1JEe-HbwUQIoG69pnuWDwVECeIXBfFDcudLCxN3QlxyBrluHpOGD7QptDBL3RMBjV5nsmWy0uwKJZ_C3H2nWzerJvRT6tlOHctJXIiRRrm_WEGxsBf82I2SAaig' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.17 seconds)2117server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test22118server # time=2026-10-01T10:34:02.959Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2119server # [ 33.433277] niks3-server[953]: 2026/10/01 10:34:03 WARN Authentication failed token_preview=not-a-valid-jwt-token token_length=21 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2120server # [ 33.481712] systemd[1]: Started Nix Daemon instance (PID 1421/UID 0).2121server # [ 33.534945] nix-daemon[1423]: remote pid 1421 is unknown user (trusted)2122server # [ 33.549135] systemd[1]: nix-daemon@13-14-1421_1422-0.service: Deactivated successfully.2123server # [ 33.556197] niks3-server[953]: 2026/10/01 10:34:03 WARN Authentication failed token_preview=not-a-valid-jwt-token token_length=21 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2124server # time=2026-10-01T10:34:03.136Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2125server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/dby496qg7y54y76acj9wnf06zq5ad9fy-oidc-test2, in 0.20 seconds)2126server: must succeed: 2127 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins create hello-pin /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.321282129server # [ 33.623638] niks3-server[953]: 2026/10/01 10:34:03 INFO Received create pin request method=POST path=/api/pins/hello-pin2130server # time=2026-10-01T10:34:03.208Z level=INFO msg="Created pin" name=hello-pin store_path=/nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.32131server # [ 33.633745] niks3-server[953]: 2026/10/01 10:34:03 INFO Created/updated pin name=hello-pin store_path=/nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.3 narinfo_key=kwhxkl8yn5y8wqiq11jsybagw3fbc4iv.narinfo2132server: (finished: must succeed: 2133 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins create hello-pin /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.32134, in 0.07 seconds)2135server: must succeed: 2136 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list21372138server # [ 33.721735] niks3-server[953]: 2026/10/01 10:34:03 INFO Received list pins request method=GET path=/api/pins2139server: (finished: must succeed: 2140 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list2141, in 0.09 seconds)2142server: must succeed: 2143 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --names-only21442145server # [ 33.780928] niks3-server[953]: 2026/10/01 10:34:03 INFO Received list pins request method=GET path=/api/pins2146server: (finished: must succeed: 2147 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --names-only2148, in 0.06 seconds)2149server: must succeed: 2150 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --json21512152server # [ 33.837292] niks3-server[953]: 2026/10/01 10:34:03 INFO Received list pins request method=GET path=/api/pins2153server: (finished: must succeed: 2154 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --json2155, in 0.06 seconds)2156server: must succeed: 2157 export S3_ENDPOINT_URL=http://localhost:90002158 export AWS_ACCESS_KEY_ID=rustfsadmin2159 export AWS_SECRET_ACCESS_KEY=rustfsadmin2160 /nix/store/1mxif175wb0rn50pgaisvczshqv3mw1i-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin21612162server: (finished: must succeed: 2163 export S3_ENDPOINT_URL=http://localhost:90002164 export AWS_ACCESS_KEY_ID=rustfsadmin2165 export AWS_SECRET_ACCESS_KEY=rustfsadmin2166 /nix/store/1mxif175wb0rn50pgaisvczshqv3mw1i-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin2167, in 0.03 seconds)2168server: must succeed: 2169 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --pin ca-pin /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log21702171server # time=2026-10-01T10:34:03.501Z level=INFO msg="All 1 paths already cached"2172server # [ 33.927082] niks3-server[953]: 2026/10/01 10:34:03 INFO Received create pin request method=POST path=/api/pins/ca-pin2173server # time=2026-10-01T10:34:03.519Z level=INFO msg="Created pin" name=ca-pin store_path=/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2174server # [ 33.944393] niks3-server[953]: 2026/10/01 10:34:03 INFO Created/updated pin name=ca-pin store_path=/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log narinfo_key=z57vvdhnnry9vypazxb33pyi7hsaz71i.narinfo2175server: (finished: must succeed: 2176 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 push --pin ca-pin /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2177, in 0.08 seconds)2178server: must succeed: 2179 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --names-only21802181server # [ 34.006947] niks3-server[953]: 2026/10/01 10:34:03 INFO Received list pins request method=GET path=/api/pins2182server: (finished: must succeed: 2183 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --names-only2184, in 0.06 seconds)2185server: must succeed: 2186 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins delete hello-pin21872188server # [ 34.065353] niks3-server[953]: 2026/10/01 10:34:03 INFO Received delete pin request method=DELETE path=/api/pins/hello-pin2189server # time=2026-10-01T10:34:03.650Z level=INFO msg="Deleted pin" name=hello-pin2190server # [ 34.074456] niks3-server[953]: 2026/10/01 10:34:03 INFO Deleted pin name=hello-pin2191server: (finished: must succeed: 2192 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins delete hello-pin2193, in 0.07 seconds)2194server: must succeed: 2195 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --names-only21962197server # [ 34.134898] niks3-server[953]: 2026/10/01 10:34:03 INFO Received list pins request method=GET path=/api/pins2198server: (finished: must succeed: 2199 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins list --names-only2200, in 0.06 seconds)2201server: must fail: 2202 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent22032204server # [ 34.192105] niks3-server[953]: 2026/10/01 10:34:03 INFO Received create pin request method=POST path=/api/pins/bad-pin2205server # time=2026-10-01T10:34:03.770Z level=ERROR msg="Fatal error" error="creating pin: server returned 400: invalid store path: invalid store path format: /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent\n"2206server: (finished: must fail: 2207 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/5b8nak2d08g6vnj3s7i7iz9h9a2yyj4p-niks3-1.13.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent2208, in 0.06 seconds)2209server: must succeed: systemctl start niks3-gc.service2210server # [ 34.223816] systemd[1]: Starting niks3 garbage collection...2211server # [ 34.272811] niks3[1543]: time=2026-10-01T10:34:03.849Z level=INFO msg="Starting garbage collection" older-than=720h failed-uploads-older-than=6h force=false2212server # [ 34.277055] niks3-server[953]: 2026/10/01 10:34:03 INFO Starting cleanup of old closures method=DELETE path=/api/closures2213server # [ 34.281269] niks3[1543]: time=2026-10-01T10:34:03.856Z level=INFO msg="Garbage collection started"2214server # [ 34.283561] niks3-server[953]: 2026/10/01 10:34:03 INFO Aborted multipart uploads count=0 kept=02215server # [ 34.288846] niks3-server[953]: 2026/10/01 10:34:03 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=02216server # [ 34.294700] niks3-server[953]: 2026/10/01 10:34:03 INFO Vacuumed table table=pending_closures2217server # [ 34.298297] niks3-server[953]: 2026/10/01 10:34:03 INFO Vacuumed table table=pending_objects2218server # [ 34.301813] niks3-server[953]: 2026/10/01 10:34:03 INFO Vacuumed table table=multipart_uploads2219server # [ 34.304732] niks3-server[953]: 2026/10/01 10:34:03 INFO Vacuumed table table=closures2220server # [ 34.307722] niks3-server[953]: 2026/10/01 10:34:03 INFO Vacuumed table table=objects2221server # [ 36.281212] niks3[1543]: time=2026-10-01T10:34:05.857Z level=INFO msg="Garbage collection completed successfully" failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=02222server # [ 36.291136] systemd[1]: niks3-gc.service: Deactivated successfully.2223server # [ 36.295694] systemd[1]: Finished niks3 garbage collection.2224server # [ 36.298916] systemd[1]: niks3-gc.service: Consumed 39ms CPU time over 2.069s wall clock time, 2.5M memory peak, 1.2K incoming IP traffic, 823B outgoing IP traffic.2225server: (finished: must succeed: systemctl start niks3-gc.service, in 2.11 seconds)2226builder: waiting for unit niks3-auto-upload.socket2227builder: waiting for the VM to finish booting2228builder: Guest shell says: b'Spawning backdoor root shell...\n'2229builder: connected to guest root shell2230builder: (connecting took 0.00 seconds)2231builder: (finished: waiting for the VM to finish booting, in 0.00 seconds)2232builder: (finished: waiting for unit niks3-auto-upload.socket, in 0.04 seconds)2233builder: must succeed: test -S /run/niks3/upload-to-cache.sock2234builder: (finished: must succeed: test -S /run/niks3/upload-to-cache.sock, in 0.02 seconds)2235builder: must succeed: grep post-build-hook /etc/nix/nix.conf2236builder: (finished: must succeed: grep post-build-hook /etc/nix/nix.conf, in 0.02 seconds)2237builder: must succeed: 2238cat > /tmp/test-drv.nix << 'EOF'2239derivation {2240 name = "post-build-hook-test";2241 system = builtins.currentSystem;2242 builder = "/bin/sh";2243 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2244}2245EOF22462247builder: (finished: must succeed: 2248cat > /tmp/test-drv.nix << 'EOF'2249derivation {2250 name = "post-build-hook-test";2251 system = builtins.currentSystem;2252 builder = "/bin/sh";2253 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2254}2255EOF2256, in 0.02 seconds)2257builder: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link2258builder # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2259builder # [ 36.469665] systemd[1]: Created slice Slice /system/nix-daemon.2260builder # [ 36.473699] systemd[1]: Started Nix Daemon instance (PID 769/UID 0).2261builder # [ 36.525782] nix-daemon[773]: remote pid 769 is unknown user (trusted)2262builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 585 ms (attempt 1/5)2263builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 931 ms (attempt 2/5)2264builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 1973 ms (attempt 3/5)2265builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 3886 ms (attempt 4/5)2266builder # 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)2267builder # this derivation will be built:2268builder # /nix/store/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv2269builder # building '/nix/store/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv'...2270builder # [ 44.079853] systemd[1]: Started niks3 auto-upload daemon.2271builder # [ 44.188997] niks3-hook[798]: time=2026-10-01T10:34:13.748Z level=INFO msg="niks3-hook serve starting" socket=/run/niks3/upload-to-cache.sock socket-activated=true db-path=/var/lib/niks3-hook/upload-queue.db batch-size=5 idle-exit-timeout=5s drain-timeout=0s2272builder # [ 44.196289] niks3-hook[798]: time=2026-10-01T10:34:13.755Z level=INFO msg="Upload queue status" pending=12273builder # [ 44.199738] niks3-hook[798]: time=2026-10-01T10:34:13.755Z level=INFO msg="Uploading batch" count=12274builder: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link, in 7.81 seconds)2275builder: waiting for unit niks3-auto-upload.service2276builder # [ 44.226607] systemd[1]: nix-daemon@0-1-769_770-0.service: Deactivated successfully.2277builder # [ 44.231830] systemd[1]: nix-daemon@0-1-769_770-0.service: Consumed 173ms CPU time over 7.753s wall clock time, 19.6M memory peak, 1.4K outgoing IP traffic.2278builder: (finished: waiting for unit niks3-auto-upload.service, in 0.08 seconds)2279??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2280 File "/nix/store/iiab4a2pmymr5lc22a6yc0d0fyf3g00n-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392281builder: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive2282??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2283 File "/nix/store/iiab4a2pmymr5lc22a6yc0d0fyf3g00n-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392284builder # [ 44.311562] systemd[1]: Started Nix Daemon instance (PID 809/UID 0).2285builder # [ 44.377720] nix-daemon[820]: remote pid 809 is unknown user (trusted)2286builder # [ 44.390606] systemd[1]: nix-daemon@1-2-809_810-0.service: Deactivated successfully.2287server # [ 44.398810] niks3-server[953]: 2026/10/01 10:34:13 INFO Received push request method=POST path=/api/pushes2288builder # [ 44.407913] niks3-hook[798]: time=2026-10-01T10:34:13.966Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2289builder # [ 44.409763] niks3-hook[798]: time=2026-10-01T10:34:13.966Z level=INFO msg="Uploading 1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test (144B)"2290server # [ 44.439809] niks3-server[953]: 2026/10/01 10:34:14 INFO Registered completed upload object_key=log/agd1mlaczhjppin104qn9mshm9plkg2x-post-build-hook-test.drv2291server # [ 44.446825] niks3-server[953]: 2026/10/01 10:34:14 INFO Registered completed upload object_key=nar/1a0vfw93shmvlz6lac7d1lv82jxzfdswigh5s32hpvncf74i1i0y.nar.zst2292server # [ 44.456835] niks3-server[953]: 2026/10/01 10:34:14 INFO Received sign narinfos request method=POST path=/api/pushes/6/sign2293builder # [ 44.457606] niks3-hook[798]: time=2026-10-01T10:34:14.016Z level=INFO msg="Uploading 1 narinfos"2294server # [ 44.459301] niks3-server[953]: 2026/10/01 10:34:14 INFO Signed narinfos id=6 count=12295server # [ 44.465415] niks3-server[953]: 2026/10/01 10:34:14 INFO Registered completed upload object_key=1qlca7drab1x7rccc3h1pw1fl9c0j7yx.ls2296server # [ 44.474281] niks3-server[953]: 2026/10/01 10:34:14 INFO Received complete push request method=POST path=/api/pushes/6/complete2297builder # [ 44.478877] niks3-hook[798]: time=2026-10-01T10:34:14.038Z level=INFO msg="Upload complete. (283ms)"2298server # [ 44.480623] niks3-server[953]: 2026/10/01 10:34:14 INFO Registered completed upload object_key=1qlca7drab1x7rccc3h1pw1fl9c0j7yx.narinfo2299builder # [ 49.196459] niks3-hook[798]: time=2026-10-01T10:34:18.756Z level=INFO msg="Idle timeout reached and queue is empty, shutting down"2300builder # [ 49.199612] niks3-hook[798]: time=2026-10-01T10:34:18.759Z level=INFO msg="niks3-hook serve stopped"2301builder # [ 49.209672] systemd[1]: niks3-auto-upload.service: Deactivated successfully.2302builder # [ 49.213160] systemd[1]: niks3-auto-upload.service: Consumed 140ms CPU time over 5.132s wall clock time, 10.7M memory peak, 68K written to disk, 5.5K incoming IP traffic, 8.3K outgoing IP traffic.2303builder: (finished: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive, in 5.20 seconds)2304server: must succeed: 2305 export AWS_ACCESS_KEY_ID=rustfsadmin2306export AWS_SECRET_ACCESS_KEY=rustfsadmin2307 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-test23082309server: (finished: must succeed: 2310 export AWS_ACCESS_KEY_ID=rustfsadmin2311export AWS_SECRET_ACCESS_KEY=rustfsadmin2312 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-test2313, in 0.21 seconds)2314server: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test2315server: (finished: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test, in 0.05 seconds)2316(finished: run the VM test script, in 50.49 seconds)2317test script finished in 50.58s2318cleanup2319kill QemuMachine (pid 47)2320builder # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2321builder # [2026-10-01T10:34:19Z INFO virtiofsd] Client disconnected, shutting down2322builder # [2026-10-01T10:34:19Z INFO virtiofsd] Client disconnected, shutting down2323builder # [2026-10-01T10:34:19Z INFO virtiofsd] Client disconnected, shutting down2324kill QemuMachine (pid 48)2325server # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2326server # [2026-10-01T10:34:19Z INFO virtiofsd] Client disconnected, shutting down2327server # [2026-10-01T10:34:19Z INFO virtiofsd] Client disconnected, shutting down2328server # [2026-10-01T10:34:19Z INFO virtiofsd] Client disconnected, shutting down2329(finished: cleanup, in 0.45 seconds)2330additionally exposed symbols:2331 builder, server,2332 vlan1,2333 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_ssh2334Hello store path: /nix/store/kwhxkl8yn5y8wqiq11jsybagw3fbc4iv-hello-2.12.32335Test output path: /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2336Symlink wrapper store path: /nix/store/0caxmbk1mdnhh6zbyh0sz4faqqy5f08r-symlink-wrapper2337Symlink wrapper points to: /nix/store/xafv64bljflg1v8hnf22lyk0w4gma3v5-base-package/bin/test-program2338OIDC test store path: /nix/store/x6bp2ql9fqpj8iqqnnpyn3v0qs5b88gj-oidc-test2339Valid OIDC token obtained (length=677)2340OIDC push with valid token: SUCCESS2341Invalid OIDC token obtained (wrong org)2342OIDC push with wrong org: correctly rejected2343Wrong audience OIDC token obtained2344OIDC push with wrong audience: correctly rejected2345OIDC push with malformed token: correctly rejected2346All OIDC tests passed!2347All pin tests passed!2348Built store path: /nix/store/1qlca7drab1x7rccc3h1pw1fl9c0j7yx-post-build-hook-test2349Post-build-hook pipeline test passed!