vm-test-run-nixos-test-external-signer
checks.x86_64-linux.nixos-test-external-signer
· build #422
· raw
1tribuchet: building on jamie2Machine 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 script7server: waiting for unit niks3.service8server: waiting for the VM to finish booting9server: starting vm10server: QEMU running (pid 45)11server # Disk image does not exist, creating the virtualisation disk image...12server # Formatting '/build/vm-state-server/tmp.XSRtaoWUhk', fmt=raw size=107374182413server # mke2fs 1.47.4 (6-Mar-2025)14server # Discarding device blocks: 0/262144 done15server # Creating filesystem with 262144 4k blocks and 65536 inodes16server # Filesystem UUID: 824a7c76-a7ce-45c6-b8d5-f74c242c739e17server # Superblock backups stored on blocks:18server # 32768, 98304, 163840, 22937619server # 20server # Allocating group tables: 0/8 done21server # Writing inode tables: 0/8 done22server # Creating journal (8192 blocks): done23server # Writing superblocks and filesystem accounting information: 0/8 done24server # 25server # Virtualisation disk image created.26server # Starting virtiofs daemons...27server # [2026-10-25T13:22:33Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28server # [2026-10-25T13:22:33Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29server # [2026-10-25T13:22:33Z INFO virtiofsd] Waiting for vhost-user socket connection...30server # [2026-10-25T13:22:33Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31server # [2026-10-25T13:22:33Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32server # [2026-10-25T13:22:33Z INFO virtiofsd] Waiting for vhost-user socket connection...33server # [2026-10-25T13:22:33Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34server # [2026-10-25T13:22:33Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35server # [2026-10-25T13:22:33Z INFO virtiofsd] Waiting for vhost-user socket connection...36server # [2026-10-25T13:22:34Z INFO virtiofsd] Client connected, servicing requests37server # [2026-10-25T13:22:34Z INFO virtiofsd] Client connected, servicing requests38server # [2026-10-25T13:22:34Z INFO virtiofsd] Client connected, servicing requests39server # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)40server # 41server # 42server # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0043server # Press Ctrl-B to configure iPXE (PCI 00:02.0)...44server # 45server # 46server # 47server # 48server # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0049server # Press Ctrl-B to configure iPXE (PCI 00:05.0)...50server # 51server # 52server # Booting from ROM...53server # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.55 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Sat Oct 3 10:39:03 UTC 202654server # [ 0.000000] Command line: console=ttyS0 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/ibyjdi2hzpiba80x2lgfp4d9cvdgap1w-nixos-system-server-test/init regInfo=/nix/.ro-store/km5lq56kd15asmki2l29xyipg7hp42rb-closure-info/registration console=ttyS0,115200n8 console=tty055server # [ 0.000000] BIOS-provided physical RAM map:56server # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable57server # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved58server # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved59server # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable60server # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved61server # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved62server # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved63server # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved64server # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved65server # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved66server # [ 0.000000] NX (Execute Disable) protection: active67server # [ 0.000000] APIC: Static calls initialized68server # [ 0.000000] SMBIOS 2.8 present.69server # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201470server # [ 0.000000] DMI: Memory slots populated: 1/171server # [ 0.000000] Hypervisor detected: KVM72server # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000073server # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0074server # [ 0.000000] kvm-clock: using sched offset of 618007215 cycles75server # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns76server # [ 0.000006] tsc: Detected 2400.008 MHz processor77server # [ 0.000765] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000078server # [ 0.000793] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs79server # [ 0.000796] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT80server # [ 0.002683] found SMP MP-table at [mem 0x000f5450-0x000f545f]81server # [ 0.002696] Using GB pages for direct mapping82server # [ 0.002790] RAMDISK: [mem 0x3e2e7000-0x3ffcffff]83server # [ 0.002799] ACPI: Early table checksum verification disabled84server # [ 0.002803] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )85server # [ 0.002807] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)86server # [ 0.002811] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)87server # [ 0.002819] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)88server # [ 0.002821] ACPI: FACS 0x000000003FFE0000 00004089server # [ 0.002823] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)90server # [ 0.002824] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)91server # [ 0.002826] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)92server # [ 0.002827] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)93server # [ 0.002829] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]94server # [ 0.002830] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]95server # [ 0.002830] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]96server # [ 0.002831] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]97server # [ 0.002832] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]98server # [ 0.002832] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]99server # [ 0.002832] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]100server # [ 0.003055] No NUMA configuration found101server # [ 0.003056] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]102server # [ 0.003059] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]103server # [ 0.005343] Zone ranges:104server # [ 0.005344] DMA [mem 0x0000000000001000-0x0000000000ffffff]105server # [ 0.005346] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]106server # [ 0.005347] Normal empty107server # [ 0.005348] Device empty108server # [ 0.005348] Movable zone start for each node109server # [ 0.005349] Early memory node ranges110server # [ 0.005350] node 0: [mem 0x0000000000001000-0x000000000009efff]111server # [ 0.005351] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]112server # [ 0.005352] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]113server # [ 0.005371] On node 0, zone DMA: 1 pages in unavailable ranges114server # [ 0.005633] On node 0, zone DMA: 97 pages in unavailable ranges115server # [ 0.022876] On node 0, zone DMA32: 40 pages in unavailable ranges116server # [ 0.023331] ACPI: PM-Timer IO Port: 0x608117server # [ 0.023343] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])118server # [ 0.023371] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23119server # [ 0.023373] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)120server # [ 0.023375] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)121server # [ 0.023376] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)122server # [ 0.023377] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)123server # [ 0.023378] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)124server # [ 0.023380] ACPI: Using ACPI (MADT) for SMP configuration information125server # [ 0.023381] ACPI: HPET id: 0x8086a201 base: 0xfed00000126server # [ 0.023384] TSC deadline timer available127server # [ 0.023388] CPU topo: Max. logical packages: 1128server # [ 0.023389] CPU topo: Max. logical dies: 1129server # [ 0.023389] CPU topo: Max. dies per package: 1130server # [ 0.023393] CPU topo: Max. threads per core: 1131server # [ 0.023394] CPU topo: Num. cores per package: 1132server # [ 0.023394] CPU topo: Num. threads per package: 1133server # [ 0.023394] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs134server # [ 0.023415] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()135server # [ 0.023446] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]136server # [ 0.023447] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]137server # [ 0.023449] [mem 0x40000000-0xafffffff] available for PCI devices138server # [ 0.023450] Booting paravirtualized kernel on KVM139server # [ 0.023453] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns140server # [ 0.028101] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1141server # [ 0.030451] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152142server # [ 0.030494] kvm-guest: PV spinlocks disabled, single CPU143server # [ 0.030496] Kernel command line: console=ttyS0 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/ibyjdi2hzpiba80x2lgfp4d9cvdgap1w-nixos-system-server-test/init regInfo=/nix/.ro-store/km5lq56kd15asmki2l29xyipg7hp42rb-closure-info/registration console=ttyS0,115200n8 console=tty0144server # [ 0.030591] Unknown kernel command line parameters "regInfo=/nix/.ro-store/km5lq56kd15asmki2l29xyipg7hp42rb-closure-info/registration", will be passed to user space.145server # [ 0.030604] random: crng init done146server # [ 0.030604] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes147server # [ 0.030626] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)148server # [ 0.031151] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)149server # [ 0.031181] Fallback order for Node 0: 0150server # [ 0.031184] Built 1 zonelists, mobility grouping on. Total pages: 262006151server # [ 0.031185] Policy zone: DMA32152server # [ 0.033576] mem auto-init: stack:all(zero), heap alloc:on, heap free:off153server # [ 0.036079] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1154server # [ 0.038314] allocated 2097152 bytes of page_ext155server # [ 0.048598] ftrace: allocating 48837 entries in 192 pages156server # [ 0.048600] ftrace: allocated 192 pages with 2 groups157server # [ 0.049445] Dynamic Preempt: lazy158server # [ 0.049574] rcu: Preemptible hierarchical RCU implementation.159server # [ 0.049575] rcu: RCU event tracing is enabled.160server # [ 0.049575] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.161server # [ 0.049577] Trampoline variant of Tasks RCU enabled.162server # [ 0.049577] Rude variant of Tasks RCU enabled.163server # [ 0.049578] Tracing variant of Tasks RCU enabled.164server # [ 0.049578] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.165server # [ 0.049579] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1166server # [ 0.049594] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.167server # [ 0.049596] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.168server # [ 0.049597] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.169server # [ 0.053878] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16170server # [ 0.054154] rcu: srcu_init: Setting srcu_struct sizes based on contention.171server # [ 0.054161] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns172server # [ 0.054264] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)173server # [ 0.057784] Console: colour VGA+ 80x25174server # [ 0.057787] printk: legacy console [tty0] enabled175server # [ 0.087202] printk: legacy console [ttyS0] enabled176server # [ 0.193114] ACPI: Core revision 20250807177server # [ 0.194047] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns178server # [ 0.195654] APIC: Switch to symmetric I/O mode setup179server # [ 0.196641] x2apic enabled180server # [ 0.197399] APIC: Switched APIC routing to: physical x2apic181server # [ 0.199176] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1182server # [ 0.200213] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983f10a64, max_idle_ns: 440795218721 ns183server # [ 0.201944] Calibrating delay loop (skipped) preset value.. 4800.01 BogoMIPS (lpj=2400008)184server # [ 0.203025] x86/cpu: User Mode Instruction Prevention (UMIP) activated185server # [ 0.204967] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127186server # [ 0.205928] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0187server # [ 0.206945] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto188server # [ 0.207941] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl189server # [ 0.209941] Transient Scheduler Attacks: Vulnerable: No microcode190server # [ 0.210941] Spectre V2 : Mitigation: Enhanced / Automatic IBRS191server # [ 0.211941] Speculative Return Stack Overflow: Mitigation: Safe RET192server # [ 0.212941] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization193server # [ 0.214948] Spectre V2 : Enabling IBPB for BPF194server # [ 0.215942] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier195server # [ 0.216941] active return thunk: srso_alias_return_thunk196server # [ 0.217962] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'197server # [ 0.218941] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'198server # [ 0.219941] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'199server # [ 0.221773] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'200server # [ 0.222758] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'201server # [ 0.223698] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'202server # [ 0.224797] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'203server # [ 0.225941] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256204server # [ 0.226941] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64205server # [ 0.227941] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512206server # [ 0.228941] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024207server # [ 0.229941] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8208server # [ 0.230941] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.209server # [ 0.264987] Freeing SMP alternatives memory: 48K210server # [ 0.265772] pid_max: default: 32768 minimum: 301211server # [ 0.266619] LSM: initializing lsm=capability,landlock,yama,bpf,ima212server # [ 0.267053] landlock: Up and running.213server # [ 0.268419] Yama: becoming mindful.214server # [ 0.269152] LSM support for eBPF active215server # [ 0.270017] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)216server # [ 0.270966] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)217server # [ 0.273716] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)218server # [ 0.274445] Performance Events: Fam17h+ core perfctr, AMD PMU driver.219server # [ 0.274946] ... version: 2220server # [ 0.275679] ... bit width: 48221server # [ 0.275944] ... generic counters: 6222server # [ 0.276698] ... generic bitmap: 000000000000003f223server # [ 0.276988] ... fixed-purpose counters: 0224server # [ 0.277746] ... fixed-purpose bitmap: 0000000000000000225server # [ 0.278689] ... value mask: 0000ffffffffffff226server # [ 0.278943] ... max period: 00007fffffffffff227server # [ 0.279890] ... global_ctrl mask: 000000000000003f228server # [ 0.280786] signal: max sigframe size: 3376229server # [ 0.281600] rcu: Hierarchical SRCU implementation.230server # [ 0.281946] rcu: Max phase no-delay instances is 400.231server # [ 0.287404] smp: Bringing up secondary CPUs ...232server # [ 0.287956] smp: Brought up 1 node, 1 CPU233server # [ 0.288670] smpboot: Total of 1 processors activated (4800.01 BogoMIPS)234server # [ 0.289088] Memory: 940552K/1048024K available (18124K kernel code, 2728K rwdata, 13644K rodata, 3656K init, 3632K bss, 100124K reserved, 0K cma-reserved)235server # [ 0.290144] devtmpfs: initialized236server # [ 0.291014] x86/mm: Memory block size: 128MB237server # [ 0.292717] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)238server # [ 0.293916] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).239server # [ 0.295023] pinctrl core: initialized pinctrl subsystem240server # [ 0.296210] PM: RTC time: 13:22:34, date: 2026-10-25241server # [ 0.299595] NET: Registered PF_NETLINK/PF_ROUTE protocol family242server # [ 0.300302] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations243server # [ 0.300960] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations244server # [ 0.302081] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations245server # [ 0.302953] audit: initializing netlink subsys (disabled)246server # [ 0.304136] thermal_sys: Registered thermal governor 'fair_share'247server # [ 0.304138] thermal_sys: Registered thermal governor 'bang_bang'248server # [ 0.304947] audit: type=2000 audit(1792934554.852:1): state=initialized audit_enabled=0 res=1249server # [ 0.306952] thermal_sys: Registered thermal governor 'step_wise'250server # [ 0.306953] thermal_sys: Registered thermal governor 'user_space'251server # [ 0.307943] thermal_sys: Registered thermal governor 'power_allocator'252server # [ 0.308970] cpuidle: using governor menu253server # [ 0.311202] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5254server # [ 0.312194] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]255server # [ 0.312947] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry256server # [ 0.313955] PCI: Using configuration type 1 for base access257server # [ 0.315094] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.258server # [ 0.321973] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages259server # [ 0.322944] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page260server # [ 0.327945] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages261server # [ 0.328944] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page262server # [ 0.340197] ACPI: Added _OSI(Module Device)263server # [ 0.340915] ACPI: Added _OSI(Processor Device)264server # [ 0.341513] ACPI: Added _OSI(Processor Aggregator Device)265server # [ 0.347404] ACPI: 1 ACPI AML tables successfully acquired and loaded266server # [ 0.351350] ACPI: Interpreter enabled267server # [ 0.351960] ACPI: PM: (supports S0 S3 S4 S5)268server # [ 0.352726] ACPI: Using IOAPIC for interrupt routing269server # [ 0.355003] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug270server # [ 0.355944] PCI: Using E820 reservations for host bridge windows271server # [ 0.359082] ACPI: Enabled 2 GPEs in block 00 to 3F272server # [ 0.365007] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])273server # [ 0.365948] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]274server # [ 0.367022] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]275server # [ 0.368069] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]276server # [ 0.369278] PCI host bridge to bus 0000:00277server # [ 0.369948] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]278server # [ 0.370944] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]279server # [ 0.371944] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]280server # [ 0.372943] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]281server # [ 0.373944] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]282server # [ 0.374944] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]283server # [ 0.375944] pci_bus 0000:00: root bus resource [bus 00-ff]284server # [ 0.376989] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint285server # [ 0.378413] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint286server # [ 0.380999] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]287server # [ 0.382027] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]288server # [ 0.382965] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]289server # [ 0.384361] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]290server # [ 0.385670] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint291server # [ 0.387986] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]292server # [ 0.388893] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]293server # [ 0.389736] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]294server # [ 0.390862] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]295server # [ 0.392409] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint296server # [ 0.393987] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]297server # [ 0.394882] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]298server # [ 0.395757] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]299server # [ 0.397388] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint300server # [ 0.399951] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]301server # [ 0.400853] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]302server # [ 0.401727] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]303server # [ 0.403408] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint304server # [ 0.404982] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]305server # [ 0.405891] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]306server # [ 0.406753] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]307server # [ 0.407950] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]308server # [ 0.409469] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint309server # [ 0.410963] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]310server # [ 0.411966] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]311server # [ 0.413551] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint312server # [ 0.414956] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]313server # [ 0.415966] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]314server # [ 0.417520] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint315server # [ 0.418956] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]316server # [ 0.419966] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]317server # [ 0.421524] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint318server # [ 0.423252] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]319server # [ 0.423966] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]320server # [ 0.425503] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint321server # [ 0.426988] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]322server # [ 0.427874] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]323server # [ 0.428757] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]324server # [ 0.430395] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint325server # [ 0.431980] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]326server # [ 0.432890] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]327server # [ 0.433765] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]328server # [ 0.435410] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint329server # [ 0.436556] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]330server # [ 0.437144] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint331server # [ 0.439304] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]332server # [ 0.440134] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint333server # [ 0.441598] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]334server # [ 0.442222] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint335server # [ 0.444573] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]336server # [ 0.445176] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint337server # [ 0.446362] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO338server # [ 0.447256] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint339server # [ 0.448956] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]340server # [ 0.449863] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]341server # [ 0.451041] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint342server # [ 0.452601] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]343server # [ 0.456460] ACPI: PCI: Interrupt link LNKA configured for IRQ 10344server # [ 0.457049] ACPI: PCI: Interrupt link LNKB configured for IRQ 10345server # [ 0.458050] ACPI: PCI: Interrupt link LNKC configured for IRQ 11346server # [ 0.459044] ACPI: PCI: Interrupt link LNKD configured for IRQ 11347server # [ 0.460088] ACPI: PCI: Interrupt link LNKE configured for IRQ 10348server # [ 0.461042] ACPI: PCI: Interrupt link LNKF configured for IRQ 10349server # [ 0.462048] ACPI: PCI: Interrupt link LNKG configured for IRQ 11350server # [ 0.463055] ACPI: PCI: Interrupt link LNKH configured for IRQ 11351server # [ 0.463985] ACPI: PCI: Interrupt link GSIA configured for IRQ 16352server # [ 0.464959] ACPI: PCI: Interrupt link GSIB configured for IRQ 17353server # [ 0.465958] ACPI: PCI: Interrupt link GSIC configured for IRQ 18354server # [ 0.466958] ACPI: PCI: Interrupt link GSID configured for IRQ 19355server # [ 0.467956] ACPI: PCI: Interrupt link GSIE configured for IRQ 20356server # [ 0.468953] ACPI: PCI: Interrupt link GSIF configured for IRQ 21357server # [ 0.469956] ACPI: PCI: Interrupt link GSIG configured for IRQ 22358server # [ 0.470918] ACPI: PCI: Interrupt link GSIH configured for IRQ 23359server # [ 0.472790] iommu: Default domain type: Translated360server # [ 0.473573] iommu: DMA domain TLB invalidation policy: lazy mode361server # [ 0.474201] ACPI: bus type USB registered362server # [ 0.474979] usbcore: registered new interface driver usbfs363server # [ 0.475883] usbcore: registered new interface driver hub364server # [ 0.476670] usbcore: registered new device driver usb365server # [ 0.477926] NetLabel: Initializing366server # [ 0.478408] NetLabel: domain hash size = 128367server # [ 0.478943] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO368server # [ 0.479902] NetLabel: unlabeled traffic allowed by default369server # [ 0.480669] PCI: Using ACPI for IRQ routing370server # [ 0.524194] pci 0000:00:01.0: vgaarb: setting as boot VGA device371server # [ 0.524940] pci 0000:00:01.0: vgaarb: bridge control possible372server # [ 0.524940] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none373server # [ 0.524948] vgaarb: loaded374server # [ 0.525694] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0375server # [ 0.525944] hpet0: 3 comparators, 64-bit 100.000000 MHz counter376server # [ 0.530027] clocksource: Switched to clocksource kvm-clock377server # [ 0.531751] VFS: Disk quotas dquot_6.6.0378server # [ 0.534260] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)379server # [ 0.535667] pnp: PnP ACPI init380server # [ 0.536463] ACPI: IRQ 4 override to edge(!), high(!)381server # [ 0.537494] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved382server # [ 0.539102] pnp: PnP ACPI: found 5 devices383server # [ 0.546818] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns384server # [ 0.548315] clocksource: Switched to clocksource acpi_pm385server # [ 0.549334] NET: Registered PF_INET protocol family386server # [ 0.550428] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)387server # [ 0.567513] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)388server # [ 0.569111] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)389server # [ 0.570496] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)390server # [ 0.571899] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)391server # [ 0.573179] TCP: Hash tables configured (established 8192 bind 8192)392server # [ 0.574345] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)393server # [ 0.575673] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)394server # [ 0.576827] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)395server # [ 0.578078] NET: Registered PF_UNIX/PF_LOCAL protocol family396server # [ 0.579092] NET: Registered PF_XDP protocol family397server # [ 0.579992] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]398server # [ 0.581062] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]399server # [ 0.582115] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]400server # [ 0.583292] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]401server # [ 0.584428] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]402server # [ 0.585601] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]403server # [ 0.587357] ACPI: \_SB_.GSIA: Enabled at IRQ 16404server # [ 0.589498] ACPI: \_SB_.GSIB: Enabled at IRQ 17405server # [ 0.591508] ACPI: \_SB_.GSIC: Enabled at IRQ 18406server # [ 0.593438] ACPI: \_SB_.GSID: Enabled at IRQ 19407server # [ 0.595105] PCI: CLS 0 bytes, default 64408server # [ 0.596028] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983f10a64, max_idle_ns: 440795218721 ns409server # [ 0.597839] Trying to unpack rootfs image as initramfs...410server # [ 0.644225] Initialise system trusted keyrings411server # [ 0.647861] workingset: timestamp_bits=40 max_order=18 bucket_order=0412server # [ 0.666881] Key type asymmetric registered413server # [ 0.667598] Asymmetric key parser 'x509' registered414server # [ 0.670848] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)415server # [ 0.673850] io scheduler mq-deadline registered416server # [ 0.674632] io scheduler kyber registered417server # [ 0.678538] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled418server # [ 0.679890] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A419server # [ 0.685412] Linux agpgart interface v0.103420server # [ 0.686234] ACPI: bus type drm_connector registered421server # [ 0.689260] usbcore: registered new interface driver usbserial_generic422server # [ 0.690407] usbserial: USB Serial support registered for generic423server # [ 0.693814] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.424server # [ 0.693814] Please enable it if your BIOS has the CPPC option.425server # [ 0.696146] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled426server # [ 0.699978] drop_monitor: Initializing network drop monitor service427server # [ 0.701190] NET: Registered PF_INET6 protocol family428server # [ 0.704284] Segment Routing with IPv6429server # [ 0.706836] In-situ OAM (IOAM) with IPv6430server # [ 0.707951] IPI shorthand broadcast: enabled431server # [ 0.716242] sched_clock: Marking stable (572013363, 143779425)->(790676805, -74884017)432server # [ 0.720990] registered taskstats version 1433server # [ 0.721977] Loading compiled-in X.509 certificates434server # [ 0.740801] Demotion targets for Node 0: null435server # [ 0.741712] Key type .fscrypt registered436server # [ 0.744798] Key type fscrypt-provisioning registered437server # [ 0.745743] ima: No TPM chip found, activating TPM-bypass!438server # [ 0.746718] ima: Allocated hash algorithm: sha1439server # [ 0.748843] ima: No architecture policies found440server # [ 0.751810] PM: Magic number: 14:106:383441server # [ 0.752531] container PNP0A06:01: hash matches442server # [ 0.753349] acpi PNP0A06:01: hash matches443server # [ 0.756655] RAS: Correctable Errors collector initialized.444server # [ 0.765436] clk: Disabling unused clocks445server # [ 0.767807] PM: genpd: Disabling unused power domains446server # [ 0.930585] Freeing initrd memory: 29604K447server # [ 0.933867] Freeing unused decrypted memory: 2028K448server # [ 0.936694] Freeing unused kernel image (initmem) memory: 3656K449server # [ 0.937796] Write protecting the kernel read-only data: 32768k450server # [ 0.939118] Freeing unused kernel image (text/rodata gap) memory: 304K451server # [ 0.940653] Freeing unused kernel image (rodata/data gap) memory: 692K452server # [ 0.991211] x86/mm: Checked W+X mappings: passed, no W+X pages found.453server # [ 0.992314] Run /init as init process454server # [ 1.002901] systemd[1]: Inserted module 'autofs4'455server # [ 1.022549] fuse: init (API version 7.45)456server # [ 1.029947] ACPI: \_SB_.GSIG: Enabled at IRQ 22457server # [ 1.032320] ACPI: \_SB_.GSIH: Enabled at IRQ 23458server # [ 1.035287] ACPI: \_SB_.GSIE: Enabled at IRQ 20459server # [ 1.037213] ACPI: \_SB_.GSIF: Enabled at IRQ 21460server # [ 1.042315] virtiofs virtio5: discovered new tag: nix-store461server # [ 1.043865] virtiofs virtio5: virtio_fs_setup_dax: No cache capability462server # [ 1.051027] virtiofs virtio6: discovered new tag: shared463server # [ 1.052726] virtiofs virtio6: virtio_fs_setup_dax: No cache capability464server # [ 1.055828] virtiofs virtio7: discovered new tag: xchg465server # [ 1.057210] virtiofs virtio7: virtio_fs_setup_dax: No cache capability466server # [ 1.075604] systemd[1]: Successfully made /usr/ read-only.467server # [ 1.412218] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)468server # [ 1.423983] systemd[1]: Detected virtualization kvm.469server # [ 1.426048] systemd[1]: Detected architecture x86-64.470server # [ 1.428122] systemd[1]: Running in initrd.471server # [ 1.430445] systemd[1]: Initializing machine ID from random generator.472server # [ 1.433301] systemd[1]: Hostname set to <server>.473server # [ 1.522267] systemd[1]: bpf-restrict-fs: LSM BPF program attached474server # [ 1.558997] systemd[1]: Queued start job for default target Initrd Default Target.475server # [ 1.562014] systemd[1]: Created slice Slice /system/modprobe.476server # [ 1.563208] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.477server # [ 1.564648] systemd[1]: Expecting device /dev/disk/by-label/nixos...478server # [ 1.565747] systemd[1]: Reached target Path Units.479server # [ 1.566632] systemd[1]: Reached target Slice Units.480server # [ 1.567537] systemd[1]: Reached target Swaps.481server # [ 1.568360] systemd[1]: Reached target Timer Units.482server # [ 1.569346] systemd[1]: Listening on D-Bus System Message Bus Socket.483server # [ 1.570567] systemd[1]: Listening on Journal Socket (/dev/log).484server # [ 1.571729] systemd[1]: Listening on Journal Sockets.485server # [ 1.572769] systemd[1]: Listening on udev Control Socket.486server # [ 1.573816] systemd[1]: Listening on udev Kernel Socket.487server # [ 1.574746] systemd[1]: Reached target Socket Units.488server # [ 1.576515] systemd[1]: Starting Create List of Static Device Nodes...489server # [ 1.595522] systemd[1]: Starting Load Kernel Module configfs...490server # [ 1.603961] systemd[1]: Starting Journal Service...491server # [ 1.612885] systemd[1]: Starting Load Kernel Modules...492server # [ 1.615870] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os493server # [ 1.621531] systemd[1]: Starting Coldplug All udev Devices...494server # [ 1.633891] systemd[1]: Finished Create List of Static Device Nodes.495server # [ 1.638625] systemd[1]: modprobe@configfs.service: Deactivated successfully.496server # [ 1.646198] systemd[1]: Finished Load Kernel Module configfs.497server # [ 1.652237] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config498server # [ 1.657410] systemd-journald[65]: Collecting audit messages is disabled.499server # [ 1.664881] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...500server # [ 1.671677] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.501server # [ 1.681972] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev502server # [ 1.706345] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.503server # [ 1.715004] systemd[1]: Starting Create Static Device Nodes in /dev...504server # [ 1.725860] systemd[1]: Finished Load Kernel Modules.505server # [ 1.735017] systemd[1]: Starting Apply Kernel Variables...506server # [ 1.752742] systemd[1]: Finished Create Static Device Nodes in /dev.507server # [ 1.760000] systemd[1]: Reached target Preparation for Local File Systems.508server # [ 1.764882] systemd[1]: Reached target Local File Systems.509server # [ 1.771979] systemd[1]: Starting Rule-based Manager for Device Events and Files...510server # [ 1.633190] systemd-modules-load[66]: Inserted module 'dm_mod'511server # [ 1.635259] systemd-modules-load[66]: Inserted module 'virtio_balloon'512server # [ 1.637256] systemd-modules-load[66]: Inserted module 'virtio_gpu'513server # [ 1.783996] systemd[1]: Started Journal Service.514server # [ 1.645484] systemd[1]: Finished Apply Kernel Variables.515server # [ 1.657164] systemd[1]: Starting Create System Files and Directories...516server # [ 1.685443] systemd[1]: Finished Create System Files and Directories.517server # [ 1.693269] systemd-udevd[73]: Using default interface naming scheme 'v261'.518server # [ 1.715525] systemd[1]: Started Rule-based Manager for Device Events and Files.519server # [ 1.769863] systemd[1]: Finished Coldplug All udev Devices.520server # [ 1.770808] systemd[1]: Reached target System Initialization.521server # [ 1.771731] systemd[1]: Reached target Basic System.522server # [ 2.099577] virtio_blk virtio2: 1/0/0 default/read/poll queues523server # [ 2.101683] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12524server # [ 2.106456] serio: i8042 KBD port at 0x60,0x64 irq 1525server # [ 2.110283] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)526server # [ 2.115985] serio: i8042 AUX port at 0x60,0x64 irq 12527server # [ 2.139497] uhci_hcd 0000:00:1d.0: UHCI Host Controller528server # [ 2.146409] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 1529server # [ 2.153612] uhci_hcd 0000:00:1d.0: detected 2 ports530server # [ 2.154664] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180531server # [ 2.158382] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18532server # [ 2.159502] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1533server # [ 2.163537] SCSI subsystem initialized534server # [ 2.168866] usb usb1: Product: UHCI Host Controller535server # [ 2.169518] usb usb1: Manufacturer: Linux 6.18.55 uhci_hcd536server # [ 2.176035] usb usb1: SerialNumber: 0000:00:1d.0537server # [ 2.179275] hub 1-0:1.0: USB hub found538server # [ 2.181808] hub 1-0:1.0: 2 ports detected539server # [ 2.188134] ehci-pci 0000:00:1d.7: EHCI Host Controller540server # [ 2.188963] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 2541server # [ 2.190618] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000542server # [ 2.050664] systemd[1]: Starting Virtual Console Setup...543server # [ 2.198831] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00544server # [ 2.199649] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18545server # [ 2.200722] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1546server # [ 2.205999] usb usb2: Product: EHCI Host Controller547server # [ 2.206696] usb usb2: Manufacturer: Linux 6.18.55 ehci_hcd548server # [ 2.210316] usb usb2: SerialNumber: 0000:00:1d.7549server # [ 2.212506] hub 2-0:1.0: USB hub found550server # [ 2.214857] hub 2-0:1.0: 6 ports detected551server # [ 2.088085] systemd[1]: Found device /dev/disk/by-label/nixos.552server # [ 2.089858] systemd[1]: Reached target Initrd Root Device.553server # [ 2.094070] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...554server # [ 2.240888] hub 1-0:1.0: USB hub found555server # [ 2.242810] hub 1-0:1.0: 2 ports detected556server # [ 2.100719] systemd-vconsole-setup[96]: Configuration of first virtual console was skipped, ignoring remaining ones.557server # [ 2.105637] systemd[1]: Finished Virtual Console Setup.558server # [ 2.250337] uhci_hcd 0000:00:1d.1: UHCI Host Controller559server # [ 2.251109] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3560server # [ 2.263707] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0561server # [ 2.130196] (udev-worker)[87]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.562server # [ 2.134937] systemd-fsck[102]: nixos: clean, 12/65536 files, 13019/262144 blocks563server # [ 2.279951] uhci_hcd 0000:00:1d.1: detected 2 ports564server # [ 2.280745] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0565server # [ 2.140616] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.566server # [ 2.143868] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.567server # [ 2.145554] (udev-worker)[87]: Network interface NamePolicy= disabled on kernel command line.568server # [ 2.291926] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18569server # [ 2.300935] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1570server # [ 2.301988] usb usb3: Product: UHCI Host Controller571server # [ 2.302650] usb usb3: Manufacturer: Linux 6.18.55 uhci_hcd572server # [ 2.315344] usb usb3: SerialNumber: 0000:00:1d.1573server # [ 2.320980] hub 3-0:1.0: USB hub found574server # [ 2.323800] hub 3-0:1.0: 2 ports detected575server # [ 2.328879] uhci_hcd 0000:00:1d.2: UHCI Host Controller576server # [ 2.329574] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4577server # [ 2.332897] uhci_hcd 0000:00:1d.2: detected 2 ports578server # [ 2.333657] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0579server # [ 2.339738] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18580server # [ 2.343621] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1581server # [ 2.346792] usb usb4: Product: UHCI Host Controller582server # [ 2.347505] usb usb4: Manufacturer: Linux 6.18.55 uhci_hcd583server # [ 2.348383] usb usb4: SerialNumber: 0000:00:1d.2584server # [ 2.351489] hub 4-0:1.0: USB hub found585server # [ 2.352630] hub 4-0:1.0: 2 ports detected586server # [ 2.383190] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode587server # [ 2.384405] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)588server # [ 2.385763] ahci 0000:00:1f.2: flags: 64bit ncq only589server # [ 2.388973] scsi host0: ahci590server # [ 2.390870] scsi host1: ahci591server # [ 2.391450] scsi host2: ahci592server # [ 2.394220] scsi host3: ahci593server # [ 2.395746] scsi host4: ahci594server # [ 2.397281] scsi host5: ahci595server # [ 2.398542] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1596server # [ 2.399870] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1597server # [ 2.401080] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1598server # [ 2.402219] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1599server # [ 2.403368] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1600server # [ 2.404656] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1601server # [ 2.454904] usb 2-1: new high-speed USB device number 2 using ehci-pci602server # [ 2.584734] usb 2-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00603server # [ 2.585893] usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10604server # [ 2.587165] usb 2-1: Product: QEMU USB Tablet605server # [ 2.587866] usb 2-1: Manufacturer: QEMU606server # [ 2.588411] usb 2-1: SerialNumber: 28754-0000:00:1d.7-1607server # [ 2.602247] hid: raw HID events driver (C) Jiri Kosina608server # [ 2.487778] systemd[1]: Mounting /sysroot...609server # [ 2.718480] ata5: SATA link down (SStatus 0 SControl 300)610server # [ 2.720688] ata4: SATA link down (SStatus 0 SControl 300)611server # [ 2.723230] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)612server # [ 2.725620] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100613server # [ 2.727687] ata3.00: applying bridge limits614server # [ 2.729641] ata1: SATA link down (SStatus 0 SControl 300)615server # [ 2.731999] ata6: SATA link down (SStatus 0 SControl 300)616server # [ 2.734120] ata2: SATA link down (SStatus 0 SControl 300)617server # [ 2.736474] ata3.00: configured for UDMA/100618server # [ 2.738860] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5619server # [ 2.804666] usbcore: registered new interface driver usbhid620server # [ 2.808793] usbhid: USB HID core driver621server # [ 2.842023] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0/0003:0627:0001.0001/input/input2622server # [ 2.844075] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0623server # [ 2.850370] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray624server # [ 2.860932] cdrom: Uniform CD-ROM driver Revision: 3.20625server # [ 2.864526] EXT4-fs (vda): mounted filesystem 824a7c76-a7ce-45c6-b8d5-f74c242c739e r/w with ordered data mode. Quota mode: none.626server # [ 2.725190] systemd[1]: Mounted /sysroot.627server # [ 2.726876] systemd[1]: Reached target Initrd Root File System.628server # [ 2.732171] systemd[1]: Mounting /sysroot/nix/.ro-store...629server # [ 2.737129] systemd[1]: Mounting /sysroot/nix/.rw-store...630server # [ 2.741264] systemd[1]: Mounting /sysroot/run...631server # [ 2.749278] systemd[1]: Mounting /sysroot/tmp/shared...632server # [ 2.754282] systemd[1]: Mounting /sysroot/tmp/xchg...633server # [ 2.762739] systemd[1]: Starting Mountpoints Configured in the Real Root...634server # [ 2.795970] systemd-sysroot-fstab-check[142]: /sysroot should be mounted in the initrd, will request daemon-reload.635server # [ 2.807760] systemd[1]: Mounted /sysroot/nix/.ro-store.636server # [ 2.811054] systemd[1]: Mounted /sysroot/nix/.rw-store.637server # [ 2.812042] systemd[1]: Mounted /sysroot/run.638server # [ 2.812820] systemd[1]: Mounted /sysroot/tmp/shared.639server # [ 2.815225] systemd[1]: Mounted /sysroot/tmp/xchg.640server # [ 2.824093] systemd[1]: Reload requested from client PID 142 ('systemd-sysroot') (unit initrd-parse-etc.service)...641server # [ 2.825522] systemd[1]: Reloading...642server # [ 2.887557] systemd[1]: Reloading finished in 63 ms.643server # [ 2.896647] systemd-sysroot-fstab-check[142]: Requesting initrd-fs.target/start/replace...644server # [ 2.899113] systemd-sysroot-fstab-check[142]: Requesting swap.target/start/replace...645server # [ 2.901061] systemd[1]: Starting rw-sysroot-nix-store.service...646server # [ 2.905712] systemd[1]: initrd-parse-etc.service: Deactivated successfully.647server # [ 2.908115] systemd[1]: Finished Mountpoints Configured in the Real Root.648server # [ 2.909175] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.649server # [ 2.916499] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.650server # [ 2.917992] systemd[1]: Finished rw-sysroot-nix-store.service.651server # [ 3.488750] systemd[1]: Mounting /sysroot/nix/store...652server # [ 3.535722] systemd[1]: Mounted /sysroot/nix/store.653server # [ 3.539129] systemd[1]: Reached target Initrd File Systems.654server # [ 3.541873] systemd[1]: Starting Find NixOS closure...655server # [ 3.545973] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...656server # [ 3.574102] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.657server # [ 3.575939] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.658server # [ 3.583584] systemd[1]: Finished Find NixOS closure.659server # [ 3.584673] systemd[1]: Reached target Initrd Default Target.660server # [ 3.586462] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...661server # [ 3.599165] systemd[1]: Stopped target Initrd Default Target.662server # [ 3.600144] systemd[1]: Stopped target Basic System.663server # [ 3.602203] systemd[1]: Stopped target Initrd Root Device.664server # [ 3.602934] systemd[1]: Stopped target Path Units.665server # [ 3.603647] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.666server # [ 3.604649] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.667server # [ 3.605654] systemd[1]: Stopped target Slice Units.668server # [ 3.606360] systemd[1]: Stopped target Socket Units.669server # [ 3.607062] systemd[1]: Stopped target System Initialization.670server # [ 3.607815] systemd[1]: Stopped target Swaps.671server # [ 3.608622] systemd[1]: Stopped target Timer Units.672server # [ 3.609563] systemd[1]: dbus.socket: Deactivated successfully.673server # [ 3.612219] systemd[1]: Closed D-Bus System Message Bus Socket.674server # [ 3.613178] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.675server # [ 3.614323] systemd[1]: Stopped Find NixOS closure.676server # [ 3.616080] systemd[1]: Starting rw-sysroot-nix-store.service...677server # [ 3.617115] systemd[1]: systemd-sysctl.service: Deactivated successfully.678server # [ 3.618199] systemd[1]: Stopped Apply Kernel Variables.679server # [ 3.620227] systemd[1]: systemd-modules-load.service: Deactivated successfully.680server # [ 3.622132] systemd[1]: Stopped Load Kernel Modules.681server # [ 3.622848] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.682server # [ 3.623978] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.683server # [ 3.625176] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.684server # [ 3.626213] systemd[1]: Stopped Create System Files and Directories.685server # [ 3.628108] systemd[1]: Stopped target Local File Systems.686server # [ 3.628899] systemd[1]: Stopped target Preparation for Local File Systems.687server # [ 3.629885] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.688server # [ 3.631069] systemd[1]: Stopped Coldplug All udev Devices.689server # [ 3.633067] systemd[1]: Stopping Rule-based Manager for Device Events and Files...690server # [ 3.634151] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.691server # [ 3.635396] systemd[1]: Stopped Virtual Console Setup.692server # [ 3.646121] systemd[1]: initrd-cleanup.service: Deactivated successfully.693server # [ 3.647204] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.694server # [ 3.651789] systemd[1]: systemd-udevd.service: Deactivated successfully.695server # [ 3.655123] systemd[1]: Stopped Rule-based Manager for Device Events and Files.696server # [ 3.656217] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.697server # [ 3.658929] systemd[1]: Closed udev Control Socket.698server # [ 3.661157] systemd[1]: Starting Cleanup udev Database...699server # [ 3.661962] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.700server # [ 3.664049] systemd[1]: Stopped Create Static Device Nodes in /dev.701server # [ 3.664960] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.702server # [ 3.666169] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.703server # [ 3.667200] systemd[1]: kmod-static-nodes.service: Deactivated successfully.704server # [ 3.668249] systemd[1]: Stopped Create List of Static Device Nodes.705server # [ 3.669141] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.706server # [ 3.671180] systemd[1]: Finished rw-sysroot-nix-store.service.707server # [ 3.686263] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.708server # [ 3.688200] systemd[1]: Finished Cleanup udev Database.709server # [ 3.688924] systemd[1]: Reached target Switch Root.710server # [ 3.690236] systemd[1]: Starting NixOS Activation...711server # [ 3.748740] initrd-nixos-activation-start[188]: booting system configuration /nix/store/ibyjdi2hzpiba80x2lgfp4d9cvdgap1w-nixos-system-server-test712server # [ 3.775661] initrd-nixos-activation-start[188]: running activation script...713server # [ 4.023277] initrd-nixos-activation-start[211]: setting up /etc...714server # [ 4.121313] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.715server # [ 4.123077] systemd[1]: Finished NixOS Activation.716server # [ 4.124703] systemd[1]: Starting Switch Root...717server # [ 4.136653] systemd[1]: Switching root.718server # [ 4.405875] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).719server # [ 4.513602] NET: Registered PF_VSOCK protocol family720server # [ 4.870926] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)721server # [ 4.874922] systemd[1]: Detected virtualization kvm.722server # [ 4.875600] systemd[1]: Detected architecture x86-64.723server # [ 4.876376] systemd[1]: Detected first boot.724server # [ 4.878578] systemd[1]: Initializing machine ID from random generator.725server # [ 4.966346] systemd[1]: bpf-restrict-fs: LSM BPF program attached726server # [ 5.114335] systemd[1]: Applying preset policy.727server # [ 5.399613] systemd[1]: Populated /etc with preset unit settings.728server # [ 5.580024] systemd[1]: initrd-switch-root.service: Deactivated successfully.729server # [ 5.581332] systemd[1]: Stopped initrd-switch-root.service.730server # [ 5.583275] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.731server # [ 5.585281] systemd[1]: Created slice Slice /system/getty.732server # [ 5.586607] systemd[1]: Created slice User and Session Slice.733server # [ 5.587504] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.734server # [ 5.588678] systemd[1]: Started Forward Password Requests to Wall Directory Watch.735server # [ 5.589720] systemd[1]: Expecting device /dev/hvc0...736server # [ 5.590448] systemd[1]: Expecting device /dev/ttyS0...737server # [ 5.591232] systemd[1]: Reached target Local Encrypted Volumes.738server # [ 5.592078] systemd[1]: Stopped target initrd-fs.target.739server # [ 5.592834] systemd[1]: Stopped target initrd-root-fs.target.740server # [ 5.593585] systemd[1]: Stopped target initrd-switch-root.target.741server # [ 5.594434] systemd[1]: Reached target Virtual Machines and Containers.742server # [ 5.606600] systemd[1]: Reached target Path Units.743server # [ 5.607287] systemd[1]: Reached target Remote File Systems.744server # [ 5.608070] systemd[1]: Reached target Slice Units.745server # [ 5.608746] systemd[1]: Reached target Swaps.746server # [ 5.610603] systemd[1]: Listening on Query the User Interactively for a Password.747server # [ 5.613011] systemd[1]: Listening on Process Core Dump Socket.748server # [ 5.614840] systemd[1]: Listening on Credential Encryption/Decryption.749server # [ 5.616738] systemd[1]: Listening on Factory Reset Management.750server # [ 5.617634] systemd[1]: Listening on Hostname Service Socket.751server # [ 5.620298] systemd[1]: Starting Journal Log Access Socket...752server # [ 5.621671] systemd[1]: Listening on Journal Audit Socket.753server # [ 5.624340] systemd[1]: Listening on Console Output Muting Service Socket.754server # [ 5.625427] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.755server # [ 5.626526] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os756server # [ 5.627803] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki757server # [ 5.631894] systemd[1]: Listening on Disk Repartitioning Service Socket.758server # [ 5.632903] systemd[1]: Listening on udev Control Socket.759server # [ 5.633726] systemd[1]: Listening on udev Varlink Socket.760server # [ 5.635825] systemd[1]: Mounting Huge Pages File System...761server # [ 5.638146] systemd[1]: Mounting POSIX Message Queue File System...762server # [ 5.644935] systemd[1]: Mounting Kernel Debug File System...763server # [ 5.651374] systemd[1]: Mounting Kernel Trace File System...764server # [ 5.660386] systemd[1]: Starting Create List of Static Device Nodes...765server # [ 5.669939] systemd[1]: Starting Load Kernel Module configfs...766server # [ 5.676262] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm767server # [ 5.684683] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore768server # [ 5.691064] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse769server # [ 5.707114] systemd[1]: Mounting FUSE Control File System...770server # [ 5.711371] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67771server # [ 5.725700] systemd[1]: Starting Journal Service...772server # [ 5.730832] systemd[1]: Starting Load Kernel Modules...773server # [ 5.740750] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...774server # [ 5.749556] systemd[1]: Starting Remount Root and Kernel File Systems...775server # [ 5.753666] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os776server # [ 5.763091] systemd[1]: Starting Coldplug All udev Devices...777server # [ 5.770160] systemd[1]: Listening on Journal Log Access Socket.778server # [ 5.774209] systemd[1]: Mounted Huge Pages File System.779server # [ 5.775119] systemd[1]: Mounted POSIX Message Queue File System.780server # [ 5.778460] systemd[1]: Mounted Kernel Debug File System.781server # [ 5.781044] systemd[1]: Mounted Kernel Trace File System.782server # [ 5.789732] systemd-journald[281]: Collecting audit messages is enabled.783server # [ 5.792404] systemd[1]: Finished Create List of Static Device Nodes.784server # [ 5.798130] systemd[1]: modprobe@configfs.service: Deactivated successfully.785server # [ 5.806701] systemd[1]: Finished Load Kernel Module configfs.786server # [ 5.670218] systemd[1]: Queued start job for default target Multi-User System.787server # [ 5.673715] systemd[1]: systemd-journald.service: Deactivated successfully.788server # [ 5.822862] EXT4-fs (vda): re-mounted 824a7c76-a7ce-45c6-b8d5-f74c242c739e.789server # [ 5.824091] systemd[1]: Mounting Kernel Configuration File System...790server # [ 5.825145] loop: module loaded791server # [ 5.682570] systemd-modules-load[282]: Inserted module 'loop'792server # [ 5.833445] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...793server # [ 5.838342] systemd[1]: Started Journal Service.794server # [ 5.696973] systemd[1]: Mounted FUSE Control File System.795server # [ 5.701653] systemd[1]: Finished Load Kernel Modules.796server # [ 5.705131] systemd[1]: Finished Remount Root and Kernel File Systems.797server # [ 5.730578] systemd[1]: Listening on Disk Image Download Service Socket.798server # [ 5.734955] systemd[1]: Starting Firewall...799server # [ 5.739952] systemd[1]: Starting Flush Journal to Persistent Storage...800server # [ 5.740868] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore801server # [ 5.748343] systemd-oomd[283]: No swap; memory pressure usage will be degraded802server # [ 5.749685] systemd[1]: Starting Load/Save OS Random Seed...803server # [ 5.760843] systemd[1]: Starting Apply Kernel Variables...804server # [ 5.761720] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os805server # [ 5.762927] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.806server # [ 5.967299] systemd-journald[281]: Received client request to flush runtime journal.807server # [ 5.933536] systemd[1]: Mounted Kernel Configuration File System.808server # [ 5.936346] systemd[1]: Finished Load/Save OS Random Seed.809server # [ 5.937700] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.810server # [ 5.939767] systemd[1]: Reached target First Boot Complete.811server # [ 5.940843] systemd[1]: Starting Create Static Device Nodes in /dev...812server # [ 5.942391] systemd[1]: Finished Apply Kernel Variables.813server # [ 5.944202] systemd[1]: Finished Flush Journal to Persistent Storage.814server # [ 6.005533] systemd[1]: Finished Create Static Device Nodes in /dev.815server # [ 6.006807] systemd[1]: Reached target Preparation for Local File Systems.816server # [ 6.011299] systemd[1]: Starting Rule-based Manager for Device Events and Files...817server # [ 6.065562] systemd[1]: Finished Coldplug All udev Devices.818server # [ 6.083286] systemd-udevd[317]: Using default interface naming scheme 'v261'.819server # [ 6.134082] systemd[1]: Started Rule-based Manager for Device Events and Files.820server # [ 6.252317] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse821server # [ 6.327857] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.822server # [ 6.341028] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.823server # [ 6.384133] (udev-worker)[344]: Network interface NamePolicy= disabled on kernel command line.824server # [ 6.392095] (udev-worker)[342]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.825server # [ 6.394192] (udev-worker)[342]: Network interface NamePolicy= disabled on kernel command line.826server # [ 6.441698] systemd[1]: Mounting /run/wrappers...827server # [ 6.477552] systemd[1]: Mounted /run/wrappers.828server # [ 6.478336] systemd[1]: Reached target Local File Systems.829server # [ 6.482686] systemd[1]: Listening on Boot Loader Control Service Socket.830server # [ 6.486073] systemd[1]: Starting register-nix-paths.service...831server # [ 6.491863] systemd[1]: Starting Create SUID/SGID Wrappers...832server # [ 6.492761] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.833server # [ 6.500486] systemd[1]: Starting Save Transient machine-id to Disk...834server # [ 6.512815] systemd[1]: Starting Create System Files and Directories...835server # [ 6.580365] systemd[1]: Condition check resulted in Virtio network device being skipped.836server # [ 6.582338] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore837server # [ 6.586195] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.838server # [ 6.589571] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67839server # [ 6.591729] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore840server # [ 6.593322] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os841server # [ 6.594495] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os842server # [ 6.609779] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.843server # [ 6.619740] systemd[1]: Finished Save Transient machine-id to Disk.844server # [ 6.671240] systemd[1]: Finished Create System Files and Directories.845server # [ 6.681360] systemd[1]: Starting Rebuild Journal Catalog...846server # [ 6.687958] systemd[1]: Starting Record System Boot/Shutdown in UTMP...847server # [ 6.774452] systemd[1]: Finished Record System Boot/Shutdown in UTMP.848server # [ 6.809799] systemd[1]: Finished Rebuild Journal Catalog.849server # [ 6.964112] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console850server # [ 6.838405] systemd[1]: Starting Update is Completed...851server # [ 7.043088] Console: switching to colour dummy device 80x25852server # [ 6.942597] systemd[1]: Finished Update is Completed.853server # [ 7.088596] rtc_cmos PNP0B00:00: RTC can wake from S4854server # [ 7.097616] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3855server # [ 7.110501] mousedev: PS/2 mouse device common for all mice856server # [ 7.112399] rtc_cmos PNP0B00:00: registered as rtc0857server # [ 7.120728] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4858server # [ 7.126789] [drm] Found bochs VGA, ID 0xb0c5.859server # [ 7.127204] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.860server # [ 7.132670] rtc_cmos PNP0B00:00: setting system clock to 2026-10-25T13:22:41 UTC (1792934561)861server # [ 7.134095] systemd-journald[281]: Time jumped backwards, rotating.862server # [ 7.141370] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic863server # [ 7.146858] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs864server # [ 7.157002] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0865server # [ 7.162495] ACPI: button: Power Button [PWRF]866server # [ 7.167233] parport_pc 00:02: reported by Plug and Play ACPI867server # [ 7.167898] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]868server # [ 7.251603] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized869server # [ 7.290682] Console: switching to colour frame buffer device 160x50870server # [ 7.313444] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device871server # [ 7.174361] systemd[1]: Finished Firewall.872server # [ 7.396093] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt873server # [ 7.397135] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD874server # [ 7.262231] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.875server # [ 7.265094] systemd[1]: Finished Create SUID/SGID Wrappers.876server # [ 7.275473] systemd[1]: Finished register-nix-paths.service.877server # [ 7.277185] systemd[1]: Reached target System Initialization.878server # [ 7.279935] systemd[1]: Started Discard unused filesystem blocks once a week.879server # [ 7.281065] systemd[1]: Started niks3 garbage collection timer.880server # [ 7.281864] systemd[1]: Started Daily Cleanup of Temporary Directories.881server # [ 7.282809] systemd[1]: Reached target Timer Units.882server # [ 7.283954] systemd[1]: Listening on D-Bus System Message Bus Socket.883server # [ 7.288704] systemd[1]: Listening on niks3 server socket.884server # [ 7.289598] systemd[1]: Listening on Nix Daemon Socket.885server # [ 7.290408] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.886server # [ 7.291577] systemd[1]: Reached target Socket Units.887server # [ 7.292685] systemd[1]: Reached target Basic System.888server # [ 7.295427] systemd[1]: Started backdoor.service.889server # [ 7.299530] systemd[1]: Starting Import lastlog data into lastlog2 database...890server # [ 7.454200] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6891server # [ 7.455398] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5892server # [ 7.315260] systemd[1]: Starting Name Service Cache Daemon (nsncd)...893server # [ 7.320089] systemd[1]: Started openbao-dev.service.894server # [ 7.323141] systemd[1]: Starting openbao-setup.service...895server # [ 7.343065] systemd[1]: Starting Post-Boot Actions...896server # [ 7.360832] systemd[1]: Started Reset console on configuration changes.897server # [ 7.377976] systemd[1]: Starting resolvconf update...898server # connecting to host...899server # [ 7.431328] nsncd[508]: Oct 25 13:22:41.940 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"900server # [ 7.440759] systemd[1]: Started Name Service Cache Daemon (nsncd).901server: Guest shell says: b'Spawning backdoor root shell...\n'902server: connected to guest root shell903server # [ 7.457043] systemd[1]: Reached target Host and Network Name Lookups.904server: (connecting took 8.19 seconds)905server # [ 7.457852] systemd[1]: Reached target User and Group Name Lookups.906server: (finished: waiting for the VM to finish booting, in 8.96 seconds)907server # [ 7.474677] systemd[1]: Starting D-Bus System Message Bus...908server # [ 7.488168] systemd[1]: Starting User Login Management...909server # [ 7.492128] systemd[1]: Finished Post-Boot Actions.910server # [ 7.532751] systemd[1]: Finished Import lastlog data into lastlog2 database.911server # [ 7.575289] systemd[1]: Starting Virtual Console Setup...912server # [ 7.646691] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.913server # [ 7.651544] systemd[1]: Stopped Virtual Console Setup.914server # [ 7.669228] systemd[1]: Starting Virtual Console Setup...915server # [ 7.715756] dbus-broker-launch[538]: Looking up NSS user entry for 'systemd-timesync'...916server # [ 7.737384] dbus-broker-launch[538]: NSS returned no entry for 'systemd-timesync'917server # [ 7.738461] dbus-broker-launch[538]: Invalid user-name in /nix/store/kk6m0qyx5cvj5ycrc084yzxzmq6c7043-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"918server # [ 7.772487] systemd-logind[542]: New seat seat0.919server # [ 7.776654] systemd[1]: Stopped target Host and Network Name Lookups.920server # [ 7.779797] systemd[1]: Stopping Host and Network Name Lookups...921server # [ 7.782347] systemd[1]: Stopped target User and Group Name Lookups.922server # [ 7.783469] systemd[1]: Stopping User and Group Name Lookups...923server # [ 7.784562] systemd-logind[542]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)924server # [ 7.789066] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...925server # [ 7.789926] systemd[1]: Started User Login Management.926server # [ 7.791945] systemd[1]: nscd.service: Deactivated successfully.927server # [ 7.793749] systemd[1]: Stopped Name Service Cache Daemon (nsncd).928server # [ 7.797699] systemd-logind[542]: Watching system buttons on /dev/input/event3 (Power Button)929server # [ 7.807477] systemd[1]: Started D-Bus System Message Bus.930server # [ 7.820182] systemd[1]: Starting linger-users.service...931server # [ 7.826584] systemd[1]: Starting Name Service Cache Daemon (nsncd)...932server # [ 7.858236] dbus-broker-launch[538]: Ready933server # [ 7.922979] systemd[1]: linger-users.service: Deactivated successfully.934server # [ 7.927036] systemd[1]: Finished linger-users.service.935server # [ 7.934371] systemd[1]: Started Name Service Cache Daemon (nsncd).936server # [ 7.938902] nsncd[610]: Oct 25 13:22:42.447 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"937server # [ 7.940680] systemd[1]: Reached target Host and Network Name Lookups.938server # [ 7.941525] systemd[1]: Reached target User and Group Name Lookups.939server # [ 7.944150] systemd-logind[542]: Watching system buttons on /dev/input/event2 (QEMU Virtio Keyboard)940server # [ 8.094470] ppdev: user-space parallel port driver941server # [ 7.980299] systemd[1]: Finished resolvconf update.942server # [ 7.980987] systemd[1]: Reached target Preparation for Network.943server # [ 7.990467] systemd[1]: Starting DHCP Client...944server # [ 7.999073] systemd[1]: Starting Address configuration of eth1...945server # [ 8.005458] systemd[1]: Starting Extra networking commands....946server # [ 8.044448] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.947server # [ 8.190560] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)948server # [ 8.052413] systemd[1]: Stopped Virtual Console Setup.949server # [ 8.078151] systemd[1]: Starting Virtual Console Setup...950server # [ 8.265467] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)951server # [ 8.246759] network-addresses-eth1-start[639]: adding address 192.168.1.1/24... done952server # [ 8.296151] network-addresses-eth1-start[639]: adding address 2001:db8:1::1/64... done953server # [ 8.372122] systemd[1]: Finished Address configuration of eth1.954server # [ 8.457811] dhcpcd[653]: dhcpcd-10.3.2 starting955server # [ 8.481373] dhcpcd[705]: dev: loaded udev956server # [ 8.628082] kvm_amd: TSC scaling supported957server # [ 8.628483] kvm_amd: Nested Virtualization enabled958server # [ 8.632816] kvm_amd: Nested Paging enabled959server # [ 8.633244] kvm_amd: LBR virtualization supported960server # [ 8.633670] kvm_amd: Virtual VMLOAD VMSAVE supported961server # [ 8.636984] kvm_amd: Virtual GIF supported962server # [ 8.637379] kvm_amd: Virtual NMI enabled963server # [ 8.504754] systemd[1]: Finished Extra networking commands..964server # [ 8.508365] systemd[1]: Reached target Network.965server # [ 8.515073] systemd[1]: Starting PostgreSQL Server...966server # [ 8.520464] systemd[1]: Started RustFS S3-compatible object storage.967server # [ 8.675240] 8021q: 802.1Q VLAN Support v1.8968server # [ 8.675652] 8021q: adding VLAN 0 to HW filter on device eth1969server # [ 8.538257] bao[510]: 2026-10-25T13:22:43.046Z [INFO] proxy environment: http_proxy="" https_proxy="" no_proxy=""970server # [ 8.544659] systemd[1]: Starting Setup RustFS bucket...971server # [ 8.551961] bao[510]: 2026-10-25T13:22:43.062Z [WARN] no `api_addr` value specified in config or in BAO_API_ADDR; falling back to detection if possible, but this value should be manually set972server # [ 8.560135] bao[510]: 2026-10-25T13:22:43.071Z [INFO] core: Initializing version history cache for core973server # [ 8.563556] bao[510]: ==> OpenBao server configuration:974server # [ 8.564335] bao[510]: Api Address: http://127.0.0.1:8200975server # [ 8.565243] bao[510]: Cgo: enabled976server # [ 8.565903] bao[510]: Cluster Address: https://127.0.0.1:8201977server # [ 8.566777] bao[510]: Environment Variables: HOME, INVOCATION_ID, IO_PRESSURE_WATCH, IO_PRESSURE_WRITE, JOURNAL_STREAM, LANG, LOCALE_ARCHIVE, LOGNAME, MEMORY_PRESSURE_WATCH, MEMORY_PRESSURE_WRITE, PATH, RUNTIME_DIRECTORY, STATE_DIRECTORY, SYSTEMD_EXEC_PID, TZDIR, USER978server # [ 8.570791] bao[510]: Go Version: go1.27.2979server # [ 8.572597] bao[510]: Listener 1: tcp (addr: "127.0.0.1:8200", cluster address: "127.0.0.1:8201", max_request_duration: "1m30s", max_request_json_memory: "34078720", max_request_json_strings: "1000", max_request_size: "33554432", tls: "disabled")980server # [ 8.576550] bao[510]: Log Level:981server # [ 8.577231] bao[510]: Recovery Mode: false982server # [ 8.577858] bao[510]: Storage: inmem983server # [ 8.578552] bao[510]: Version: OpenBao v2.7.1, committed 1970-01-01T00:00:00Z984server # [ 8.579657] bao[510]: Version Sha: refs/tags/v2.7.1985server # [ 8.580490] bao[510]: 2026-10-25T13:22:43.081Z [INFO] core: security barrier not initialized986server # [ 8.581553] bao[510]: 2026-10-25T13:22:43.083Z [INFO] core: security barrier initialized: shares=1 threshold=1987server # [ 8.586203] systemd[1]: Starting Permit User Sessions...988server # [ 8.739270] EDAC MC: Ver: 3.0.0989server # [ 8.599251] bao[510]: 2026-10-25T13:22:43.109Z [INFO] core: post-unseal setup starting990server # [ 8.645160] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.991server # [ 8.681762] bao[510]: 2026-10-25T13:22:43.191Z [INFO] core: loaded wrapping token key992server # [ 8.683889] bao[510]: 2026-10-25T13:22:43.194Z [INFO] core: successfully setup plugin catalog: plugin-directory=""993server # [ 8.691904] bao[510]: 2026-10-25T13:22:43.202Z [INFO] core: reading transactional mount table994server # [ 8.693117] bao[510]: 2026-10-25T13:22:43.202Z [INFO] core: no mounts in transactional mount table; adding default mount table995server # [ 8.700655] bao[510]: 2026-10-25T13:22:43.211Z [INFO] core: successfully mounted: type=cubbyhole version="v2.7.1+builtin.bao" path=cubbyhole/ namespace="ID: root. Path: "996server # [ 8.705365] systemd[1]: Finished Permit User Sessions.997server # [ 8.706635] bao[510]: 2026-10-25T13:22:43.215Z [INFO] core: successfully mounted: type=system version="v2.7.1+builtin.bao" path=sys/ namespace="ID: root. Path: "998server # [ 8.718067] bao[510]: 2026-10-25T13:22:43.226Z [INFO] core: successfully mounted: type=identity version="v2.7.1+builtin.bao" path=identity/ namespace="ID: root. Path: "999server # [ 8.721042] systemd[1]: Started Getty on tty1.1000server # [ 8.729352] systemd[1]: Reached target Login Prompts.1001server # [ 8.730067] bao[510]: 2026-10-25T13:22:43.239Z [INFO] core: reading transactional auth mount table1002server # [ 8.731151] bao[510]: 2026-10-25T13:22:43.239Z [INFO] core: no auth mounts in transactional auth mount table; adding default auth mount table1003server # [ 8.744286] bao[510]: 2026-10-25T13:22:43.252Z [INFO] core: successfully mounted: type=token version="v2.7.1+builtin.bao" path=token/ namespace="ID: root. Path: "1004server # [ 8.746731] bao[510]: 2026-10-25T13:22:43.257Z [INFO] rollback: Starting the rollback manager with 256 workers1005server # [ 8.760810] bao[510]: 2026-10-25T13:22:43.271Z [INFO] core: restoring leases1006server # [ 8.775790] bao[510]: 2026-10-25T13:22:43.286Z [INFO] core: entities restored1007server # [ 8.778948] bao[510]: 2026-10-25T13:22:43.288Z [INFO] core: groups restored1008server # [ 8.779832] bao[510]: 2026-10-25T13:22:43.288Z [INFO] core: Recorded vault version: vault version=2.7.1 upgrade time="2026-10-25 13:22:43.288612518 +0000 UTC" commit date=1970-01-01T00:00:00Z1009server # [ 8.794347] bao[510]: 2026-10-25T13:22:43.304Z [INFO] expiration: lease restore complete1010server # [ 8.795412] bao[510]: 2026-10-25T13:22:43.305Z [INFO] core: post-unseal setup complete1011server # [ 8.811967] bao[510]: 2026-10-25T13:22:43.320Z [INFO] core: root token generated1012server # [ 8.812968] bao[510]: 2026-10-25T13:22:43.320Z [INFO] core: pre-seal teardown starting1013server # [ 8.821222] bao[510]: 2026-10-25T13:22:43.330Z [INFO] rollback: starting rollback manager1014server # [ 8.822273] bao[510]: 2026-10-25T13:22:43.331Z [INFO] rollback: stopping rollback manager1015server # [ 8.825572] bao[510]: 2026-10-25T13:22:43.335Z [INFO] core: pre-seal teardown complete1016server # [ 8.832966] bao[510]: 2026-10-25T13:22:43.343Z [INFO] core.cluster-listener.tcp: starting listener: listener_address=127.0.0.1:82011017server # [ 8.834540] bao[510]: 2026-10-25T13:22:43.343Z [INFO] core.cluster-listener: serving cluster requests: cluster_listen_address=127.0.0.1:82011018server # [ 8.839062] bao[510]: 2026-10-25T13:22:43.350Z [INFO] core: post-unseal setup starting1019server # [ 8.841930] bao[510]: 2026-10-25T13:22:43.352Z [INFO] core: loaded wrapping token key1020server # [ 8.842982] bao[510]: 2026-10-25T13:22:43.352Z [INFO] core: successfully setup plugin catalog: plugin-directory=""1021server # [ 8.858108] bao[510]: 2026-10-25T13:22:43.367Z [INFO] core: reading transactional mount table1022server # [ 8.864980] bao[510]: 2026-10-25T13:22:43.376Z [INFO] core: successfully mounted: type=system version="v2.7.1+builtin.bao" path=sys/ namespace="ID: root. Path: "1023server # [ 8.882385] bao[510]: 2026-10-25T13:22:43.392Z [INFO] core: successfully mounted: type=identity version="v2.7.1+builtin.bao" path=identity/ namespace="ID: root. Path: "1024server # [ 8.889465] bao[510]: 2026-10-25T13:22:43.399Z [INFO] core: successfully mounted: type=cubbyhole version="v2.7.1+builtin.bao" path=cubbyhole/ namespace="ID: root. Path: "1025server # [ 8.892558] bao[510]: 2026-10-25T13:22:43.402Z [INFO] core: reading transactional auth mount table1026server # [ 8.894939] bao[510]: 2026-10-25T13:22:43.405Z [INFO] core: successfully mounted: type=token version="v2.7.1+builtin.bao" path=token/ namespace="ID: root. Path: "1027server # [ 8.903470] bao[510]: 2026-10-25T13:22:43.413Z [INFO] rollback: Starting the rollback manager with 256 workers1028server # [ 8.909469] bao[510]: 2026-10-25T13:22:43.420Z [INFO] core: restoring leases1029server # [ 8.916148] bao[510]: 2026-10-25T13:22:43.426Z [INFO] rollback: starting rollback manager1030server # [ 8.929430] bao[510]: 2026-10-25T13:22:43.439Z [INFO] expiration: lease restore complete1031server # [ 8.933980] bao[510]: 2026-10-25T13:22:43.445Z [INFO] core: entities restored1032server # [ 8.938113] bao[510]: 2026-10-25T13:22:43.448Z [INFO] core: groups restored1033server # [ 8.939092] bao[510]: 2026-10-25T13:22:43.449Z [INFO] core: post-unseal setup complete1034server # [ 8.947174] bao[510]: 2026-10-25T13:22:43.455Z [INFO] core: vault is unsealed1035server # [ 9.094271] cfg80211: Loading compiled-in X.509 certificates for regulatory database1036server # [ 8.969937] bao[510]: 2026-10-25T13:22:43.480Z [INFO] expiration: revoked lease: lease_id=auth/token/root/ha0f9bb97834be7f27feef94327c61604956c548f1a6ebc7f368febd50eee59e61037server # [ 8.995066] bao[510]: 2026-10-25T13:22:43.504Z [INFO] core: successful mount: namespace="" path=secret/ type=kv version=""1038server # [ 8.996573] bao[510]: 2026-10-25T13:22:43.504Z [INFO] beginning post-unseal configuration1039server # [ 8.999260] bao[510]: ==> OpenBao server started!1040server # [ 9.007185] bao[510]: 2026-10-25T13:22:43.518Z [INFO] secrets.kv.kv_73c1cc68: collecting keys to upgrade1041server # [ 9.011285] bao[510]: 2026-10-25T13:22:43.521Z [INFO] secrets.kv.kv_73c1cc68: done collecting keys: num_keys=11042server # [ 9.012526] bao[510]: 2026-10-25T13:22:43.521Z [INFO] secrets.kv.kv_73c1cc68: upgrading keys finished1043server # [ 9.175111] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1044server # [ 9.176255] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1045server # [ 9.180218] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21046server # [ 9.181056] cfg80211: failed to load regulatory.db1047server # [ 9.110643] postgresql-pre-start[735]: The files belonging to this database system will be owned by user "postgres".1048server # [ 9.112885] postgresql-pre-start[735]: This user must also own the server process.1049server # [ 9.114190] bao[510]: WARNING! dev mode is enabled! In this mode, OpenBao runs entirely in-memory1050server # [ 9.115898] bao[510]: and starts unsealed with a single unseal key. The root token is already1051server # [ 9.117166] bao[510]: authenticated to the CLI, so you can immediately begin using OpenBao.1052server # [ 9.118834] bao[510]: You may need to set the following environment variables:1053server # [ 9.119759] bao[510]: $ export BAO_ADDR='http://127.0.0.1:8200'1054server # [ 9.120983] bao[510]: The unseal key and root token are displayed below in case you want to1055server # [ 9.122138] bao[510]: seal/unseal the Vault or re-authenticate.1056server # [ 9.122847] bao[510]: Unseal Key: Xc//EUlfsExrEj4zS4Ikhp45WeR59xMO9tEgwf43eXc=1057server # [ 9.124193] bao[510]: Root Token: root1058server # [ 9.125234] bao[510]: Development mode should NOT be used in production installations!1059server # [ 9.130174] postgresql-pre-start[735]: The database cluster will be initialized with locale "en_US.UTF-8".1060server # [ 9.131325] postgresql-pre-start[735]: The default database encoding has accordingly been set to "UTF8".1061server # [ 9.132438] postgresql-pre-start[735]: The default text search configuration will be set to "english".1062server # [ 9.133516] postgresql-pre-start[735]: Data page checksums are enabled.1063server # [ 9.134378] postgresql-pre-start[735]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok1064server # [ 9.135488] postgresql-pre-start[735]: creating subdirectories ... ok1065server # [ 9.136275] postgresql-pre-start[735]: selecting dynamic shared memory implementation ... posix1066server # [ 9.287262] 8021q: adding VLAN 0 to HW filter on device eth01067server # [ 9.148069] dhcpcd[705]: eth0: waiting for carrier1068server # [ 9.149061] dhcpcd[705]: eth0: carrier acquired1069server # [ 9.164847] dhcpcd[705]: DUID 00:01:00:01:32:70:bf:23:52:54:00:12:34:561070server # [ 9.165867] dhcpcd[705]: eth0: IAID 00:12:34:561071server # [ 9.166701] dhcpcd[705]: eth0: adding address fe80::5054:ff:fe12:34561072server # [ 9.240765] postgresql-pre-start[735]: selecting default "max_connections" ... 1001073server # [ 9.248813] systemd-vconsole-setup[642]: Configuration of first virtual console was skipped, ignoring remaining ones.1074server # [ 9.254470] systemd[1]: Finished Virtual Console Setup.1075server # [ 9.324964] postgresql-pre-start[735]: selecting default "shared_buffers" ... 128MB1076server # [ 9.838187] bao[510]: 2026-10-25T13:22:44.348Z [INFO] core: successful mount: namespace="" path=transit/ type=transit version=""1077server # [ 9.842262] openbao-setup-start[776]: Success! Enabled the transit secrets engine at: transit/1078server # [ 9.854881] dhcpcd[705]: eth0: soliciting a DHCP lease1079server # [ 10.016797] NET: Registered PF_PACKET protocol family1080server # [ 9.882472] dhcpcd[705]: eth0: offered 10.0.2.15 from 10.0.2.21081server # [ 9.884184] dhcpcd[705]: eth0: probing address 10.0.2.15/241082server # [ 9.985488] openbao-setup-start[786]: Retrieving wrapping key.1083server # [ 11.073786] openbao-setup-start[786]: Wrapping source key with ephemeral key.1084server # [ 11.074983] openbao-setup-start[786]: Encrypting ephemeral key with wrapping key.1085server # [ 11.076868] openbao-setup-start[786]: Submitting wrapped key.1086server # [ 11.089105] openbao-setup-start[786]: Success!1087server # [ 11.100198] systemd[1]: Finished openbao-setup.service.1088server # [ 11.292625] postgresql-pre-start[735]: selecting default time zone ... UTC1089server # [ 11.295301] postgresql-pre-start[735]: creating configuration files ... ok1090server # [ 11.463454] postgresql-pre-start[735]: running bootstrap script ... ok1091server # [ 11.836511] postgresql-pre-start[735]: performing post-bootstrap initialization ... ok1092server # [ 11.846879] dhcpcd[705]: eth0: soliciting an IPv6 router1093server # [ 11.847795] dhcpcd[705]: eth0: Router Advertisement from fe80::21094server # [ 11.848580] dhcpcd[705]: eth0: adding address fec0::5054:ff:fe12:3456/641095server # [ 11.849434] dhcpcd[705]: eth0: adding route to fec0::/641096server # [ 11.851329] dhcpcd[705]: eth0: adding default route via fe80::21097server # [ 11.950437] postgresql-pre-start[735]: syncing data to disk ... ok1098server # [ 11.952216] postgresql-pre-start[735]: initdb: warning: enabling "trust" authentication for local connections1099server # [ 11.953408] postgresql-pre-start[735]: 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.1100server # [ 11.955336] postgresql-pre-start[735]: Success. You can now start the database server using:1101server # [ 11.956393] postgresql-pre-start[735]: pg_ctl -D /var/lib/postgresql/18 -l logfile start1102server # [ 12.027421] postgres[821]: [821] LOG: starting PostgreSQL 18.6 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit1103server # [ 12.029701] postgres[821]: [821] LOG: listening on IPv6 address "::1", port 54321104server # [ 12.030741] postgres[821]: [821] LOG: listening on IPv4 address "127.0.0.1", port 54321105server # [ 12.033793] postgres[821]: [821] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1106server # [ 12.044984] postgres[830]: [830] LOG: database system was shut down at 2026-10-25 13:22:46 GMT1107server # [ 12.050113] postgres[821]: [821] LOG: database system is ready to accept connections1108server # [ 12.054146] systemd[1]: Started PostgreSQL Server.1109server # [ 12.057764] systemd[1]: Starting PostgreSQL Setup Scripts...1110server # [ 12.181728] postgresql-setup-start[841]: CREATE DATABASE1111server # [ 12.210045] postgresql-setup-start[846]: CREATE ROLE1112server # [ 12.221088] postgresql-setup-start[848]: ALTER DATABASE1113server # [ 12.225843] systemd[1]: Finished PostgreSQL Setup Scripts.1114server # [ 12.227724] systemd[1]: Reached target PostgreSQL.1115server # [ 14.603859] dhcpcd[705]: eth0: leased 10.0.2.15 for 86400 seconds1116server # [ 14.605221] dhcpcd[705]: eth0: adding route to 10.0.2.0/241117server # [ 14.606328] dhcpcd[705]: eth0: adding default route via 10.0.2.21118server # [ 14.661963] systemd[1]: Started DHCP Client.1119server # [ 22.306983] rustfs-setup-start[1010]: mb s3://niks3-test1120server # [ 22.312645] systemd[1]: Finished Setup RustFS bucket.1121server # [ 22.320197] systemd[1]: Starting niks3 server...1122server # [ 22.489329] niks3-server[1019]: 2026/10/25 13:22:56 INFO Configured external signer program=/nix/store/48aprxb904y6171346rha7xknfww6rs4-niks3-openbao-signer/bin/niks3-openbao-signer1123server # [ 22.531160] niks3-server[1019]: 2026/10/25 13:22:57 INFO Read proxy enabled — serving cache objects from S31124server # [ 22.536313] niks3-server[1019]: 2026/10/25 13:22:57 INFO Using socket-activated listener address=0.0.0.0:57511125server # [ 22.537634] niks3-server[1019]: 2026/10/25 13:22:57 INFO systemd watchdog enabled interval=15s1126server # [ 22.538723] niks3-server[1019]: 2026/10/25 13:22:57 INFO Starting HTTP server address=0.0.0.0:57511127server # [ 22.539833] systemd[1]: Started niks3 server.1128server # [ 22.540535] systemd[1]: Reached target Multi-User System.1129server # [ 22.542365] systemd[1]: Startup finished in 851ms (kernel) + 3.498s (initrd) + 18.191s (userspace) = 22.541s.1130server: (finished: waiting for unit niks3.service, in 24.37 seconds)1131server: waiting for TCP port 5751 on localhost1132server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded!1133server: (finished: waiting for TCP port 5751 on localhost, in 0.04 seconds)1134server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/auth-token1135server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/auth-token, in 0.01 seconds)1136subtest: narinfos signed through OpenBao verify against the public key1137server: must succeed: nix-build -E 'derivation { name="signer-1"; system=builtins.currentSystem; builder="/bin/sh"; args=["-c" "echo signer-1 > $out"]; }' --no-out-link1138server # this derivation will be built:1139server # /nix/store/xsaww49bj3jyzrjxl784g7nq5sci6v3s-signer-1.drv1140server # building '/nix/store/xsaww49bj3jyzrjxl784g7nq5sci6v3s-signer-1.drv'...1141server: (finished: must succeed: nix-build -E 'derivation { name="signer-1"; system=builtins.currentSystem; builder="/bin/sh"; args=["-c" "echo signer-1 > $out"]; }' --no-out-link, in 0.13 seconds)1142server: must succeed: NIKS3_SERVER_URL=http://localhost:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/auth-token /nix/store/bg6jgn2yqlmlvyjgl3inckyq6nryrvfj-niks3-1.15.0/bin/niks3 push /nix/store/ccr6560gzlizgp89dw67dhvzrbdwngfs-signer-11143server # [ 23.169775] niks3-server[1019]: 2026/10/25 13:22:57 INFO Received push request method=POST path=/api/pushes1144server # time=2026-10-25T13:22:57.686Z level=INFO msg="Uploading 1 paths to localhost (0 already cached)"1145server # time=2026-10-25T13:22:57.687Z level=INFO msg="Uploading ccr6560gzlizgp89dw67dhvzrbdwngfs-signer-1 (128B)"1146server # [ 23.210718] niks3-server[1019]: 2026/10/25 13:22:57 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign1147server # [ 23.230465] niks3-server[1019]: 2026/10/25 13:22:57 INFO Registered completed upload object_key=ccr6560gzlizgp89dw67dhvzrbdwngfs.ls1148server # [ 23.255632] niks3-server[1019]: 2026/10/25 13:22:57 INFO Registered completed upload object_key=nar/1xbdd0jsa3f9d9xz2wfpcjbcrx5dpyy490mg6cnrlkqswmwsm6j0.nar.zst1149server # [ 23.260465] niks3-server[1019]: 2026/10/25 13:22:57 INFO Registered completed upload object_key=log/xsaww49bj3jyzrjxl784g7nq5sci6v3s-signer-1.drv1150server # [ 23.288418] niks3-server[1019]: 2026/10/25 13:22:57 INFO Signed narinfos id=1 count=11151server # time=2026-10-25T13:22:57.800Z level=INFO msg="Uploading 1 narinfos"1152server # [ 23.308285] niks3-server[1019]: 2026/10/25 13:22:57 INFO Received complete push request method=POST path=/api/pushes/1/complete1153server # [ 23.316267] niks3-server[1019]: 2026/10/25 13:22:57 INFO Registered completed upload object_key=ccr6560gzlizgp89dw67dhvzrbdwngfs.narinfo1154server # time=2026-10-25T13:22:57.832Z level=INFO msg="Upload complete. (198ms)"1155server # [ 23.323387] niks3-server[1019]: 2026/10/25 13:22:57 INFO Completed push id=1 objects=4 duration=10.778186ms1156server: (finished: must succeed: NIKS3_SERVER_URL=http://localhost:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/auth-token /nix/store/bg6jgn2yqlmlvyjgl3inckyq6nryrvfj-niks3-1.15.0/bin/niks3 push /nix/store/ccr6560gzlizgp89dw67dhvzrbdwngfs-signer-1, in 0.27 seconds)1157server: must succeed: nix copy --from http://localhost:5751 --to /tmp/store-1 /nix/store/ccr6560gzlizgp89dw67dhvzrbdwngfs-signer-11158server # copying 1 paths...1159server # copying path '/nix/store/ccr6560gzlizgp89dw67dhvzrbdwngfs-signer-1' from 'http://localhost:5751'...1160server: (finished: must succeed: nix copy --from http://localhost:5751 --to /tmp/store-1 /nix/store/ccr6560gzlizgp89dw67dhvzrbdwngfs-signer-1, in 0.09 seconds)1161server: must succeed: nix --store /tmp/store-1 store cat /nix/store/ccr6560gzlizgp89dw67dhvzrbdwngfs-signer-1 | grep -F signer-1162server: (finished: must succeed: nix --store /tmp/store-1 store cat /nix/store/ccr6560gzlizgp89dw67dhvzrbdwngfs-signer-1 | grep -F signer-, in 0.05 seconds)1163(finished: subtest: narinfos signed through OpenBao verify against the public key, in 0.55 seconds)1164subtest: the signing process is reused across pushes1165server: must succeed: pgrep -x niks3-openbao-s | head -n11166server: (finished: must succeed: pgrep -x niks3-openbao-s | head -n1, in 0.03 seconds)1167server: must succeed: nix-build -E 'derivation { name="signer-2"; system=builtins.currentSystem; builder="/bin/sh"; args=["-c" "echo signer-2 > $out"]; }' --no-out-link1168server # this derivation will be built:1169server # /nix/store/5asrlih8nqb505aw3x6396fzqbjm5w9j-signer-2.drv1170server # building '/nix/store/5asrlih8nqb505aw3x6396fzqbjm5w9j-signer-2.drv'...1171server: (finished: must succeed: nix-build -E 'derivation { name="signer-2"; system=builtins.currentSystem; builder="/bin/sh"; args=["-c" "echo signer-2 > $out"]; }' --no-out-link, in 0.12 seconds)1172server: must succeed: NIKS3_SERVER_URL=http://localhost:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/auth-token /nix/store/bg6jgn2yqlmlvyjgl3inckyq6nryrvfj-niks3-1.15.0/bin/niks3 push /nix/store/g4cb183m3i1illkc1c0r2i7b6c1wk4sf-signer-21173server # [ 23.707655] niks3-server[1019]: 2026/10/25 13:22:58 INFO Received push request method=POST path=/api/pushes1174server # time=2026-10-25T13:22:58.223Z level=INFO msg="Uploading 1 paths to localhost (0 already cached)"1175server # time=2026-10-25T13:22:58.225Z level=INFO msg="Uploading g4cb183m3i1illkc1c0r2i7b6c1wk4sf-signer-2 (128B)"1176server # [ 23.742252] niks3-server[1019]: 2026/10/25 13:22:58 INFO Registered completed upload object_key=nar/0nwavznx6l2iygh4lpjn8msr9hyp614fcfihlbm95zgkr76jikcj.nar.zst1177server # [ 23.746284] niks3-server[1019]: 2026/10/25 13:22:58 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign1178server # [ 23.748311] niks3-server[1019]: 2026/10/25 13:22:58 INFO Registered completed upload object_key=g4cb183m3i1illkc1c0r2i7b6c1wk4sf.ls1179server # [ 23.756647] niks3-server[1019]: 2026/10/25 13:22:58 INFO Signed narinfos id=2 count=11180server # time=2026-10-25T13:22:58.268Z level=INFO msg="Uploading 1 narinfos"1181server # [ 23.759386] niks3-server[1019]: 2026/10/25 13:22:58 INFO Registered completed upload object_key=log/5asrlih8nqb505aw3x6396fzqbjm5w9j-signer-2.drv1182server # [ 23.775072] niks3-server[1019]: 2026/10/25 13:22:58 INFO Received complete push request method=POST path=/api/pushes/2/complete1183server # time=2026-10-25T13:22:58.290Z level=INFO msg="Upload complete. (115ms)"1184server # [ 23.780671] niks3-server[1019]: 2026/10/25 13:22:58 INFO Completed push id=2 objects=4 duration=4.72602ms1185server # [ 23.782746] niks3-server[1019]: 2026/10/25 13:22:58 INFO Registered completed upload object_key=g4cb183m3i1illkc1c0r2i7b6c1wk4sf.narinfo1186server: (finished: must succeed: NIKS3_SERVER_URL=http://localhost:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/auth-token /nix/store/bg6jgn2yqlmlvyjgl3inckyq6nryrvfj-niks3-1.15.0/bin/niks3 push /nix/store/g4cb183m3i1illkc1c0r2i7b6c1wk4sf-signer-2, in 0.17 seconds)1187server: must succeed: nix copy --from http://localhost:5751 --to /tmp/store-2 /nix/store/g4cb183m3i1illkc1c0r2i7b6c1wk4sf-signer-21188server # copying 1 paths...1189server # copying path '/nix/store/g4cb183m3i1illkc1c0r2i7b6c1wk4sf-signer-2' from 'http://localhost:5751'...1190server: (finished: must succeed: nix copy --from http://localhost:5751 --to /tmp/store-2 /nix/store/g4cb183m3i1illkc1c0r2i7b6c1wk4sf-signer-2, in 0.08 seconds)1191server: must succeed: nix --store /tmp/store-2 store cat /nix/store/g4cb183m3i1illkc1c0r2i7b6c1wk4sf-signer-2 | grep -F signer-1192server: (finished: must succeed: nix --store /tmp/store-2 store cat /nix/store/g4cb183m3i1illkc1c0r2i7b6c1wk4sf-signer-2 | grep -F signer-, in 0.05 seconds)1193server: must succeed: pgrep -x niks3-openbao-s | head -n11194server: (finished: must succeed: pgrep -x niks3-openbao-s | head -n1, in 0.02 seconds)1195(finished: subtest: the signing process is reused across pushes, in 0.46 seconds)1196subtest: a killed signing process is restarted1197server: must succeed: pkill -KILL -x niks3-openbao-s1198server: (finished: must succeed: pkill -KILL -x niks3-openbao-s, in 0.02 seconds)1199server: must succeed: nix-build -E 'derivation { name="signer-3"; system=builtins.currentSystem; builder="/bin/sh"; args=["-c" "echo signer-3 > $out"]; }' --no-out-link1200server # this derivation will be built:1201server # /nix/store/690vc1n2hpjzyg5k7r8ak9j29x35yjav-signer-3.drv1202server # building '/nix/store/690vc1n2hpjzyg5k7r8ak9j29x35yjav-signer-3.drv'...1203server: (finished: must succeed: nix-build -E 'derivation { name="signer-3"; system=builtins.currentSystem; builder="/bin/sh"; args=["-c" "echo signer-3 > $out"]; }' --no-out-link, in 0.12 seconds)1204server: must succeed: NIKS3_SERVER_URL=http://localhost:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/auth-token /nix/store/bg6jgn2yqlmlvyjgl3inckyq6nryrvfj-niks3-1.15.0/bin/niks3 push /nix/store/j82pa3l46yhmddg860sc999r8x7fhmcc-signer-31205server # [ 24.161187] niks3-server[1019]: 2026/10/25 13:22:58 INFO Received push request method=POST path=/api/pushes1206server # time=2026-10-25T13:22:58.677Z level=INFO msg="Uploading 1 paths to localhost (0 already cached)"1207server # time=2026-10-25T13:22:58.678Z level=INFO msg="Uploading j82pa3l46yhmddg860sc999r8x7fhmcc-signer-3 (128B)"1208server # [ 24.207877] niks3-server[1019]: 2026/10/25 13:22:58 INFO Registered completed upload object_key=j82pa3l46yhmddg860sc999r8x7fhmcc.ls1209server # [ 24.212731] niks3-server[1019]: 2026/10/25 13:22:58 INFO Received sign narinfos request method=POST path=/api/pushes/3/sign1210server # [ 24.218553] niks3-server[1019]: 2026/10/25 13:22:58 INFO Registered completed upload object_key=log/690vc1n2hpjzyg5k7r8ak9j29x35yjav-signer-3.drv1211server # [ 24.222900] niks3-server[1019]: 2026/10/25 13:22:58 INFO Registered completed upload object_key=nar/0a5xm19dinsl6r6mgmzvlxv91jdb1hr34kxlyic4f5w7c5db29z4.nar.zst1212server # [ 24.233977] niks3-server[1019]: 2026/10/25 13:22:58 INFO Signed narinfos id=3 count=11213server # time=2026-10-25T13:22:58.746Z level=INFO msg="Uploading 1 narinfos"1214server # [ 24.245273] niks3-server[1019]: 2026/10/25 13:22:58 INFO Received complete push request method=POST path=/api/pushes/3/complete1215server # [ 24.252958] niks3-server[1019]: 2026/10/25 13:22:58 INFO Registered completed upload object_key=j82pa3l46yhmddg860sc999r8x7fhmcc.narinfo1216server # [ 24.256051] niks3-server[1019]: 2026/10/25 13:22:58 INFO Completed push id=3 objects=4 duration=10.983519ms1217server # time=2026-10-25T13:22:58.768Z level=INFO msg="Upload complete. (138ms)"1218server: (finished: must succeed: NIKS3_SERVER_URL=http://localhost:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/auth-token /nix/store/bg6jgn2yqlmlvyjgl3inckyq6nryrvfj-niks3-1.15.0/bin/niks3 push /nix/store/j82pa3l46yhmddg860sc999r8x7fhmcc-signer-3, in 0.19 seconds)1219server: must succeed: nix copy --from http://localhost:5751 --to /tmp/store-3 /nix/store/j82pa3l46yhmddg860sc999r8x7fhmcc-signer-31220server # copying 1 paths...1221server # copying path '/nix/store/j82pa3l46yhmddg860sc999r8x7fhmcc-signer-3' from 'http://localhost:5751'...1222server: (finished: must succeed: nix copy --from http://localhost:5751 --to /tmp/store-3 /nix/store/j82pa3l46yhmddg860sc999r8x7fhmcc-signer-3, in 0.07 seconds)1223server: must succeed: nix --store /tmp/store-3 store cat /nix/store/j82pa3l46yhmddg860sc999r8x7fhmcc-signer-3 | grep -F signer-1224server: (finished: must succeed: nix --store /tmp/store-3 store cat /nix/store/j82pa3l46yhmddg860sc999r8x7fhmcc-signer-3 | grep -F signer-, in 0.05 seconds)1225(finished: subtest: a killed signing process is restarted, in 0.45 seconds)1226subtest: OpenBao errors fail the push without crashing the server1227server: must succeed: systemctl stop openbao-dev.service1228server # [ 24.398234] systemd[1]: Stopping openbao-dev.service...1229server # [ 24.399229] bao[510]: ==> OpenBao shutdown triggered1230server # [ 24.401121] bao[510]: 2026-10-25T13:22:58.911Z [INFO] core: marked as sealed1231server # [ 24.402031] bao[510]: 2026-10-25T13:22:58.911Z [INFO] core: pre-seal teardown starting1232server # [ 24.403238] bao[510]: 2026-10-25T13:22:58.914Z [INFO] rollback: stopping rollback manager1233server # [ 24.405746] bao[510]: 2026-10-25T13:22:58.916Z [INFO] core: pre-seal teardown complete1234server # [ 24.406870] bao[510]: 2026-10-25T13:22:58.917Z [INFO] core: stopping cluster listeners1235server # [ 24.407905] bao[510]: 2026-10-25T13:22:58.918Z [INFO] core.cluster-listener: forwarding rpc listeners stopped1236server # [ 24.897689] bao[510]: 2026-10-25T13:22:59.408Z [INFO] core.cluster-listener: rpc listeners successfully shut down1237server # [ 24.902215] bao[510]: 2026-10-25T13:22:59.408Z [INFO] core: cluster listeners successfully shut down1238server # [ 24.904983] bao[510]: 2026-10-25T13:22:59.408Z [INFO] core: vault is sealed1239server # [ 24.917446] systemd[1]: openbao-dev.service: Deactivated successfully.1240server # [ 24.921173] systemd[1]: Stopped openbao-dev.service.1241server # [ 24.924505] systemd[1]: openbao-dev.service: Consumed 1.325s CPU time over 17.600s wall clock time, 71.3M memory peak, 4.4K incoming IP traffic, 4.9K outgoing IP traffic.1242server: (finished: must succeed: systemctl stop openbao-dev.service, in 0.55 seconds)1243server: must succeed: nix-build -E 'derivation { name="signer-4"; system=builtins.currentSystem; builder="/bin/sh"; args=["-c" "echo signer-4 > $out"]; }' --no-out-link1244server # this derivation will be built:1245server # /nix/store/6z5x25a307y69hc8y846il1a2yn7c6b9-signer-4.drv1246server # building '/nix/store/6z5x25a307y69hc8y846il1a2yn7c6b9-signer-4.drv'...1247server: (finished: must succeed: nix-build -E 'derivation { name="signer-4"; system=builtins.currentSystem; builder="/bin/sh"; args=["-c" "echo signer-4 > $out"]; }' --no-out-link, in 0.14 seconds)1248server: must fail: NIKS3_SERVER_URL=http://localhost:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/auth-token /nix/store/bg6jgn2yqlmlvyjgl3inckyq6nryrvfj-niks3-1.15.0/bin/niks3 push /nix/store/h9x5fql7nfamn3mpz7b7mpp1mbini4n9-signer-41249server # [ 25.162812] niks3-server[1019]: 2026/10/25 13:22:59 INFO Received push request method=POST path=/api/pushes1250server # time=2026-10-25T13:22:59.677Z level=INFO msg="Uploading 1 paths to localhost (0 already cached)"1251server # time=2026-10-25T13:22:59.678Z level=INFO msg="Uploading h9x5fql7nfamn3mpz7b7mpp1mbini4n9-signer-4 (128B)"1252server # [ 25.201181] niks3-server[1019]: 2026/10/25 13:22:59 INFO Received sign narinfos request method=POST path=/api/pushes/4/sign1253server # [ 25.204054] niks3-server[1019]: 2026/10/25 13:22:59 ERROR Failed to sign narinfos signer=niks3-test-1 error="signing program: narinfo \"h9x5fql7nfamn3mpz7b7mpp1mbini4n9.narinfo\": reading token: open /run/openbao-signer/token: no such file or directory"1254server # time=2026-10-25T13:22:59.717Z level=WARN msg="Request returned retryable status, retrying" attempt=1 backoff=100ms status=500 url=http://localhost:5751/api/pushes/4/sign1255server # [ 25.208885] niks3-server[1019]: 2026/10/25 13:22:59 INFO Registered completed upload object_key=h9x5fql7nfamn3mpz7b7mpp1mbini4n9.ls1256server # [ 25.211949] niks3-server[1019]: 2026/10/25 13:22:59 INFO Registered completed upload object_key=log/6z5x25a307y69hc8y846il1a2yn7c6b9-signer-4.drv1257server # [ 25.214407] niks3-server[1019]: 2026/10/25 13:22:59 INFO Registered completed upload object_key=nar/08bvxj5dmksfdkqa4pjy110ni52l4pkvb9a02p3qa256f0idj24s.nar.zst1258server # [ 25.311795] niks3-server[1019]: 2026/10/25 13:22:59 INFO Received sign narinfos request method=POST path=/api/pushes/4/sign1259server # [ 25.315947] niks3-server[1019]: 2026/10/25 13:22:59 ERROR Failed to sign narinfos signer=niks3-test-1 error="signing program: narinfo \"h9x5fql7nfamn3mpz7b7mpp1mbini4n9.narinfo\": reading token: open /run/openbao-signer/token: no such file or directory"1260server # time=2026-10-25T13:22:59.832Z level=WARN msg="Request returned retryable status, retrying" attempt=2 backoff=209.754687ms status=500 url=http://localhost:5751/api/pushes/4/sign1261server # [ 25.535337] niks3-server[1019]: 2026/10/25 13:23:00 INFO Received sign narinfos request method=POST path=/api/pushes/4/sign1262server # [ 25.537626] niks3-server[1019]: 2026/10/25 13:23:00 ERROR Failed to sign narinfos signer=niks3-test-1 error="signing program: narinfo \"h9x5fql7nfamn3mpz7b7mpp1mbini4n9.narinfo\": reading token: open /run/openbao-signer/token: no such file or directory"1263server # time=2026-10-25T13:23:00.051Z level=WARN msg="Request returned retryable status, retrying" attempt=3 backoff=423.029051ms status=500 url=http://localhost:5751/api/pushes/4/sign1264server # [ 25.966881] niks3-server[1019]: 2026/10/25 13:23:00 INFO Received sign narinfos request method=POST path=/api/pushes/4/sign1265server # [ 25.972142] niks3-server[1019]: 2026/10/25 13:23:00 ERROR Failed to sign narinfos signer=niks3-test-1 error="signing program: narinfo \"h9x5fql7nfamn3mpz7b7mpp1mbini4n9.narinfo\": reading token: open /run/openbao-signer/token: no such file or directory"1266server # time=2026-10-25T13:23:00.491Z level=WARN msg="Request returned retryable status, retrying" attempt=4 backoff=759.709595ms status=500 url=http://localhost:5751/api/pushes/4/sign1267server # [ 26.747077] niks3-server[1019]: 2026/10/25 13:23:01 INFO Received sign narinfos request method=POST path=/api/pushes/4/sign1268server # [ 26.752747] niks3-server[1019]: 2026/10/25 13:23:01 ERROR Failed to sign narinfos signer=niks3-test-1 error="signing program: narinfo \"h9x5fql7nfamn3mpz7b7mpp1mbini4n9.narinfo\": reading token: open /run/openbao-signer/token: no such file or directory"1269server # time=2026-10-25T13:23:01.271Z level=WARN msg="Request returned retryable status, retrying" attempt=5 backoff=1.692200993s status=500 url=http://localhost:5751/api/pushes/4/sign1270server # [ 28.460747] niks3-server[1019]: 2026/10/25 13:23:02 INFO Received sign narinfos request method=POST path=/api/pushes/4/sign1271server # [ 28.466672] niks3-server[1019]: 2026/10/25 13:23:02 ERROR Failed to sign narinfos signer=niks3-test-1 error="signing program: narinfo \"h9x5fql7nfamn3mpz7b7mpp1mbini4n9.narinfo\": reading token: open /run/openbao-signer/token: no such file or directory"1272server # time=2026-10-25T13:23:02.984Z level=ERROR msg="Fatal error" error="pushing paths: signing and uploading narinfos: signing narinfos for closure 4: server returned 500: failed to sign narinfos: signing program: narinfo \"h9x5fql7nfamn3mpz7b7mpp1mbini4n9.narinfo\": reading token: open /run/openbao-signer/token: no such file or directory\n"1273server: (finished: must fail: NIKS3_SERVER_URL=http://localhost:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/auth-token /nix/store/bg6jgn2yqlmlvyjgl3inckyq6nryrvfj-niks3-1.15.0/bin/niks3 push /nix/store/h9x5fql7nfamn3mpz7b7mpp1mbini4n9-signer-4, in 3.42 seconds)1274server: must succeed: systemctl restart openbao-setup.service1275server # [ 28.524915] niks3-server[1019]: 2026/10/25 13:23:03 INFO Shutdown signal received, draining in-flight requests timeout=10s1276server # [ 28.526505] systemd[1]: Stopping niks3 server...1277server # [ 28.539546] systemd[1]: niks3.service: Deactivated successfully.1278server # [ 28.541232] systemd[1]: Stopped niks3 server.1279server # [ 28.541956] systemd[1]: niks3.service: Consumed 158ms CPU time over 6.220s wall clock time, 29.5M memory peak, 19.3K incoming IP traffic, 24.8K outgoing IP traffic.1280server # [ 28.547206] systemd[1]: openbao-setup.service: Deactivated successfully.1281server # [ 28.548468] systemd[1]: Stopped openbao-setup.service.1282server # [ 28.550234] systemd[1]: openbao-setup.service: Consumed 445ms CPU time over 21.225s wall clock time, 45.7M memory peak, 3K incoming IP traffic, 2.8K outgoing IP traffic.1283server # [ 28.551974] systemd[1]: Stopping openbao-setup.service...1284server # [ 28.553965] systemd[1]: Started openbao-dev.service.1285server # [ 28.560433] systemd[1]: Starting openbao-setup.service...1286server # [ 28.770449] bao[1231]: 2026-10-25T13:23:03.280Z [INFO] proxy environment: http_proxy="" https_proxy="" no_proxy=""1287server # [ 28.773146] bao[1231]: 2026-10-25T13:23:03.283Z [WARN] no `api_addr` value specified in config or in BAO_API_ADDR; falling back to detection if possible, but this value should be manually set1288server # [ 28.776815] bao[1231]: 2026-10-25T13:23:03.287Z [INFO] core: Initializing version history cache for core1289server # [ 28.777995] bao[1231]: ==> OpenBao server configuration:1290server # [ 28.778685] bao[1231]: Api Address: http://127.0.0.1:82001291server # [ 28.779480] bao[1231]: Cgo: enabled1292server # [ 28.780224] bao[1231]: Cluster Address: https://127.0.0.1:82011293server # [ 28.781072] bao[1231]: Environment Variables: HOME, INVOCATION_ID, IO_PRESSURE_WATCH, IO_PRESSURE_WRITE, JOURNAL_STREAM, LANG, LOCALE_ARCHIVE, LOGNAME, MEMORY_PRESSURE_WATCH, MEMORY_PRESSURE_WRITE, PATH, RUNTIME_DIRECTORY, STATE_DIRECTORY, SYSTEMD_EXEC_PID, TZDIR, USER1294server # [ 28.783677] bao[1231]: Go Version: go1.27.21295server # [ 28.784365] bao[1231]: Listener 1: tcp (addr: "127.0.0.1:8200", cluster address: "127.0.0.1:8201", max_request_duration: "1m30s", max_request_json_memory: "34078720", max_request_json_strings: "1000", max_request_size: "33554432", tls: "disabled")1296server # [ 28.787073] bao[1231]: Log Level:1297server # [ 28.787727] bao[1231]: Recovery Mode: false1298server # [ 28.788428] bao[1231]: Storage: inmem1299server # [ 28.789069] bao[1231]: Version: OpenBao v2.7.1, committed 1970-01-01T00:00:00Z1300server # [ 28.790113] bao[1231]: Version Sha: refs/tags/v2.7.11301server # [ 28.790872] bao[1231]: 2026-10-25T13:23:03.287Z [INFO] core: security barrier not initialized1302server # [ 28.794055] bao[1231]: 2026-10-25T13:23:03.303Z [INFO] core: security barrier initialized: shares=1 threshold=11303server # [ 28.795316] bao[1231]: 2026-10-25T13:23:03.304Z [INFO] core: post-unseal setup starting1304server # [ 28.808637] bao[1231]: 2026-10-25T13:23:03.319Z [INFO] core: loaded wrapping token key1305server # [ 28.809722] bao[1231]: 2026-10-25T13:23:03.320Z [INFO] core: successfully setup plugin catalog: plugin-directory=""1306server # [ 28.811844] bao[1231]: 2026-10-25T13:23:03.322Z [INFO] core: reading transactional mount table1307server # [ 28.813202] bao[1231]: 2026-10-25T13:23:03.324Z [INFO] core: no mounts in transactional mount table; adding default mount table1308server # [ 28.815737] bao[1231]: 2026-10-25T13:23:03.326Z [INFO] core: successfully mounted: type=cubbyhole version="v2.7.1+builtin.bao" path=cubbyhole/ namespace="ID: root. Path: "1309server # [ 28.817825] bao[1231]: 2026-10-25T13:23:03.328Z [INFO] core: successfully mounted: type=system version="v2.7.1+builtin.bao" path=sys/ namespace="ID: root. Path: "1310server # [ 28.819840] bao[1231]: 2026-10-25T13:23:03.330Z [INFO] core: successfully mounted: type=identity version="v2.7.1+builtin.bao" path=identity/ namespace="ID: root. Path: "1311server # [ 28.823117] bao[1231]: 2026-10-25T13:23:03.334Z [INFO] core: reading transactional auth mount table1312server # [ 28.824790] bao[1231]: 2026-10-25T13:23:03.335Z [INFO] core: no auth mounts in transactional auth mount table; adding default auth mount table1313server # [ 28.827127] bao[1231]: 2026-10-25T13:23:03.338Z [INFO] core: successfully mounted: type=token version="v2.7.1+builtin.bao" path=token/ namespace="ID: root. Path: "1314server # [ 28.829378] bao[1231]: 2026-10-25T13:23:03.340Z [INFO] rollback: Starting the rollback manager with 256 workers1315server # [ 28.831663] bao[1231]: 2026-10-25T13:23:03.342Z [INFO] core: restoring leases1316server # [ 28.835051] bao[1231]: 2026-10-25T13:23:03.346Z [INFO] expiration: lease restore complete1317server # [ 28.838049] bao[1231]: 2026-10-25T13:23:03.348Z [INFO] rollback: starting rollback manager1318server # [ 28.839117] bao[1231]: 2026-10-25T13:23:03.348Z [INFO] core: entities restored1319server # [ 28.840062] bao[1231]: 2026-10-25T13:23:03.348Z [INFO] core: groups restored1320server # [ 28.841290] bao[1231]: 2026-10-25T13:23:03.352Z [INFO] core: Recorded vault version: vault version=2.7.1 upgrade time="2026-10-25 13:23:03.352079521 +0000 UTC" commit date=1970-01-01T00:00:00Z1321server # [ 28.844505] bao[1231]: 2026-10-25T13:23:03.355Z [INFO] core: post-unseal setup complete1322server # [ 28.847315] bao[1231]: 2026-10-25T13:23:03.358Z [INFO] core: root token generated1323server # [ 28.848308] bao[1231]: 2026-10-25T13:23:03.359Z [INFO] core: pre-seal teardown starting1324server # [ 28.851889] bao[1231]: 2026-10-25T13:23:03.362Z [INFO] rollback: stopping rollback manager1325server # [ 28.855047] bao[1231]: 2026-10-25T13:23:03.365Z [INFO] core: pre-seal teardown complete1326server # [ 28.857791] bao[1231]: 2026-10-25T13:23:03.368Z [INFO] core.cluster-listener.tcp: starting listener: listener_address=127.0.0.1:82011327server # [ 28.860064] bao[1231]: 2026-10-25T13:23:03.370Z [INFO] core.cluster-listener: serving cluster requests: cluster_listen_address=127.0.0.1:82011328server # [ 28.861621] bao[1231]: 2026-10-25T13:23:03.370Z [INFO] core: post-unseal setup starting1329server # [ 28.862586] bao[1231]: 2026-10-25T13:23:03.370Z [INFO] core: loaded wrapping token key1330server # [ 28.863576] bao[1231]: 2026-10-25T13:23:03.370Z [INFO] core: successfully setup plugin catalog: plugin-directory=""1331server # [ 28.865300] bao[1231]: 2026-10-25T13:23:03.376Z [INFO] core: reading transactional mount table1332server # [ 28.867458] bao[1231]: 2026-10-25T13:23:03.378Z [INFO] core: successfully mounted: type=identity version="v2.7.1+builtin.bao" path=identity/ namespace="ID: root. Path: "1333server # [ 28.869827] bao[1231]: 2026-10-25T13:23:03.380Z [INFO] core: successfully mounted: type=system version="v2.7.1+builtin.bao" path=sys/ namespace="ID: root. Path: "1334server # [ 28.873052] bao[1231]: 2026-10-25T13:23:03.382Z [INFO] core: successfully mounted: type=cubbyhole version="v2.7.1+builtin.bao" path=cubbyhole/ namespace="ID: root. Path: "1335server # [ 28.874902] bao[1231]: 2026-10-25T13:23:03.384Z [INFO] core: reading transactional auth mount table1336server # [ 28.878157] bao[1231]: 2026-10-25T13:23:03.389Z [INFO] core: successfully mounted: type=token version="v2.7.1+builtin.bao" path=token/ namespace="ID: root. Path: "1337server # [ 28.880532] bao[1231]: 2026-10-25T13:23:03.391Z [INFO] rollback: Starting the rollback manager with 256 workers1338server # [ 28.882381] bao[1231]: 2026-10-25T13:23:03.393Z [INFO] core: restoring leases1339server # [ 28.884878] bao[1231]: 2026-10-25T13:23:03.395Z [INFO] expiration: lease restore complete1340server # [ 28.887090] bao[1231]: 2026-10-25T13:23:03.398Z [INFO] rollback: starting rollback manager1341server # [ 28.888724] bao[1231]: 2026-10-25T13:23:03.399Z [INFO] core: entities restored1342server # [ 28.889805] bao[1231]: 2026-10-25T13:23:03.400Z [INFO] core: groups restored1343server # [ 28.891574] bao[1231]: 2026-10-25T13:23:03.402Z [INFO] core: post-unseal setup complete1344server # [ 28.892738] bao[1231]: 2026-10-25T13:23:03.403Z [INFO] core: vault is unsealed1345server # [ 28.900098] bao[1231]: 2026-10-25T13:23:03.410Z [INFO] expiration: revoked lease: lease_id=auth/token/root/hf7077a8f6fb1fd4475e5edbd1609ef437f1ea09f5fa5a36d71483eafc701d41a1346server # [ 28.903889] bao[1231]: 2026-10-25T13:23:03.414Z [INFO] core: successful mount: namespace="" path=secret/ type=kv version=""1347server # [ 28.905561] bao[1231]: 2026-10-25T13:23:03.416Z [INFO] beginning post-unseal configuration1348server # [ 28.907669] bao[1231]: ==> OpenBao server started!1349server # [ 28.912197] bao[1231]: 2026-10-25T13:23:03.418Z [INFO] secrets.kv.kv_86e4c126: collecting keys to upgrade1350server # [ 28.914323] bao[1231]: 2026-10-25T13:23:03.425Z [INFO] secrets.kv.kv_86e4c126: done collecting keys: num_keys=11351server # [ 28.915560] bao[1231]: 2026-10-25T13:23:03.425Z [INFO] secrets.kv.kv_86e4c126: upgrading keys finished1352server # [ 29.007178] bao[1231]: 2026-10-25T13:23:03.518Z [INFO] core: successful mount: namespace="" path=transit/ type=transit version=""1353server # [ 29.011287] openbao-setup-start[1248]: Success! Enabled the transit secrets engine at: transit/1354server # [ 29.107031] openbao-setup-start[1257]: Retrieving wrapping key.1355server # [ 29.119178] bao[1231]: WARNING! dev mode is enabled! In this mode, OpenBao runs entirely in-memory1356server # [ 29.120288] bao[1231]: and starts unsealed with a single unseal key. The root token is already1357server # [ 29.121322] bao[1231]: authenticated to the CLI, so you can immediately begin using OpenBao.1358server # [ 29.123054] bao[1231]: You may need to set the following environment variables:1359server # [ 29.123907] bao[1231]: $ export BAO_ADDR='http://127.0.0.1:8200'1360server # [ 29.124714] bao[1231]: The unseal key and root token are displayed below in case you want to1361server # [ 29.125704] bao[1231]: seal/unseal the Vault or re-authenticate.1362server # [ 29.126455] bao[1231]: Unseal Key: oCFD8On1ypS3/kFpawv8Xs5LFbDrlM54DjIBLZ2AnHY=1363server # [ 29.127336] bao[1231]: Root Token: root1364server # [ 29.127835] bao[1231]: Development mode should NOT be used in production installations!1365server # [ 29.463890] openbao-setup-start[1257]: Wrapping source key with ephemeral key.1366server # [ 29.465079] openbao-setup-start[1257]: Encrypting ephemeral key with wrapping key.1367server # [ 29.466601] openbao-setup-start[1257]: Submitting wrapped key.1368server # [ 29.478060] openbao-setup-start[1257]: Success!1369server # [ 29.486673] systemd[1]: Finished openbao-setup.service.1370server # [ 29.493041] systemd[1]: Starting niks3 server...1371server: (finished: must succeed: systemctl restart openbao-setup.service, in 1.01 seconds)1372server: must succeed: nix-build -E 'derivation { name="signer-5"; system=builtins.currentSystem; builder="/bin/sh"; args=["-c" "echo signer-5 > $out"]; }' --no-out-link1373server # [ 29.577513] niks3-server[1265]: 2026/10/25 13:23:04 INFO Configured external signer program=/nix/store/48aprxb904y6171346rha7xknfww6rs4-niks3-openbao-signer/bin/niks3-openbao-signer1374server # this derivation will be built:1375server # /nix/store/bqsh510vk6kx4ynb4vifzbffdv3pym0x-signer-5.drv1376server # [ 29.608426] niks3-server[1265]: 2026/10/25 13:23:04 INFO Read proxy enabled — serving cache objects from S31377server # [ 29.610661] niks3-server[1265]: 2026/10/25 13:23:04 INFO Using socket-activated listener address=0.0.0.0:57511378server # [ 29.612225] niks3-server[1265]: 2026/10/25 13:23:04 INFO systemd watchdog enabled interval=15s1379server # [ 29.614465] niks3-server[1265]: 2026/10/25 13:23:04 INFO Starting HTTP server address=0.0.0.0:57511380server # [ 29.616549] systemd[1]: Started niks3 server.1381server # building '/nix/store/bqsh510vk6kx4ynb4vifzbffdv3pym0x-signer-5.drv'...1382server: (finished: must succeed: nix-build -E 'derivation { name="signer-5"; system=builtins.currentSystem; builder="/bin/sh"; args=["-c" "echo signer-5 > $out"]; }' --no-out-link, in 0.19 seconds)1383server: must succeed: NIKS3_SERVER_URL=http://localhost:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/auth-token /nix/store/bg6jgn2yqlmlvyjgl3inckyq6nryrvfj-niks3-1.15.0/bin/niks3 push /nix/store/2ifz3szqnr8jjys2nvr5kxiw20gzifx0-signer-51384server # [ 29.801983] niks3-server[1265]: 2026/10/25 13:23:04 INFO Received push request method=POST path=/api/pushes1385server # time=2026-10-25T13:23:04.319Z level=INFO msg="Uploading 1 paths to localhost (0 already cached)"1386server # time=2026-10-25T13:23:04.320Z level=INFO msg="Uploading 2ifz3szqnr8jjys2nvr5kxiw20gzifx0-signer-5 (128B)"1387server # [ 29.840382] niks3-server[1265]: 2026/10/25 13:23:04 INFO Registered completed upload object_key=log/bqsh510vk6kx4ynb4vifzbffdv3pym0x-signer-5.drv1388server # [ 29.846485] niks3-server[1265]: 2026/10/25 13:23:04 INFO Registered completed upload object_key=2ifz3szqnr8jjys2nvr5kxiw20gzifx0.ls1389server # [ 29.850549] niks3-server[1265]: 2026/10/25 13:23:04 INFO Received sign narinfos request method=POST path=/api/pushes/5/sign1390server # [ 29.859660] niks3-server[1265]: 2026/10/25 13:23:04 INFO Registered completed upload object_key=nar/1rrjdxihgavl3xr6jzkh49lrd92dlyrdgs4inygpqssjvgcrjh9x.nar.zst1391server # [ 29.870325] niks3-server[1265]: 2026/10/25 13:23:04 INFO Signed narinfos id=5 count=11392server # time=2026-10-25T13:23:04.382Z level=INFO msg="Uploading 1 narinfos"1393server # [ 29.884150] niks3-server[1265]: 2026/10/25 13:23:04 INFO Received complete push request method=POST path=/api/pushes/5/complete1394server # time=2026-10-25T13:23:04.403Z level=INFO msg="Upload complete. (151ms)"1395server # [ 29.893172] niks3-server[1265]: 2026/10/25 13:23:04 INFO Completed push id=5 objects=4 duration=7.878655ms1396server # [ 29.899683] niks3-server[1265]: 2026/10/25 13:23:04 INFO Registered completed upload object_key=2ifz3szqnr8jjys2nvr5kxiw20gzifx0.narinfo1397server: (finished: must succeed: NIKS3_SERVER_URL=http://localhost:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/auth-token /nix/store/bg6jgn2yqlmlvyjgl3inckyq6nryrvfj-niks3-1.15.0/bin/niks3 push /nix/store/2ifz3szqnr8jjys2nvr5kxiw20gzifx0-signer-5, in 0.22 seconds)1398server: must succeed: nix copy --from http://localhost:5751 --to /tmp/store-5 /nix/store/2ifz3szqnr8jjys2nvr5kxiw20gzifx0-signer-51399server # copying 1 paths...1400server # copying path '/nix/store/2ifz3szqnr8jjys2nvr5kxiw20gzifx0-signer-5' from 'http://localhost:5751'...1401server: (finished: must succeed: nix copy --from http://localhost:5751 --to /tmp/store-5 /nix/store/2ifz3szqnr8jjys2nvr5kxiw20gzifx0-signer-5, in 0.09 seconds)1402server: must succeed: nix --store /tmp/store-5 store cat /nix/store/2ifz3szqnr8jjys2nvr5kxiw20gzifx0-signer-5 | grep -F signer-1403server: (finished: must succeed: nix --store /tmp/store-5 store cat /nix/store/2ifz3szqnr8jjys2nvr5kxiw20gzifx0-signer-5 | grep -F signer-, in 0.06 seconds)1404(finished: subtest: OpenBao errors fail the push without crashing the server, in 5.67 seconds)1405(finished: run the VM test script, in 31.55 seconds)1406test script finished in 31.59s1407cleanup1408kill QemuMachine (pid 45)1409server # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14)1410server # [2026-10-25T13:23:05Z INFO virtiofsd] Client disconnected, shutting down1411server # [2026-10-25T13:23:05Z INFO virtiofsd] Client disconnected, shutting down1412server # [2026-10-25T13:23:05Z INFO virtiofsd] Client disconnected, shutting down1413(finished: cleanup, in 0.35 seconds)1414additionally exposed symbols:1415 server,1416 vlan1,1417 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh