vm-test-run-nixos-test-niks3
checks.x86_64-linux.nixos-test-niks3-lix
· build #206
· 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 script7start all VMs8builder: starting vm9server: starting vm10server # Disk image does not exist, creating the virtualisation disk image...11server: QEMU running (pid 48)12server # Formatting '/build/vm-state-server/tmp.CW6AcIW1Bp', 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: 87bc6cf8-ab3e-4bef-bd27-9fae73b845f717server # 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.26builder: QEMU running (pid 47)27builder # Disk image does not exist, creating the virtualisation disk image...28builder # Formatting '/build/vm-state-builder/tmp.ZNmXyUMLOp', fmt=raw size=107374182429builder # mke2fs 1.47.4 (6-Mar-2025)30builder # Discarding device blocks: 0/262144 done31builder # Creating filesystem with 262144 4k blocks and 65536 inodes32builder # Filesystem UUID: 5b2f16f1-113b-4ee9-862b-8496fd52187e33builder # Superblock backups stored on blocks:34builder # 32768, 98304, 163840, 22937635builder # 36builder # Allocating group tables: 0/8 done37builder # Writing inode tables: 0/8 done38builder # Creating journal (8192 blocks): done39builder # Writing superblocks and filesystem accounting information: 0/8 done40builder # 41builder # Virtualisation disk image created.42(finished: start all VMs, in 0.27 seconds)43server: waiting for unit postgresql.service44server: waiting for the VM to finish booting45builder # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)46server # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)47server # 48server # 49server # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0050builder # 51builder # 52builder # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0053server # Press Ctrl-B to configure iPXE (PCI 00:02.0)...54server # 55server # 56server # 57server # 58server # iPXE (http://ipxe.org) 00:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0059builder # Press Ctrl-B to configure iPXE (PCI 00:02.0)...60builder # 61builder # 62builder # 63builder # 64builder # iPXE (http://ipxe.org) 00:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0065server # Press Ctrl-B to configure iPXE (PCI 00:08.0)...66server # 67server # 68builder # Press Ctrl-B to configure iPXE (PCI 00:08.0)...69builder # 70builder # 71builder # Booting from ROM...72builder # Probing EDD (edd=off to disable)... ok73server # Booting from ROM...74builder # [ 0.000000] Linux version 6.18.49 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Wed Sep 2 12:31:51 UTC 202675builder # [ 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/yja6sry4k4j8mnf9ynxn8yqb2ns6lbpl-nixos-system-builder-test/init regInfo=/nix/store/hv80ah30php7c7wig84ksylx13y13pag-closure-info/registration console=ttyS0,115200n8 console=tty076builder # [ 0.000000] BIOS-provided physical RAM map:77builder # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable78server # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.49 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Wed Sep 2 12:31:51 UTC 202679builder # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved80builder # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved81builder # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable82builder # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved83builder # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved84server # [ 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/d4bmp513a2vng4j49gqm195hzj9xlhki-nixos-system-server-test/init regInfo=/nix/store/qw70hrafz9y83ma4841f8mp0smmqqycw-closure-info/registration console=ttyS0,115200n8 console=tty085server # [ 0.000000] BIOS-provided physical RAM map:86builder # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved87server # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable88builder # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved89server # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved90builder # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved91server # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved92builder # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved93server # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable94builder # [ 0.000000] NX (Execute Disable) protection: active95builder # [ 0.000000] APIC: Static calls initialized96server # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved97builder # [ 0.000000] SMBIOS 2.8 present.98server # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved99builder # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014100server # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved101builder # [ 0.000000] DMI: Memory slots populated: 1/1102builder # [ 0.000000] Hypervisor detected: KVM103server # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved104builder # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000105server # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved106builder # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00107server # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved108builder # [ 0.000000] kvm-clock: using sched offset of 528976206 cycles109server # [ 0.000000] NX (Execute Disable) protection: active110server # [ 0.000000] APIC: Static calls initialized111builder # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns112server # [ 0.000000] SMBIOS 2.8 present.113builder # [ 0.000005] tsc: Detected 2400.012 MHz processor114builder # [ 0.000811] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000115server # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014116server # [ 0.000000] DMI: Memory slots populated: 1/1117builder # [ 0.000838] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs118server # [ 0.000000] Hypervisor detected: KVM119server # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000120builder # [ 0.000841] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT121server # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00122builder # [ 0.002744] found SMP MP-table at [mem 0x000f5450-0x000f545f]123server # [ 0.000001] kvm-clock: using sched offset of 534628156 cycles124builder # [ 0.002755] Using GB pages for direct mapping125builder # [ 0.002873] RAMDISK: [mem 0x3e36b000-0x3ffcffff]126builder # [ 0.002881] ACPI: Early table checksum verification disabled127server # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns128server # [ 0.000005] tsc: Detected 2400.012 MHz processor129builder # [ 0.002884] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )130server # [ 0.000804] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000131builder # [ 0.002888] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)132server # [ 0.000831] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs133builder # [ 0.002891] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)134server # [ 0.000833] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT135builder # [ 0.002898] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)136server # [ 0.002727] found SMP MP-table at [mem 0x000f5450-0x000f545f]137server # [ 0.002738] Using GB pages for direct mapping138builder # [ 0.002900] ACPI: FACS 0x000000003FFE0000 000040139server # [ 0.002791] RAMDISK: [mem 0x3e369000-0x3ffcffff]140builder # [ 0.002902] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)141server # [ 0.002798] ACPI: Early table checksum verification disabled142server # [ 0.002801] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )143builder # [ 0.002903] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)144server # [ 0.002805] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)145builder # [ 0.002905] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)146server # [ 0.002809] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)147builder # [ 0.002906] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)148builder # [ 0.002908] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]149server # [ 0.002816] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)150server # [ 0.002819] ACPI: FACS 0x000000003FFE0000 000040151builder # [ 0.002909] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]152builder # [ 0.002909] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]153server # [ 0.002820] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)154builder # [ 0.002910] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]155server # [ 0.002822] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)156builder # [ 0.002910] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]157server # [ 0.002823] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)158builder # [ 0.002910] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]159server # [ 0.002825] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)160builder # [ 0.002911] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]161builder # [ 0.003133] No NUMA configuration found162server # [ 0.002826] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]163builder # [ 0.003134] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]164server # [ 0.002827] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]165builder # [ 0.003137] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]166server # [ 0.002827] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]167builder # [ 0.005326] Zone ranges:168builder # [ 0.005327] DMA [mem 0x0000000000001000-0x0000000000ffffff]169server # [ 0.002828] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]170builder # [ 0.005329] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]171server # [ 0.002828] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]172builder # [ 0.005330] Normal empty173builder # [ 0.005331] Device empty174server # [ 0.002829] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]175builder # [ 0.005332] Movable zone start for each node176builder # [ 0.005332] Early memory node ranges177server # [ 0.002829] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]178server # [ 0.003076] No NUMA configuration found179builder # [ 0.005333] node 0: [mem 0x0000000000001000-0x000000000009efff]180server # [ 0.003077] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]181builder # [ 0.005334] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]182server # [ 0.003079] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]183builder # [ 0.005335] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]184server # [ 0.005333] Zone ranges:185builder # [ 0.005353] On node 0, zone DMA: 1 pages in unavailable ranges186server # [ 0.005334] DMA [mem 0x0000000000001000-0x0000000000ffffff]187builder # [ 0.005611] On node 0, zone DMA: 97 pages in unavailable ranges188server # [ 0.005335] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]189server # [ 0.005336] Normal empty190builder # [ 0.022716] On node 0, zone DMA32: 40 pages in unavailable ranges191server # [ 0.005337] Device empty192builder # [ 0.023154] ACPI: PM-Timer IO Port: 0x608193server # [ 0.005338] Movable zone start for each node194server # [ 0.005339] Early memory node ranges195builder # [ 0.023163] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])196server # [ 0.005339] node 0: [mem 0x0000000000001000-0x000000000009efff]197builder # [ 0.023188] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23198server # [ 0.005340] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]199builder # [ 0.023191] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)200server # [ 0.005341] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]201builder # [ 0.023193] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)202server # [ 0.005360] On node 0, zone DMA: 1 pages in unavailable ranges203builder # [ 0.023194] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)204server # [ 0.005619] On node 0, zone DMA: 97 pages in unavailable ranges205builder # [ 0.023195] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)206server # [ 0.022820] On node 0, zone DMA32: 40 pages in unavailable ranges207builder # [ 0.023196] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)208server # [ 0.023270] ACPI: PM-Timer IO Port: 0x608209builder # [ 0.023198] ACPI: Using ACPI (MADT) for SMP configuration information210server # [ 0.023280] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])211builder # [ 0.023199] ACPI: HPET id: 0x8086a201 base: 0xfed00000212server # [ 0.023305] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23213builder # [ 0.023202] TSC deadline timer available214server # [ 0.023307] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)215builder # [ 0.023206] CPU topo: Max. logical packages: 1216builder # [ 0.023207] CPU topo: Max. logical dies: 1217server # [ 0.023309] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)218builder # [ 0.023207] CPU topo: Max. dies per package: 1219server # [ 0.023310] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)220builder # [ 0.023211] CPU topo: Max. threads per core: 1221builder # [ 0.023211] CPU topo: Num. cores per package: 1222server # [ 0.023311] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)223builder # [ 0.023212] CPU topo: Num. threads per package: 1224server # [ 0.023312] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)225builder # [ 0.023212] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs226server # [ 0.023314] ACPI: Using ACPI (MADT) for SMP configuration information227builder # [ 0.023228] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()228server # [ 0.023315] ACPI: HPET id: 0x8086a201 base: 0xfed00000229server # [ 0.023318] TSC deadline timer available230builder # [ 0.023257] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]231server # [ 0.023322] CPU topo: Max. logical packages: 1232server # [ 0.023322] CPU topo: Max. logical dies: 1233builder # [ 0.023258] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]234server # [ 0.023323] CPU topo: Max. dies per package: 1235builder # [ 0.023260] [mem 0x40000000-0xafffffff] available for PCI devices236server # [ 0.023326] CPU topo: Max. threads per core: 1237builder # [ 0.023261] Booting paravirtualized kernel on KVM238server # [ 0.023327] CPU topo: Num. cores per package: 1239server # [ 0.023327] CPU topo: Num. threads per package: 1240builder # [ 0.023263] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns241server # [ 0.023327] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs242builder # [ 0.027715] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1243server # [ 0.023344] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()244builder # [ 0.029829] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152245server # [ 0.023374] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]246builder # [ 0.029874] kvm-guest: PV spinlocks disabled, single CPU247server # [ 0.023376] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]248server # [ 0.023377] [mem 0x40000000-0xafffffff] available for PCI devices249server # [ 0.023378] Booting paravirtualized kernel on KVM250server # [ 0.023381] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns251builder # [ 0.029875] 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/yja6sry4k4j8mnf9ynxn8yqb2ns6lbpl-nixos-system-builder-test/init regInfo=/nix/store/hv80ah30php7c7wig84ksylx13y13pag-closure-info/registration console=ttyS0,115200n8 console=tty0252server # [ 0.027802] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1253server # [ 0.029912] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152254builder # [ 0.029965] Unknown kernel command line parameters "regInfo=/nix/store/hv80ah30php7c7wig84ksylx13y13pag-closure-info/registration", will be passed to user space.255server # [ 0.029959] kvm-guest: PV spinlocks disabled, single CPU256builder # [ 0.029977] random: crng init done257builder # [ 0.029978] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes258builder # [ 0.031165] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)259builder # [ 0.031177] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)260builder # [ 0.031206] Fallback order for Node 0: 0261server # [ 0.029960] 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/d4bmp513a2vng4j49gqm195hzj9xlhki-nixos-system-server-test/init regInfo=/nix/store/qw70hrafz9y83ma4841f8mp0smmqqycw-closure-info/registration console=ttyS0,115200n8 console=tty0262builder # [ 0.031209] Built 1 zonelists, mobility grouping on. Total pages: 262006263builder # [ 0.031210] Policy zone: DMA32264builder # [ 0.033589] mem auto-init: stack:all(zero), heap alloc:on, heap free:off265server # [ 0.030058] Unknown kernel command line parameters "regInfo=/nix/store/qw70hrafz9y83ma4841f8mp0smmqqycw-closure-info/registration", will be passed to user space.266server # [ 0.030071] random: crng init done267builder # [ 0.035964] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1268builder # [ 0.038147] allocated 2097152 bytes of page_ext269server # [ 0.030072] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes270builder # [ 0.047915] ftrace: allocating 48732 entries in 192 pages271server # [ 0.031270] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)272builder # [ 0.047918] ftrace: allocated 192 pages with 2 groups273builder # [ 0.048716] Dynamic Preempt: lazy274server # [ 0.031283] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)275builder # [ 0.048881] rcu: Preemptible hierarchical RCU implementation.276server # [ 0.031311] Fallback order for Node 0: 0277builder # [ 0.048882] rcu: RCU event tracing is enabled.278server # [ 0.031314] Built 1 zonelists, mobility grouping on. Total pages: 262006279server # [ 0.031315] Policy zone: DMA32280builder # [ 0.048882] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.281builder # [ 0.048884] Trampoline variant of Tasks RCU enabled.282server # [ 0.033708] mem auto-init: stack:all(zero), heap alloc:on, heap free:off283builder # [ 0.048884] Rude variant of Tasks RCU enabled.284server # [ 0.036118] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1285builder # [ 0.048884] Tracing variant of Tasks RCU enabled.286server # [ 0.038296] allocated 2097152 bytes of page_ext287builder # [ 0.048885] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.288server # [ 0.048101] ftrace: allocating 48732 entries in 192 pages289server # [ 0.048102] ftrace: allocated 192 pages with 2 groups290builder # [ 0.048886] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1291server # [ 0.048896] Dynamic Preempt: lazy292server # [ 0.049029] rcu: Preemptible hierarchical RCU implementation.293builder # [ 0.048900] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.294server # [ 0.049030] rcu: RCU event tracing is enabled.295builder # [ 0.048901] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.296server # [ 0.049031] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.297server # [ 0.049032] Trampoline variant of Tasks RCU enabled.298builder # [ 0.048902] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.299server # [ 0.049033] Rude variant of Tasks RCU enabled.300builder # [ 0.053202] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16301server # [ 0.049033] Tracing variant of Tasks RCU enabled.302builder # [ 0.053486] rcu: srcu_init: Setting srcu_struct sizes based on contention.303server # [ 0.049034] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.304server # [ 0.049034] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1305builder # [ 0.053493] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns306server # [ 0.049088] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.307builder # [ 0.053604] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)308builder # [ 0.057077] Console: colour VGA+ 80x25309server # [ 0.049090] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.310builder # [ 0.057080] printk: legacy console [tty0] enabled311builder # [ 0.086134] printk: legacy console [ttyS0] enabled312server # [ 0.049091] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.313builder # [ 0.187195] ACPI: Core revision 20250807314server # [ 0.053403] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16315server # [ 0.053681] rcu: srcu_init: Setting srcu_struct sizes based on contention.316builder # [ 0.188100] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns317server # [ 0.053688] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns318builder # [ 0.189678] APIC: Switch to symmetric I/O mode setup319builder # [ 0.190703] x2apic enabled320server # [ 0.053788] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)321server # [ 0.057275] Console: colour VGA+ 80x25322builder # [ 0.191470] APIC: Switched APIC routing to: physical x2apic323server # [ 0.057278] printk: legacy console [tty0] enabled324server # [ 0.086297] printk: legacy console [ttyS0] enabled325server # [ 0.187692] ACPI: Core revision 20250807326builder # [ 0.193418] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1327server # [ 0.188575] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns328builder # [ 0.194440] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns329server # [ 0.190159] APIC: Switch to symmetric I/O mode setup330server # [ 0.191172] x2apic enabled331builder # [ 0.196133] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)332server # [ 0.191924] APIC: Switched APIC routing to: physical x2apic333builder # [ 0.197215] x86/cpu: User Mode Instruction Prevention (UMIP) activated334builder # [ 0.199436] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127335server # [ 0.193746] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1336builder # [ 0.200130] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0337server # [ 0.194747] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns338builder # [ 0.201134] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto339server # [ 0.196459] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)340builder # [ 0.202130] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl341server # [ 0.197543] x86/cpu: User Mode Instruction Prevention (UMIP) activated342builder # [ 0.203130] Transient Scheduler Attacks: Vulnerable: No microcode343server # [ 0.199373] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127344builder # [ 0.204130] Spectre V2 : Mitigation: Enhanced / Automatic IBRS345server # [ 0.200174] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0346builder # [ 0.205130] Speculative Return Stack Overflow: Mitigation: Safe RET347server # [ 0.201245] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto348server # [ 0.202456] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl349server # [ 0.203456] Transient Scheduler Attacks: Vulnerable: No microcode350builder # [ 0.206130] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization351server # [ 0.204456] Spectre V2 : Mitigation: Enhanced / Automatic IBRS352builder # [ 0.208138] Spectre V2 : Enabling IBPB for BPF353server # [ 0.205456] Speculative Return Stack Overflow: Mitigation: Safe RET354builder # [ 0.209131] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier355builder # [ 0.210130] active return thunk: srso_alias_return_thunk356builder # [ 0.211151] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'357builder # [ 0.212130] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'358server # [ 0.206456] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization359builder # [ 0.213891] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'360server # [ 0.208463] Spectre V2 : Enabling IBPB for BPF361builder # [ 0.214879] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'362server # [ 0.209193] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier363builder # [ 0.215905] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'364server # [ 0.210457] active return thunk: srso_alias_return_thunk365builder # [ 0.216875] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'366server # [ 0.211477] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'367builder # [ 0.217928] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'368server # [ 0.212456] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'369builder # [ 0.219130] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256370server # [ 0.213456] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'371builder # [ 0.220130] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64372server # [ 0.214456] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'373builder # [ 0.221086] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512374server # [ 0.215456] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'375builder # [ 0.221909] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024376server # [ 0.216456] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'377builder # [ 0.223130] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8378server # [ 0.217456] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'379builder # [ 0.224130] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.380server # [ 0.218456] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256381server # [ 0.220217] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64382server # [ 0.221247] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512383server # [ 0.222242] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024384server # [ 0.223242] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8385server # [ 0.224245] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.386builder # [ 0.258334] Freeing SMP alternatives memory: 44K387builder # [ 0.259129] pid_max: default: 32768 minimum: 301388builder # [ 0.259826] LSM: initializing lsm=capability,landlock,yama,bpf,ima389builder # [ 0.261183] landlock: Up and running.390builder # [ 0.261822] Yama: becoming mindful.391builder # [ 0.262345] LSM support for eBPF active392builder # [ 0.263227] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)393server # [ 0.258800] Freeing SMP alternatives memory: 44K394builder # [ 0.264149] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)395server # [ 0.259458] pid_max: default: 32768 minimum: 301396server # [ 0.260270] LSM: initializing lsm=capability,landlock,yama,bpf,ima397server # [ 0.261554] landlock: Up and running.398builder # [ 0.266967] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)399server # [ 0.262161] Yama: becoming mindful.400server # [ 0.262671] LSM support for eBPF active401builder # [ 0.267639] Performance Events: Fam17h+ core perfctr, AMD PMU driver.402builder # [ 0.268135] ... version: 2403server # [ 0.263573] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)404builder # [ 0.268807] ... bit width: 48405server # [ 0.264475] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)406builder # [ 0.269132] ... generic counters: 6407builder # [ 0.269824] ... generic bitmap: 000000000000003f408builder # [ 0.270178] ... fixed-purpose counters: 0409builder # [ 0.270914] ... fixed-purpose bitmap: 0000000000000000410server # [ 0.267584] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)411builder # [ 0.271133] ... value mask: 0000ffffffffffff412server # [ 0.269062] Performance Events: Fam17h+ core perfctr, AMD PMU driver.413builder # [ 0.272065] ... max period: 00007fffffffffff414server # [ 0.269465] ... version: 2415builder # [ 0.272819] ... global_ctrl mask: 000000000000003f416server # [ 0.270186] ... bit width: 48417builder # [ 0.273257] signal: max sigframe size: 3376418server # [ 0.270458] ... generic counters: 6419builder # [ 0.274074] rcu: Hierarchical SRCU implementation.420server # [ 0.271204] ... generic bitmap: 000000000000003f421builder # [ 0.274734] rcu: Max phase no-delay instances is 400.422server # [ 0.271458] ... fixed-purpose counters: 0423server # [ 0.272182] ... fixed-purpose bitmap: 0000000000000000424server # [ 0.272458] ... value mask: 0000ffffffffffff425server # [ 0.273376] ... max period: 00007fffffffffff426server # [ 0.274164] ... global_ctrl mask: 000000000000003f427server # [ 0.274567] signal: max sigframe size: 3376428builder # [ 0.279551] smp: Bringing up secondary CPUs ...429server # [ 0.275382] rcu: Hierarchical SRCU implementation.430builder # [ 0.280146] smp: Brought up 1 node, 1 CPU431server # [ 0.276059] rcu: Max phase no-delay instances is 400.432builder # [ 0.280798] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)433builder # [ 0.281300] Memory: 941260K/1048024K available (17215K kernel code, 2726K rwdata, 13584K rodata, 3644K init, 2988K bss, 99600K reserved, 0K cma-reserved)434builder # [ 0.282361] devtmpfs: initialized435builder # [ 0.283199] x86/mm: Memory block size: 128MB436server # [ 0.280945] smp: Bringing up secondary CPUs ...437server # [ 0.281471] smp: Brought up 1 node, 1 CPU438builder # [ 0.284944] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)439server # [ 0.282154] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)440builder # [ 0.286084] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).441builder # [ 0.287213] pinctrl core: initialized pinctrl subsystem442server # [ 0.282654] Memory: 941048K/1048024K available (17215K kernel code, 2726K rwdata, 13584K rodata, 3644K init, 2988K bss, 99608K reserved, 0K cma-reserved)443server # [ 0.283700] devtmpfs: initialized444builder # [ 0.288402] PM: RTC time: 08:17:22, date: 2026-09-15445server # [ 0.284452] x86/mm: Memory block size: 128MB446server # [ 0.286014] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)447builder # [ 0.291847] NET: Registered PF_NETLINK/PF_ROUTE protocol family448server # [ 0.286481] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).449builder # [ 0.292497] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations450server # [ 0.287541] pinctrl core: initialized pinctrl subsystem451builder # [ 0.293150] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations452server # [ 0.288649] PM: RTC time: 08:17:22, date: 2026-09-15453builder # [ 0.294276] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations454builder # [ 0.295142] audit: initializing netlink subsys (disabled)455builder # [ 0.296323] thermal_sys: Registered thermal governor 'fair_share'456server # [ 0.292123] NET: Registered PF_NETLINK/PF_ROUTE protocol family457builder # [ 0.296326] thermal_sys: Registered thermal governor 'bang_bang'458server # [ 0.292811] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations459builder # [ 0.297135] audit: type=2000 audit(1789460243.251:1): state=initialized audit_enabled=0 res=1460server # [ 0.293475] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations461builder # [ 0.299134] thermal_sys: Registered thermal governor 'step_wise'462server # [ 0.294608] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations463builder # [ 0.299136] thermal_sys: Registered thermal governor 'user_space'464server # [ 0.295469] audit: initializing netlink subsys (disabled)465builder # [ 0.300095] thermal_sys: Registered thermal governor 'power_allocator'466builder # [ 0.300937] cpuidle: using governor menu467server # [ 0.296638] thermal_sys: Registered thermal governor 'fair_share'468server # [ 0.296640] thermal_sys: Registered thermal governor 'bang_bang'469builder # [ 0.303221] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5470server # [ 0.297459] thermal_sys: Registered thermal governor 'step_wise'471builder # [ 0.304403] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]472server # [ 0.298462] audit: type=2000 audit(1789460243.255:1): state=initialized audit_enabled=0 res=1473builder # [ 0.305135] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry474server # [ 0.300462] thermal_sys: Registered thermal governor 'user_space'475builder # [ 0.306145] PCI: Using configuration type 1 for base access476server # [ 0.300463] thermal_sys: Registered thermal governor 'power_allocator'477server # [ 0.301427] cpuidle: using governor menu478builder # [ 0.307290] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.479server # [ 0.303030] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5480server # [ 0.303705] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]481server # [ 0.304462] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry482server # [ 0.305470] PCI: Using configuration type 1 for base access483server # [ 0.306605] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.484builder # [ 0.314163] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages485builder # [ 0.315134] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page486server # [ 0.311702] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages487server # [ 0.312459] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page488builder # [ 0.320134] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages489builder # [ 0.321134] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page490server # [ 0.317461] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages491server # [ 0.318458] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page492builder # [ 0.332414] ACPI: Added _OSI(Module Device)493builder # [ 0.333133] ACPI: Added _OSI(Processor Device)494builder # [ 0.333908] ACPI: Added _OSI(Processor Aggregator Device)495server # [ 0.328840] ACPI: Added _OSI(Module Device)496server # [ 0.329460] ACPI: Added _OSI(Processor Device)497server # [ 0.330223] ACPI: Added _OSI(Processor Aggregator Device)498builder # [ 0.339730] ACPI: 1 ACPI AML tables successfully acquired and loaded499builder # [ 0.343593] ACPI: Interpreter enabled500builder # [ 0.344149] ACPI: PM: (supports S0 S3 S4 S5)501builder # [ 0.344868] ACPI: Using IOAPIC for interrupt routing502server # [ 0.339001] ACPI: 1 ACPI AML tables successfully acquired and loaded503builder # [ 0.347187] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug504builder # [ 0.348133] PCI: Using E820 reservations for host bridge windows505server # [ 0.342864] ACPI: Interpreter enabled506server # [ 0.343474] ACPI: PM: (supports S0 S3 S4 S5)507server # [ 0.344233] ACPI: Using IOAPIC for interrupt routing508builder # [ 0.351273] ACPI: Enabled 2 GPEs in block 00 to 3F509server # [ 0.344517] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug510server # [ 0.345459] PCI: Using E820 reservations for host bridge windows511server # [ 0.348591] ACPI: Enabled 2 GPEs in block 00 to 3F512builder # [ 0.358677] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])513builder # [ 0.359138] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]514builder # [ 0.360212] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]515builder # [ 0.361265] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]516builder # [ 0.362469] PCI host bridge to bus 0000:00517server # [ 0.357259] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])518builder # [ 0.363137] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]519server # [ 0.358225] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]520builder # [ 0.364132] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]521builder # [ 0.365132] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]522server # [ 0.360442] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]523builder # [ 0.366133] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]524server # [ 0.361507] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]525builder # [ 0.367133] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]526server # [ 0.362811] PCI host bridge to bus 0000:00527builder # [ 0.368133] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]528server # [ 0.363463] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]529builder # [ 0.369132] pci_bus 0000:00: root bus resource [bus 00-ff]530server # [ 0.364458] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]531builder # [ 0.370134] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint532server # [ 0.365458] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]533server # [ 0.366458] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]534builder # [ 0.371572] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint535server # [ 0.367459] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]536server # [ 0.368459] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]537server # [ 0.369459] pci_bus 0000:00: root bus resource [bus 00-ff]538server # [ 0.370469] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint539server # [ 0.371893] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint540builder # [ 0.374211] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]541builder # [ 0.375212] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]542builder # [ 0.376154] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]543builder # [ 0.377478] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]544builder # [ 0.379083] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint545server # [ 0.374534] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]546server # [ 0.375470] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]547server # [ 0.376547] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]548builder # [ 0.382170] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f]549builder # [ 0.382994] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]550server # [ 0.377813] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]551builder # [ 0.383884] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]552builder # [ 0.384140] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]553server # [ 0.379158] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint554builder # [ 0.385699] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint555server # [ 0.382493] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f]556server # [ 0.383391] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]557builder # [ 0.388102] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f]558server # [ 0.384212] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]559builder # [ 0.388855] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]560server # [ 0.384464] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]561builder # [ 0.389154] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]562builder # [ 0.390680] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint563server # [ 0.386002] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint564server # [ 0.388489] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f]565server # [ 0.389353] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]566builder # [ 0.393162] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf]567server # [ 0.390244] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]568builder # [ 0.394140] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]569builder # [ 0.395119] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]570server # [ 0.391953] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint571builder # [ 0.396615] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint572builder # [ 0.399102] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f]573server # [ 0.394778] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf]574builder # [ 0.399799] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]575server # [ 0.396100] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]576builder # [ 0.400154] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]577server # [ 0.396500] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]578server # [ 0.397998] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint579builder # [ 0.402238] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint580server # [ 0.400273] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f]581builder # [ 0.404992] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf]582server # [ 0.401091] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]583builder # [ 0.405776] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]584server # [ 0.401481] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]585builder # [ 0.406154] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]586builder # [ 0.407699] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint587server # [ 0.403572] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint588server # [ 0.406243] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf]589builder # [ 0.410140] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f]590server # [ 0.407144] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]591builder # [ 0.411792] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]592server # [ 0.407480] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]593builder # [ 0.412154] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]594server # [ 0.409029] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint595builder # [ 0.413770] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint596builder # [ 0.415958] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df]597builder # [ 0.416838] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]598server # [ 0.411841] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f]599builder # [ 0.417154] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]600server # [ 0.413118] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]601server # [ 0.413481] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]602builder # [ 0.418139] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref]603server # [ 0.415074] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint604builder # [ 0.419704] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint605server # [ 0.417159] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df]606builder # [ 0.421146] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]607server # [ 0.417466] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]608builder # [ 0.422138] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]609server # [ 0.418422] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]610builder # [ 0.423687] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint611server # [ 0.419369] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref]612server # [ 0.421018] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint613builder # [ 0.425822] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff]614builder # [ 0.426140] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]615server # [ 0.422472] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]616builder # [ 0.427154] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]617server # [ 0.423464] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]618builder # [ 0.428670] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint619server # [ 0.425025] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint620builder # [ 0.430813] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff]621builder # [ 0.431139] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]622server # [ 0.426497] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff]623builder # [ 0.432115] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]624server # [ 0.427466] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]625server # [ 0.428446] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]626builder # [ 0.433529] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint627server # [ 0.429893] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint628builder # [ 0.434970] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f]629server # [ 0.432147] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff]630builder # [ 0.436031] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint631server # [ 0.432465] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]632builder # [ 0.437791] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f]633server # [ 0.433449] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]634server # [ 0.434833] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint635server # [ 0.436281] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f]636server # [ 0.437338] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint637server # [ 0.439144] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f]638builder # [ 0.438334] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint639builder # [ 0.440932] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f]640builder # [ 0.442061] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint641builder # [ 0.443819] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]642builder # [ 0.444406] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint643server # [ 0.439665] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint644builder # [ 0.445418] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO645server # [ 0.442235] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f]646builder # [ 0.446375] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint647server # [ 0.442723] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint648builder # [ 0.448161] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f]649server # [ 0.444130] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]650builder # [ 0.449034] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]651server # [ 0.444716] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint652builder # [ 0.450449] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint653builder # [ 0.451944] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]654server # [ 0.445739] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO655server # [ 0.446754] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint656builder # [ 0.455729] ACPI: PCI: Interrupt link LNKA configured for IRQ 10657builder # [ 0.456236] ACPI: PCI: Interrupt link LNKB configured for IRQ 10658builder # [ 0.457233] ACPI: PCI: Interrupt link LNKC configured for IRQ 11659builder # [ 0.458231] ACPI: PCI: Interrupt link LNKD configured for IRQ 11660builder # [ 0.459233] ACPI: PCI: Interrupt link LNKE configured for IRQ 10661server # [ 0.451374] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f]662server # [ 0.452158] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]663builder # [ 0.460229] ACPI: PCI: Interrupt link LNKF configured for IRQ 10664server # [ 0.452796] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint665builder # [ 0.461284] ACPI: PCI: Interrupt link LNKG configured for IRQ 11666builder # [ 0.462234] ACPI: PCI: Interrupt link LNKH configured for IRQ 11667server # [ 0.454325] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]668builder # [ 0.463170] ACPI: PCI: Interrupt link GSIA configured for IRQ 16669builder # [ 0.464145] ACPI: PCI: Interrupt link GSIB configured for IRQ 17670server # [ 0.458126] ACPI: PCI: Interrupt link LNKA configured for IRQ 10671builder # [ 0.465161] ACPI: PCI: Interrupt link GSIC configured for IRQ 18672server # [ 0.458571] ACPI: PCI: Interrupt link LNKB configured for IRQ 10673builder # [ 0.466157] ACPI: PCI: Interrupt link GSID configured for IRQ 19674server # [ 0.459557] ACPI: PCI: Interrupt link LNKC configured for IRQ 11675builder # [ 0.467115] ACPI: PCI: Interrupt link GSIE configured for IRQ 20676server # [ 0.460558] ACPI: PCI: Interrupt link LNKD configured for IRQ 11677builder # [ 0.467891] ACPI: PCI: Interrupt link GSIF configured for IRQ 21678server # [ 0.461555] ACPI: PCI: Interrupt link LNKE configured for IRQ 10679builder # [ 0.468146] ACPI: PCI: Interrupt link GSIG configured for IRQ 22680server # [ 0.462554] ACPI: PCI: Interrupt link LNKF configured for IRQ 10681builder # [ 0.469097] ACPI: PCI: Interrupt link GSIH configured for IRQ 23682server # [ 0.463559] ACPI: PCI: Interrupt link LNKG configured for IRQ 11683builder # [ 0.470930] iommu: Default domain type: Translated684server # [ 0.464568] ACPI: PCI: Interrupt link LNKH configured for IRQ 11685builder # [ 0.471728] iommu: DMA domain TLB invalidation policy: lazy mode686server # [ 0.465495] ACPI: PCI: Interrupt link GSIA configured for IRQ 16687builder # [ 0.472406] ACPI: bus type USB registered688server # [ 0.466440] ACPI: PCI: Interrupt link GSIB configured for IRQ 17689builder # [ 0.473136] usbcore: registered new interface driver usbfs690server # [ 0.467189] ACPI: PCI: Interrupt link GSIC configured for IRQ 18691server # [ 0.467472] ACPI: PCI: Interrupt link GSID configured for IRQ 19692builder # [ 0.474083] usbcore: registered new interface driver hub693builder # [ 0.474912] usbcore: registered new device driver usb694server # [ 0.468472] ACPI: PCI: Interrupt link GSIE configured for IRQ 20695server # [ 0.469475] ACPI: PCI: Interrupt link GSIF configured for IRQ 21696builder # [ 0.476003] NetLabel: Initializing697server # [ 0.470470] ACPI: PCI: Interrupt link GSIG configured for IRQ 22698builder # [ 0.476600] NetLabel: domain hash size = 128699server # [ 0.471474] ACPI: PCI: Interrupt link GSIH configured for IRQ 23700builder # [ 0.477132] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO701builder # [ 0.478173] NetLabel: unlabeled traffic allowed by default702server # [ 0.473532] iommu: Default domain type: Translated703builder # [ 0.479152] PCI: Using ACPI for IRQ routing704server # [ 0.474371] iommu: DMA domain TLB invalidation policy: lazy mode705server # [ 0.475519] ACPI: bus type USB registered706server # [ 0.476330] usbcore: registered new interface driver usbfs707server # [ 0.477212] usbcore: registered new interface driver hub708server # [ 0.477474] usbcore: registered new device driver usb709server # [ 0.479192] NetLabel: Initializing710server # [ 0.479464] NetLabel: domain hash size = 128711server # [ 0.480253] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO712server # [ 0.481244] NetLabel: unlabeled traffic allowed by default713server # [ 0.481518] PCI: Using ACPI for IRQ routing714builder # [ 0.522446] pci 0000:00:01.0: vgaarb: setting as boot VGA device715builder # [ 0.523129] pci 0000:00:01.0: vgaarb: bridge control possible716builder # [ 0.523129] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none717builder # [ 0.523137] vgaarb: loaded718builder # [ 0.523907] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0719builder # [ 0.524133] hpet0: 3 comparators, 64-bit 100.000000 MHz counter720builder # [ 0.528222] clocksource: Switched to clocksource kvm-clock721server # [ 0.524363] pci 0000:00:01.0: vgaarb: setting as boot VGA device722server # [ 0.524455] pci 0000:00:01.0: vgaarb: bridge control possible723server # [ 0.524455] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none724server # [ 0.524463] vgaarb: loaded725builder # [ 0.531673] VFS: Disk quotas dquot_6.6.0726server # [ 0.525215] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0727builder # [ 0.532378] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)728builder # [ 0.533770] pnp: PnP ACPI init729server # [ 0.525459] hpet0: 3 comparators, 64-bit 100.000000 MHz counter730builder # [ 0.534546] ACPI: IRQ 4 override to edge(!), high(!)731builder # [ 0.535574] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved732builder # [ 0.537144] pnp: PnP ACPI: found 6 devices733server # [ 0.529549] clocksource: Switched to clocksource kvm-clock734server # [ 0.533271] VFS: Disk quotas dquot_6.6.0735server # [ 0.534027] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)736server # [ 0.535415] pnp: PnP ACPI init737server # [ 0.536245] ACPI: IRQ 4 override to edge(!), high(!)738builder # [ 0.544749] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns739builder # [ 0.546188] clocksource: Switched to clocksource acpi_pm740server # [ 0.537221] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved741builder # [ 0.547185] NET: Registered PF_INET protocol family742server # [ 0.538750] pnp: PnP ACPI: found 6 devices743builder # [ 0.548256] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)744server # [ 0.546332] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns745server # [ 0.547796] clocksource: Switched to clocksource acpi_pm746server # [ 0.548799] NET: Registered PF_INET protocol family747server # [ 0.549845] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)748builder # [ 0.565057] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)749builder # [ 0.566583] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)750builder # [ 0.567971] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)751builder # [ 0.569305] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)752builder # [ 0.570570] TCP: Hash tables configured (established 8192 bind 8192)753builder # [ 0.571758] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)754builder # [ 0.573086] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)755builder # [ 0.574237] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)756builder # [ 0.575475] NET: Registered PF_UNIX/PF_LOCAL protocol family757server # [ 0.566272] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)758builder # [ 0.576499] NET: Registered PF_XDP protocol family759builder # [ 0.577361] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]760server # [ 0.568055] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)761builder # [ 0.578389] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]762server # [ 0.569355] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)763builder # [ 0.579405] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]764server # [ 0.570633] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)765builder # [ 0.580533] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]766server # [ 0.571851] TCP: Hash tables configured (established 8192 bind 8192)767builder # [ 0.581701] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]768server # [ 0.572915] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)769builder # [ 0.582806] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]770server # [ 0.574174] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)771builder # [ 0.584505] ACPI: \_SB_.GSIA: Enabled at IRQ 16772server # [ 0.575280] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)773server # [ 0.576532] NET: Registered PF_UNIX/PF_LOCAL protocol family774server # [ 0.577531] NET: Registered PF_XDP protocol family775builder # [ 0.586832] ACPI: \_SB_.GSIB: Enabled at IRQ 17776server # [ 0.578410] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]777server # [ 0.579468] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]778builder # [ 0.588874] ACPI: \_SB_.GSIC: Enabled at IRQ 18779server # [ 0.580496] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]780server # [ 0.581633] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]781builder # [ 0.591028] ACPI: \_SB_.GSID: Enabled at IRQ 19782server # [ 0.582734] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]783builder # [ 0.592822] PCI: CLS 0 bytes, default 64784server # [ 0.583884] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]785server # [ 0.585572] ACPI: \_SB_.GSIA: Enabled at IRQ 16786builder # [ 0.593841] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns787builder # [ 0.595577] Trying to unpack rootfs image as initramfs...788server # [ 0.587672] ACPI: \_SB_.GSIB: Enabled at IRQ 17789server # [ 0.589671] ACPI: \_SB_.GSIC: Enabled at IRQ 18790server # [ 0.591728] ACPI: \_SB_.GSID: Enabled at IRQ 19791server # [ 0.593544] PCI: CLS 0 bytes, default 64792server # [ 0.594535] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns793server # [ 0.596297] Trying to unpack rootfs image as initramfs...794builder # [ 0.639523] Initialise system trusted keyrings795builder # [ 0.642701] workingset: timestamp_bits=40 max_order=18 bucket_order=0796server # [ 0.640706] Initialise system trusted keyrings797server # [ 0.643832] workingset: timestamp_bits=40 max_order=18 bucket_order=0798builder # [ 0.663813] Key type asymmetric registered799builder # [ 0.664532] Asymmetric key parser 'x509' registered800builder # [ 0.665429] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)801builder # [ 0.670788] io scheduler mq-deadline registered802builder # [ 0.671537] io scheduler kyber registered803server # [ 0.664999] Key type asymmetric registered804server # [ 0.665712] Asymmetric key parser 'x509' registered805builder # [ 0.675178] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled806builder # [ 0.676462] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A807server # [ 0.667852] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)808server # [ 0.670903] io scheduler mq-deadline registered809builder # [ 0.680420] Linux agpgart interface v0.103810server # [ 0.671670] io scheduler kyber registered811builder # [ 0.681206] ACPI: bus type drm_connector registered812builder # [ 0.684108] usbcore: registered new interface driver usbserial_generic813server # [ 0.675309] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled814builder # [ 0.685195] usbserial: USB Serial support registered for generic815server # [ 0.676564] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A816builder # [ 0.688665] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.817server # [ 0.680594] Linux agpgart interface v0.103818builder # [ 0.688665] Please enable it if your BIOS has the CPPC option.819server # [ 0.681351] ACPI: bus type drm_connector registered820builder # [ 0.690895] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled821builder # [ 0.694791] drop_monitor: Initializing network drop monitor service822server # [ 0.686278] usbcore: registered new interface driver usbserial_generic823builder # [ 0.696000] NET: Registered PF_INET6 protocol family824server # [ 0.687475] usbserial: USB Serial support registered for generic825builder # [ 0.699151] Segment Routing with IPv6826server # [ 0.689807] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.827server # [ 0.689807] Please enable it if your BIOS has the CPPC option.828builder # [ 0.701686] In-situ OAM (IOAM) with IPv6829server # [ 0.692093] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled830builder # [ 0.702722] IPI shorthand broadcast: enabled831server # [ 0.695924] drop_monitor: Initializing network drop monitor service832server # [ 0.697136] NET: Registered PF_INET6 protocol family833server # [ 0.701249] Segment Routing with IPv6834builder # [ 0.711006] sched_clock: Marking stable (573013733, 137636447)->(788478967, -77828787)835server # [ 0.703812] In-situ OAM (IOAM) with IPv6836server # [ 0.704851] IPI shorthand broadcast: enabled837builder # [ 0.715904] registered taskstats version 1838builder # [ 0.716866] Loading compiled-in X.509 certificates839server # [ 0.713126] sched_clock: Marking stable (574013892, 138766916)->(794116129, -81335321)840server # [ 0.717879] registered taskstats version 1841server # [ 0.718803] Loading compiled-in X.509 certificates842builder # [ 0.735668] Demotion targets for Node 0: null843builder # [ 0.736518] Key type .fscrypt registered844builder # [ 0.737233] Key type fscrypt-provisioning registered845builder # [ 0.739787] ima: No TPM chip found, activating TPM-bypass!846builder # [ 0.740732] ima: Allocated hash algorithm: sha1847builder # [ 0.741521] ima: No architecture policies found848builder # [ 0.744864] PM: Magic number: 10:711:270849server # [ 0.736797] Demotion targets for Node 0: null850builder # [ 0.746457] RAS: Correctable Errors collector initialized.851server # [ 0.737673] Key type .fscrypt registered852server # [ 0.738329] Key type fscrypt-provisioning registered853server # [ 0.739285] ima: No TPM chip found, activating TPM-bypass!854server # [ 0.743803] ima: Allocated hash algorithm: sha1855server # [ 0.744596] ima: No architecture policies found856server # [ 0.745546] PM: Magic number: 10:711:270857builder # [ 0.757366] clk: Disabling unused clocks858server # [ 0.749217] RAS: Correctable Errors collector initialized.859builder # [ 0.759665] PM: genpd: Disabling unused power domains860server # [ 0.757918] clk: Disabling unused clocks861server # [ 0.758563] PM: genpd: Disabling unused power domains862builder # [ 0.918332] Freeing initrd memory: 29076K863builder # [ 0.921467] Freeing unused decrypted memory: 2028K864builder # [ 0.924091] Freeing unused kernel image (initmem) memory: 3644K865builder # [ 0.925173] Write protecting the kernel read-only data: 32768k866server # [ 0.918218] Freeing initrd memory: 29084K867builder # [ 0.927052] Freeing unused kernel image (text/rodata gap) memory: 1216K868builder # [ 0.928539] Freeing unused kernel image (rodata/data gap) memory: 752K869server # [ 0.921378] Freeing unused decrypted memory: 2028K870server # [ 0.923980] Freeing unused kernel image (initmem) memory: 3644K871server # [ 0.925053] Write protecting the kernel read-only data: 32768k872server # [ 0.926952] Freeing unused kernel image (text/rodata gap) memory: 1216K873server # [ 0.928468] Freeing unused kernel image (rodata/data gap) memory: 752K874builder # [ 0.979897] x86/mm: Checked W+X mappings: passed, no W+X pages found.875builder # [ 0.980948] Run /init as init process876server # [ 0.979875] x86/mm: Checked W+X mappings: passed, no W+X pages found.877server # [ 0.980986] Run /init as init process878builder # [ 0.991064] systemd[1]: Inserted module 'autofs4'879server # [ 0.991072] systemd[1]: Inserted module 'autofs4'880builder # [ 1.007574] fuse: init (API version 7.45)881builder # [ 1.013553] ACPI: \_SB_.GSIG: Enabled at IRQ 22882builder # [ 1.016163] ACPI: \_SB_.GSIH: Enabled at IRQ 23883server # [ 1.007337] fuse: init (API version 7.45)884builder # [ 1.019444] ACPI: \_SB_.GSIE: Enabled at IRQ 20885builder # [ 1.022160] ACPI: \_SB_.GSIF: Enabled at IRQ 21886server # [ 1.013137] ACPI: \_SB_.GSIG: Enabled at IRQ 22887server # [ 1.015517] ACPI: \_SB_.GSIH: Enabled at IRQ 23888server # [ 1.018824] ACPI: \_SB_.GSIE: Enabled at IRQ 20889server # [ 1.020980] ACPI: \_SB_.GSIF: Enabled at IRQ 21890builder # [ 1.051453] systemd[1]: Successfully made /usr/ read-only.891server # [ 1.046622] systemd[1]: Successfully made /usr/ read-only.892builder # [ 1.388127] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)893builder # [ 1.399894] systemd[1]: Detected virtualization kvm.894builder # [ 1.401954] systemd[1]: Detected architecture x86-64.895server # [ 1.383267] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)896builder # [ 1.404050] systemd[1]: Running in initrd.897server # [ 1.395568] systemd[1]: Detected virtualization kvm.898server # [ 1.397705] systemd[1]: Detected architecture x86-64.899builder # [ 1.406439] systemd[1]: Initializing machine ID from random generator.900server # [ 1.399829] systemd[1]: Running in initrd.901builder # [ 1.409295] systemd[1]: Hostname set to <builder>.902server # [ 1.402295] systemd[1]: Initializing machine ID from random generator.903server # [ 1.405157] systemd[1]: Hostname set to <server>.904builder # [ 1.631292] systemd[1]: bpf-restrict-fs: LSM BPF program attached905server # [ 1.624347] systemd[1]: bpf-restrict-fs: LSM BPF program attached906builder # [ 1.685179] systemd[1]: Queued start job for default target Initrd Default Target.907server # [ 1.677969] systemd[1]: Queued start job for default target Initrd Default Target.908builder # [ 1.688908] systemd[1]: Created slice Slice /system/modprobe.909server # [ 1.681056] systemd[1]: Created slice Slice /system/modprobe.910builder # [ 1.690067] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.911builder # [ 1.691426] systemd[1]: Expecting device /dev/disk/by-label/nixos...912server # [ 1.682239] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.913builder # [ 1.692561] systemd[1]: Reached target Path Units.914server # [ 1.683632] systemd[1]: Expecting device /dev/disk/by-label/nixos...915builder # [ 1.693450] systemd[1]: Reached target Slice Units.916server # [ 1.684711] systemd[1]: Reached target Path Units.917builder # [ 1.694345] systemd[1]: Reached target Swaps.918server # [ 1.685549] systemd[1]: Reached target Slice Units.919builder # [ 1.695163] systemd[1]: Reached target Timer Units.920server # [ 1.686470] systemd[1]: Reached target Swaps.921server # [ 1.687295] systemd[1]: Reached target Timer Units.922builder # [ 1.696132] systemd[1]: Listening on D-Bus System Message Bus Socket.923builder # [ 1.697340] systemd[1]: Listening on Journal Socket (/dev/log).924server # [ 1.688253] systemd[1]: Listening on D-Bus System Message Bus Socket.925builder # [ 1.698486] systemd[1]: Listening on Journal Sockets.926server # [ 1.689445] systemd[1]: Listening on Journal Socket (/dev/log).927builder # [ 1.699480] systemd[1]: Listening on udev Control Socket.928server # [ 1.690602] systemd[1]: Listening on Journal Sockets.929builder # [ 1.700544] systemd[1]: Listening on udev Kernel Socket.930server # [ 1.691602] systemd[1]: Listening on udev Control Socket.931builder # [ 1.701506] systemd[1]: Reached target Socket Units.932server # [ 1.692617] systemd[1]: Listening on udev Kernel Socket.933server # [ 1.693572] systemd[1]: Reached target Socket Units.934builder # [ 1.703324] systemd[1]: Starting Create List of Static Device Nodes...935server # [ 1.695391] systemd[1]: Starting Create List of Static Device Nodes...936builder # [ 1.707731] systemd[1]: Starting Load Kernel Module 9pnet_virtio...937server # [ 1.699877] systemd[1]: Starting Load Kernel Module 9pnet_virtio...938builder # [ 1.714725] systemd[1]: Starting Load Kernel Module configfs...939server # [ 1.707846] systemd[1]: Starting Load Kernel Module configfs...940builder # [ 1.729032] systemd[1]: Starting Journal Service...941server # [ 1.721982] systemd[1]: Starting Journal Service...942server # [ 1.732899] systemd[1]: Starting Load Kernel Modules...943builder # [ 1.742780] systemd[1]: Starting Load Kernel Modules...944builder # [ 1.748741] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os945server # [ 1.740853] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os946builder # [ 1.765061] netfs: FS-Cache loaded947server # [ 1.756316] netfs: FS-Cache loaded948server # [ 1.761036] 9pnet: Installing 9P2000 support949builder # [ 1.772233] 9pnet: Installing 9P2000 support950server # [ 1.769536] systemd[1]: Starting Coldplug All udev Devices...951builder # [ 1.784893] systemd[1]: Starting Coldplug All udev Devices...952server # [ 1.782882] systemd[1]: Finished Create List of Static Device Nodes.953builder # [ 1.793897] systemd-journald[67]: Collecting audit messages is disabled.954server # [ 1.788335] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.955builder # [ 1.799199] systemd[1]: Finished Create List of Static Device Nodes.956server # [ 1.796198] systemd[1]: Finished Load Kernel Module 9pnet_virtio.957builder # [ 1.805246] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.958builder # [ 1.811744] systemd[1]: Finished Load Kernel Module 9pnet_virtio.959server # [ 1.803236] systemd[1]: modprobe@configfs.service: Deactivated successfully.960server # [ 1.804875] systemd-journald[67]: Collecting audit messages is disabled.961server # [ 1.808161] systemd[1]: Finished Load Kernel Module configfs.962builder # [ 1.818021] systemd[1]: modprobe@configfs.service: Deactivated successfully.963builder # [ 1.825005] systemd[1]: Finished Load Kernel Module configfs.964server # [ 1.815211] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config965server # [ 1.817786] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.966builder # [ 1.829073] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config967builder # [ 1.831603] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.968server # [ 1.825866] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...969server # [ 1.827338] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev970builder # [ 1.840813] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev971builder # [ 1.842497] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...972server # [ 1.856649] systemd[1]: Finished Load Kernel Modules.973builder # [ 1.872232] systemd[1]: Finished Load Kernel Modules.974server # [ 1.864628] systemd[1]: Starting Apply Kernel Variables...975builder # [ 1.881904] systemd[1]: Starting Apply Kernel Variables...976server # [ 1.874890] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.977builder # [ 1.891776] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.978server # [ 1.883983] systemd[1]: Starting Create Static Device Nodes in /dev...979builder # [ 1.899895] systemd[1]: Starting Create Static Device Nodes in /dev...980server # [ 1.902336] systemd[1]: Finished Apply Kernel Variables.981builder # [ 1.771632] systemd-modules-load[68]: Inserted module 'dm_mod'982builder # [ 1.776308] systemd-modules-load[68]: Inserted module 'virtio_balloon'983builder # [ 1.777300] systemd-modules-load[68]: Inserted module 'virtio_gpu'984builder # [ 1.916005] systemd[1]: Started Journal Service.985server # [ 1.915848] systemd[1]: Finished Create Static Device Nodes in /dev.986builder # [ 1.789071] systemd[1]: Finished Apply Kernel Variables.987server # [ 1.779387] systemd-modules-load[68]: Inserted module 'dm_mod'988server # [ 1.781389] systemd-modules-load[68]: Inserted module 'virtio_balloon'989server # [ 1.921171] systemd[1]: Started Journal Service.990server # [ 1.784347] systemd-modules-load[68]: Inserted module 'virtio_gpu'991builder # [ 1.801228] systemd[1]: Finished Create Static Device Nodes in /dev.992server # [ 1.792742] systemd[1]: Reached target Preparation for Local File Systems.993builder # [ 1.804071] systemd[1]: Reached target Preparation for Local File Systems.994builder # [ 1.804961] systemd[1]: Reached target Local File Systems.995server # [ 1.795712] systemd[1]: Reached target Local File Systems.996server # [ 1.796775] systemd[1]: Starting Create System Files and Directories...997builder # [ 1.807794] systemd[1]: Starting Create System Files and Directories...998server # [ 1.802037] systemd[1]: Starting Rule-based Manager for Device Events and Files...999builder # [ 1.815065] systemd[1]: Starting Rule-based Manager for Device Events and Files...1000server # [ 1.829549] systemd[1]: Finished Create System Files and Directories.1001builder # [ 1.842143] systemd[1]: Finished Create System Files and Directories.1002server # [ 1.849835] systemd-udevd[83]: Using default interface naming scheme 'v261'.1003builder # [ 1.861826] systemd-udevd[83]: Using default interface naming scheme 'v261'.1004server # [ 1.874656] systemd[1]: Started Rule-based Manager for Device Events and Files.1005builder # [ 1.886737] systemd[1]: Started Rule-based Manager for Device Events and Files.1006server # [ 1.915720] systemd[1]: Finished Coldplug All udev Devices.1007server # [ 1.916595] systemd[1]: Reached target System Initialization.1008server # [ 1.918162] systemd[1]: Reached target Basic System.1009builder # [ 1.937927] systemd[1]: Finished Coldplug All udev Devices.1010builder # [ 1.938820] systemd[1]: Reached target System Initialization.1011builder # [ 1.939641] systemd[1]: Reached target Basic System.1012server # [ 2.229078] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121013server # [ 2.246120] virtio_blk virtio5: 1/0/0 default/read/poll queues1014server # [ 2.252485] serio: i8042 KBD port at 0x60,0x64 irq 11015server # [ 2.263664] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1016server # [ 2.266786] serio: i8042 AUX port at 0x60,0x64 irq 121017builder # [ 2.276879] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121018builder # [ 2.284927] virtio_blk virtio5: 1/0/0 default/read/poll queues1019server # [ 2.276083] ehci-pci 0000:00:1d.7: EHCI Host Controller1020server # [ 2.276997] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11021server # [ 2.278672] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001022builder # [ 2.288768] ehci-pci 0000:00:1d.7: EHCI Host Controller1023builder # [ 2.289456] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11024builder # [ 2.291493] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001025builder # [ 2.293386] serio: i8042 KBD port at 0x60,0x64 irq 11026server # [ 2.288502] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001027builder # [ 2.297452] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1028server # [ 2.289479] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181029builder # [ 2.299180] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001030server # [ 2.290643] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11031builder # [ 2.300130] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181032server # [ 2.291617] usb usb1: Product: EHCI Host Controller1033builder # [ 2.302651] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11034builder # [ 2.303594] usb usb1: Product: EHCI Host Controller1035builder # [ 2.304948] serio: i8042 AUX port at 0x60,0x64 irq 121036server # [ 2.296081] usb usb1: Manufacturer: Linux 6.18.49 ehci_hcd1037server # [ 2.296918] usb usb1: SerialNumber: 0000:00:1d.71038builder # [ 2.306026] usb usb1: Manufacturer: Linux 6.18.49 ehci_hcd1039builder # [ 2.307664] usb usb1: SerialNumber: 0000:00:1d.71040server # [ 2.298681] hub 1-0:1.0: USB hub found1041builder # [ 2.308587] hub 1-0:1.0: USB hub found1042server # [ 2.300789] hub 1-0:1.0: 6 ports detected1043builder # [ 2.310087] hub 1-0:1.0: 6 ports detected1044builder # [ 2.312816] uhci_hcd 0000:00:1d.0: UHCI Host Controller1045builder # [ 2.313511] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21046server # [ 2.305797] uhci_hcd 0000:00:1d.0: UHCI Host Controller1047server # [ 2.306625] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21048builder # [ 2.320175] uhci_hcd 0000:00:1d.0: detected 2 ports1049builder # [ 2.326014] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c2001050server # [ 2.318112] uhci_hcd 0000:00:1d.0: detected 2 ports1051server # [ 2.321472] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c2001052builder # [ 2.334878] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181053builder # [ 2.336012] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11054server # [ 2.331382] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181055server # [ 2.332520] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11056builder # [ 2.351722] usb usb2: Product: UHCI Host Controller1057builder # [ 2.352393] usb usb2: Manufacturer: Linux 6.18.49 uhci_hcd1058server # [ 2.346582] usb usb2: Product: UHCI Host Controller1059server # [ 2.347488] usb usb2: Manufacturer: Linux 6.18.49 uhci_hcd1060builder # [ 2.362745] usb usb2: SerialNumber: 0000:00:1d.01061server # [ 2.356845] usb usb2: SerialNumber: 0000:00:1d.01062server # [ 2.358785] SCSI subsystem initialized1063builder # [ 2.234736] (udev-worker)[88]: Network interface NamePolicy= disabled on kernel command line.1064server # [ 2.364087] hub 2-0:1.0: USB hub found1065builder # [ 2.374977] hub 2-0:1.0: USB hub found1066server # [ 2.365965] hub 2-0:1.0: 2 ports detected1067builder # [ 2.378720] hub 2-0:1.0: 2 ports detected1068server # [ 2.231965] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line.1069builder # [ 2.246559] systemd[1]: Starting Virtual Console Setup...1070server # [ 2.375358] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01071server # [ 2.241891] systemd[1]: Starting Virtual Console Setup...1072builder # [ 2.252576] (udev-worker)[87]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1073builder # [ 2.255162] (udev-worker)[87]: Network interface NamePolicy= disabled on kernel command line.1074server # [ 2.385602] uhci_hcd 0000:00:1d.1: UHCI Host Controller1075server # [ 2.386519] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31076builder # [ 2.399480] SCSI subsystem initialized1077builder # [ 2.404042] uhci_hcd 0000:00:1d.1: UHCI Host Controller1078builder # [ 2.404815] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31079builder # [ 2.416261] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01080server # [ 2.276975] (udev-worker)[87]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1081builder # [ 2.288320] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones.1082server # [ 2.278968] (udev-worker)[87]: Network interface NamePolicy= disabled on kernel command line.1083builder # [ 2.292208] systemd[1]: Finished Virtual Console Setup.1084server # [ 2.281364] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.1085server # [ 2.285147] systemd[1]: Finished Virtual Console Setup.1086builder # [ 2.436511] uhci_hcd 0000:00:1d.1: detected 2 ports1087builder # [ 2.437596] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c2201088server # [ 2.429689] uhci_hcd 0000:00:1d.1: detected 2 ports1089server # [ 2.435006] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c2201090builder # [ 2.444568] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181091builder # [ 2.449663] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11092server # [ 2.441116] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181093builder # [ 2.450614] usb usb3: Product: UHCI Host Controller1094server # [ 2.442266] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11095builder # [ 2.454664] usb usb3: Manufacturer: Linux 6.18.49 uhci_hcd1096builder # [ 2.455404] usb usb3: SerialNumber: 0000:00:1d.11097server # [ 2.451280] usb usb3: Product: UHCI Host Controller1098builder # [ 2.460956] hub 3-0:1.0: USB hub found1099builder # [ 2.326493] systemd[1]: Found device /dev/disk/by-label/nixos.1100builder # [ 2.327604] systemd[1]: Reached target Initrd Root Device.1101builder # [ 2.466017] hub 3-0:1.0: 2 ports detected1102server # [ 2.456852] usb usb3: Manufacturer: Linux 6.18.49 uhci_hcd1103server # [ 2.457563] usb usb3: SerialNumber: 0000:00:1d.11104server # [ 2.459092] hub 3-0:1.0: USB hub found1105server # [ 2.321769] systemd[1]: Found device /dev/disk/by-label/nixos.1106builder # [ 2.332067] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1107server # [ 2.322950] systemd[1]: Reached target Initrd Root Device.1108builder # [ 2.475798] uhci_hcd 0000:00:1d.2: UHCI Host Controller1109server # [ 2.328071] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1110builder # [ 2.476515] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41111server # [ 2.468793] hub 3-0:1.0: 2 ports detected1112builder # [ 2.478110] uhci_hcd 0000:00:1d.2: detected 2 ports1113server # [ 2.472239] uhci_hcd 0000:00:1d.2: UHCI Host Controller1114server # [ 2.474834] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41115builder # [ 2.484605] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c2401116builder # [ 2.486927] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181117server # [ 2.481819] uhci_hcd 0000:00:1d.2: detected 2 ports1118builder # [ 2.491664] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11119server # [ 2.482713] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c2401120builder # [ 2.492800] usb usb4: Product: UHCI Host Controller1121builder # [ 2.493527] usb usb4: Manufacturer: Linux 6.18.49 uhci_hcd1122builder # [ 2.496708] usb usb4: SerialNumber: 0000:00:1d.21123server # [ 2.488871] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181124builder # [ 2.498677] hub 4-0:1.0: USB hub found1125server # [ 2.489993] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11126builder # [ 2.367609] systemd-fsck[108]: nixos: clean, 12/65536 files, 13019/262144 blocks1127server # [ 2.497963] usb usb4: Product: UHCI Host Controller1128builder # [ 2.507690] hub 4-0:1.0: 2 ports detected1129server # [ 2.498611] usb usb4: Manufacturer: Linux 6.18.49 uhci_hcd1130server # [ 2.502406] usb usb4: SerialNumber: 0000:00:1d.21131builder # [ 2.373728] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1132server # [ 2.365791] systemd-fsck[108]: nixos: clean, 12/65536 files, 13019/262144 blocks1133server # [ 2.506885] hub 4-0:1.0: USB hub found1134server # [ 2.507674] hub 4-0:1.0: 2 ports detected1135server # [ 2.372849] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1136server # [ 2.525343] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1137server # [ 2.526442] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1138builder # [ 2.536306] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1139builder # [ 2.537441] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1140builder # [ 2.542543] ahci 0000:00:1f.2: flags: 64bit ncq only1141server # [ 2.536861] usb 1-1: new high-speed USB device number 2 using ehci-pci1142builder # [ 2.546305] scsi host0: ahci1143server # [ 2.538272] ahci 0000:00:1f.2: flags: 64bit ncq only1144builder # [ 2.548221] scsi host1: ahci1145builder # [ 2.549935] scsi host2: ahci1146builder # [ 2.551605] scsi host3: ahci1147builder # [ 2.552110] usb 1-1: new high-speed USB device number 2 using ehci-pci1148builder # [ 2.554387] scsi host4: ahci1149builder # [ 2.556133] scsi host5: ahci1150builder # [ 2.557330] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 11151server # [ 2.549710] scsi host0: ahci1152builder # [ 2.558781] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 11153server # [ 2.551460] scsi host1: ahci1154builder # [ 2.560057] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 11155builder # [ 2.561277] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 11156builder # [ 2.562437] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 11157server # [ 2.554324] scsi host2: ahci1158builder # [ 2.563590] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 11159server # [ 2.556734] scsi host3: ahci1160server # [ 2.558850] scsi host4: ahci1161server # [ 2.560919] scsi host5: ahci1162server # [ 2.561444] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 11163server # [ 2.565072] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 11164server # [ 2.566989] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 11165server # [ 2.568168] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 11166server # [ 2.569303] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 11167server # [ 2.570440] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 11168server # [ 2.666930] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001169server # [ 2.668067] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101170server # [ 2.669419] usb 1-1: Product: QEMU USB Tablet1171server # [ 2.670129] usb 1-1: Manufacturer: QEMU1172server # [ 2.670656] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11173builder # [ 2.680966] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001174builder # [ 2.683767] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101175builder # [ 2.686931] usb 1-1: Product: QEMU USB Tablet1176builder # [ 2.688720] usb 1-1: Manufacturer: QEMU1177builder # [ 2.690292] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11178server # [ 2.684397] hid: raw HID events driver (C) Jiri Kosina1179builder # [ 2.720534] hid: raw HID events driver (C) Jiri Kosina1180server # [ 2.639585] systemd[1]: Mounting /sysroot...1181builder # [ 2.657696] systemd[1]: Mounting /sysroot...1182builder # [ 2.870765] ata1: SATA link down (SStatus 0 SControl 300)1183builder # [ 2.877751] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1184builder # [ 2.880000] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001185builder # [ 2.882167] ata3.00: applying bridge limits1186builder # [ 2.883993] ata3.00: configured for UDMA/1001187builder # [ 2.886081] ata5: SATA link down (SStatus 0 SControl 300)1188server # [ 2.879051] ata6: SATA link down (SStatus 0 SControl 300)1189server # [ 2.880011] ata2: SATA link down (SStatus 0 SControl 300)1190builder # [ 2.888305] ata6: SATA link down (SStatus 0 SControl 300)1191server # [ 2.881098] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1192server # [ 2.882040] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001193builder # [ 2.890587] ata2: SATA link down (SStatus 0 SControl 300)1194server # [ 2.882884] ata3.00: applying bridge limits1195server # [ 2.883543] ata5: SATA link down (SStatus 0 SControl 300)1196server # [ 2.884486] ata4: SATA link down (SStatus 0 SControl 300)1197builder # [ 2.892935] ata4: SATA link down (SStatus 0 SControl 300)1198server # [ 2.885386] ata1: SATA link down (SStatus 0 SControl 300)1199server # [ 2.886325] ata3.00: configured for UDMA/1001200builder # [ 2.895228] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51201server # [ 2.887400] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51202server # [ 2.941551] usbcore: registered new interface driver usbhid1203server # [ 2.947774] usbhid: USB HID core driver1204builder # [ 2.958870] usbcore: registered new interface driver usbhid1205builder # [ 2.959611] usbhid: USB HID core driver1206server # [ 2.966945] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1207server # [ 2.969437] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input21208builder # [ 2.979832] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input21209server # [ 2.973046] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01210builder # [ 2.984215] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01211builder # [ 2.988792] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1212server # [ 2.981889] cdrom: Uniform CD-ROM driver Revision: 3.201213builder # [ 2.999819] cdrom: Uniform CD-ROM driver Revision: 3.201214builder # [ 3.000952] EXT4-fs (vda): mounted filesystem 5b2f16f1-113b-4ee9-862b-8496fd52187e r/w with ordered data mode. Quota mode: none.1215server # [ 2.992400] EXT4-fs (vda): mounted filesystem 87bc6cf8-ab3e-4bef-bd27-9fae73b845f7 r/w with ordered data mode. Quota mode: none.1216builder # [ 2.868795] systemd[1]: Mounted /sysroot.1217server # [ 2.860339] systemd[1]: Mounted /sysroot.1218builder # [ 2.871373] systemd[1]: Reached target Initrd Root File System.1219server # [ 2.862076] systemd[1]: Reached target Initrd Root File System.1220builder # [ 2.874754] systemd[1]: Mounting /sysroot/nix/.ro-store...1221server # [ 2.867069] systemd[1]: Mounting /sysroot/nix/.ro-store...1222server # [ 2.871893] systemd[1]: Mounting /sysroot/nix/.rw-store...1223builder # [ 2.882518] systemd[1]: Mounting /sysroot/nix/.rw-store...1224builder # [ 2.885416] systemd[1]: Mounting /sysroot/run...1225server # [ 2.878148] systemd[1]: Mounting /sysroot/run...1226builder # [ 2.888890] systemd[1]: Mounting /sysroot/tmp/shared...1227server # [ 2.882147] systemd[1]: Mounting /sysroot/tmp/shared...1228builder # [ 2.897063] systemd[1]: Mounting /sysroot/tmp/xchg...1229server # [ 2.890830] systemd[1]: Mounting /sysroot/tmp/xchg...1230builder # [ 2.910679] systemd[1]: Starting Mountpoints Configured in the Real Root...1231server # [ 2.900536] systemd[1]: Starting Mountpoints Configured in the Real Root...1232server # [ 3.071507] 9p: Installing v9fs 9p2000 file system support1233builder # [ 3.082424] 9p: Installing v9fs 9p2000 file system support1234builder # [ 2.948049] systemd-sysroot-fstab-check[146]: /sysroot should be mounted in the initrd, will request daemon-reload.1235builder # [ 2.955494] systemd[1]: Mounted /sysroot/nix/.rw-store.1236builder # [ 2.956277] systemd[1]: Mounted /sysroot/run.1237server # [ 2.951078] systemd-sysroot-fstab-check[147]: /sysroot should be mounted in the initrd, will request daemon-reload.1238server # [ 2.952704] systemd[1]: Mounted /sysroot/nix/.rw-store.1239server # [ 2.955065] systemd[1]: Mounted /sysroot/nix/.ro-store.1240server # [ 2.956261] systemd[1]: Mounted /sysroot/run.1241builder # [ 2.967342] systemd[1]: Mounted /sysroot/nix/.ro-store.1242server # [ 2.957462] systemd[1]: Mounted /sysroot/tmp/shared.1243builder # [ 2.968310] systemd[1]: Mounted /sysroot/tmp/shared.1244server # [ 2.959185] systemd[1]: Mounted /sysroot/tmp/xchg.1245builder # [ 2.970281] systemd[1]: Mounted /sysroot/tmp/xchg.1246builder # [ 2.975628] systemd[1]: Starting rw-sysroot-nix-store.service...1247server # [ 2.965277] systemd[1]: Reload requested from client PID 147 ('systemd-sysroot') (unit initrd-parse-etc.service)...1248server # [ 2.967082] systemd[1]: Reloading...1249builder # [ 2.981562] systemd[1]: Reload requested from client PID 146 ('systemd-sysroot') (unit initrd-parse-etc.service)...1250builder # [ 2.985091] systemd[1]: Reloading...1251server # [ 3.035176] systemd[1]: Reloading finished in 67 ms.1252builder # [ 3.050447] systemd[1]: Reloading finished in 66 ms.1253server # [ 3.043800] systemd-sysroot-fstab-check[147]: Requesting initrd-fs.target/start/replace...1254server # [ 3.047180] systemd-sysroot-fstab-check[147]: Requesting swap.target/start/replace...1255builder # [ 3.059249] systemd-sysroot-fstab-check[146]: Requesting initrd-fs.target/start/replace...1256server # [ 3.049633] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1257server # [ 3.050975] systemd[1]: Finished Mountpoints Configured in the Real Root.1258builder # [ 3.061276] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1259server # [ 3.052061] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1260builder # [ 3.062625] systemd[1]: Finished rw-sysroot-nix-store.service.1261builder # [ 3.064627] systemd-sysroot-fstab-check[146]: Requesting swap.target/start/replace...1262server # [ 3.055668] systemd[1]: Starting rw-sysroot-nix-store.service...1263builder # [ 3.068640] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1264builder # [ 3.069971] systemd[1]: Finished Mountpoints Configured in the Real Root.1265builder # [ 3.070962] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1266builder # [ 3.073125] systemd[1]: Starting rw-sysroot-nix-store.service...1267server # [ 3.066939] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1268server # [ 3.068713] systemd[1]: Finished rw-sysroot-nix-store.service.1269builder # [ 3.083633] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1270builder # [ 3.085070] systemd[1]: Finished rw-sysroot-nix-store.service.1271server # [ 3.640981] systemd[1]: Mounting /sysroot/nix/store...1272builder # [ 3.657754] systemd[1]: Mounting /sysroot/nix/store...1273server # [ 3.687219] systemd[1]: Mounted /sysroot/nix/store.1274server # [ 3.689789] systemd[1]: Reached target Initrd File Systems.1275builder # [ 3.702187] systemd[1]: Mounted /sysroot/nix/store.1276server # [ 3.694137] systemd[1]: Starting Find NixOS closure...1277builder # [ 3.704759] systemd[1]: Reached target Initrd File Systems.1278server # [ 3.697972] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1279builder # [ 3.709150] systemd[1]: Starting Find NixOS closure...1280builder # [ 3.713095] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1281server # [ 3.723727] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1282server # [ 3.727532] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1283builder # [ 3.739932] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1284builder # [ 3.742171] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1285server # [ 3.733957] systemd[1]: Finished Find NixOS closure.1286server # [ 3.735154] systemd[1]: Reached target Initrd Default Target.1287server # [ 3.736900] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1288builder # [ 3.749705] systemd[1]: Finished Find NixOS closure.1289builder # [ 3.750933] systemd[1]: Reached target Initrd Default Target.1290builder # [ 3.752814] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1291server # [ 3.751108] systemd[1]: Stopped target Initrd Default Target.1292server # [ 3.752331] systemd[1]: Stopped target Basic System.1293server # [ 3.754127] systemd[1]: Stopped target Initrd Root Device.1294server # [ 3.754866] systemd[1]: Stopped target Path Units.1295server # [ 3.755605] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1296builder # [ 3.766548] systemd[1]: Stopped target Initrd Default Target.1297server # [ 3.756608] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1298builder # [ 3.767652] systemd[1]: Stopped target Basic System.1299server # [ 3.757796] systemd[1]: Stopped target Slice Units.1300builder # [ 3.768591] systemd[1]: Stopped target Initrd Root Device.1301server # [ 3.758540] systemd[1]: Stopped target Socket Units.1302server # [ 3.759340] systemd[1]: Stopped target System Initialization.1303builder # [ 3.770151] systemd[1]: Stopped target Path Units.1304server # [ 3.760423] systemd[1]: Stopped target Swaps.1305builder # [ 3.770830] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1306server # [ 3.761277] systemd[1]: Stopped target Timer Units.1307builder # [ 3.771931] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1308server # [ 3.762419] systemd[1]: dbus.socket: Deactivated successfully.1309builder # [ 3.772948] systemd[1]: Stopped target Slice Units.1310builder # [ 3.773697] systemd[1]: Stopped target Socket Units.1311server # [ 3.763480] systemd[1]: Closed D-Bus System Message Bus Socket.1312builder # [ 3.774710] systemd[1]: Stopped target System Initialization.1313server # [ 3.764418] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1314builder # [ 3.775774] systemd[1]: Stopped target Swaps.1315server # [ 3.766140] systemd[1]: Stopped Find NixOS closure.1316builder # [ 3.776916] systemd[1]: Stopped target Timer Units.1317server # [ 3.766842] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1318builder # [ 3.777805] systemd[1]: dbus.socket: Deactivated successfully.1319builder # [ 3.778837] systemd[1]: Closed D-Bus System Message Bus Socket.1320server # [ 3.768711] systemd[1]: Starting rw-sysroot-nix-store.service...1321builder # [ 3.780160] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1322server # [ 3.770187] systemd[1]: systemd-sysctl.service: Deactivated successfully.1323builder # [ 3.781223] systemd[1]: Stopped Find NixOS closure.1324server # [ 3.771228] systemd[1]: Stopped Apply Kernel Variables.1325server # [ 3.772229] systemd[1]: systemd-modules-load.service: Deactivated successfully.1326builder # [ 3.782126] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1327builder # [ 3.784157] systemd[1]: Starting rw-sysroot-nix-store.service...1328server # [ 3.774151] systemd[1]: Stopped Load Kernel Modules.1329builder # [ 3.785050] systemd[1]: systemd-sysctl.service: Deactivated successfully.1330server # [ 3.775173] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1331server # [ 3.776391] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1332builder # [ 3.787112] systemd[1]: Stopped Apply Kernel Variables.1333server # [ 3.777727] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1334builder # [ 3.788117] systemd[1]: systemd-modules-load.service: Deactivated successfully.1335builder # [ 3.789324] systemd[1]: Stopped Load Kernel Modules.1336server # [ 3.779125] systemd[1]: Stopped Create System Files and Directories.1337server # [ 3.780059] systemd[1]: Stopped target Local File Systems.1338builder # [ 3.790268] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1339builder # [ 3.791939] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1340server # [ 3.782125] systemd[1]: Stopped target Preparation for Local File Systems.1341server # [ 3.783060] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1342builder # [ 3.793682] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1343server # [ 3.784159] systemd[1]: Stopped Coldplug All udev Devices.1344builder # [ 3.794674] systemd[1]: Stopped Create System Files and Directories.1345builder # [ 3.795545] systemd[1]: Stopped target Local File Systems.1346server # [ 3.786196] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1347builder # [ 3.797475] systemd[1]: Stopped target Preparation for Local File Systems.1348server # [ 3.787213] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1349server # [ 3.788198] systemd[1]: Stopped Virtual Console Setup.1350builder # [ 3.798366] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1351builder # [ 3.799324] systemd[1]: Stopped Coldplug All udev Devices.1352builder # [ 3.802359] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1353builder # [ 3.803357] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1354builder # [ 3.804326] systemd[1]: Stopped Virtual Console Setup.1355server # [ 3.797375] systemd[1]: initrd-cleanup.service: Deactivated successfully.1356server # [ 3.799158] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1357builder # [ 3.813395] systemd[1]: initrd-cleanup.service: Deactivated successfully.1358builder # [ 3.815133] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1359builder # [ 3.815989] systemd[1]: systemd-udevd.service: Deactivated successfully.1360server # [ 3.808673] systemd[1]: systemd-udevd.service: Deactivated successfully.1361builder # [ 3.819713] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1362server # [ 3.811074] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1363server # [ 3.812129] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1364server # [ 3.813251] systemd[1]: Closed udev Control Socket.1365builder # [ 3.823419] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1366builder # [ 3.824792] systemd[1]: Finished rw-sysroot-nix-store.service.1367server # [ 3.814889] systemd[1]: Starting Cleanup udev Database...1368server # [ 3.815828] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1369builder # [ 3.826961] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1370builder # [ 3.828059] systemd[1]: Closed udev Control Socket.1371server # [ 3.818153] systemd[1]: Stopped Create Static Device Nodes in /dev.1372builder # [ 3.828964] systemd[1]: Starting Cleanup udev Database...1373builder # [ 3.830157] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1374server # [ 3.820188] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1375builder # [ 3.831174] systemd[1]: Stopped Create Static Device Nodes in /dev.1376server # [ 3.821365] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1377server # [ 3.822430] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1378builder # [ 3.833129] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1379server # [ 3.823481] systemd[1]: Stopped Create List of Static Device Nodes.1380builder # [ 3.834178] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1381server # [ 3.824441] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1382builder # [ 3.835095] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1383builder # [ 3.835969] systemd[1]: Stopped Create List of Static Device Nodes.1384server # [ 3.826269] systemd[1]: Finished rw-sysroot-nix-store.service.1385builder # [ 3.849306] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1386builder # [ 3.850811] systemd[1]: Finished Cleanup udev Database.1387builder # [ 3.852313] systemd[1]: Reached target Switch Root.1388builder # [ 3.854085] systemd[1]: Starting NixOS Activation...1389server # [ 3.846252] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1390server # [ 3.847608] systemd[1]: Finished Cleanup udev Database.1391server # [ 3.848956] systemd[1]: Reached target Switch Root.1392server # [ 3.850790] systemd[1]: Starting NixOS Activation...1393builder # [ 3.997899] initrd-nixos-activation-start[197]: booting system configuration /nix/store/yja6sry4k4j8mnf9ynxn8yqb2ns6lbpl-nixos-system-builder-test1394server # [ 4.014551] initrd-nixos-activation-start[195]: booting system configuration /nix/store/d4bmp513a2vng4j49gqm195hzj9xlhki-nixos-system-server-test1395builder # [ 4.055710] initrd-nixos-activation-start[197]: running activation script...1396server # [ 4.072730] initrd-nixos-activation-start[195]: running activation script...1397builder # [ 4.412250] initrd-nixos-activation-start[220]: setting up /etc...1398server # [ 4.452039] initrd-nixos-activation-start[218]: setting up /etc...1399builder # [ 4.661843] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1400builder # [ 4.664114] systemd[1]: Finished NixOS Activation.1401builder # [ 4.665303] systemd[1]: Starting Switch Root...1402builder # [ 4.677062] systemd[1]: Switching root.1403server # [ 4.712152] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1404server # [ 4.714064] systemd[1]: Finished NixOS Activation.1405server # [ 4.715527] systemd[1]: Starting Switch Root...1406server # [ 4.727936] systemd[1]: Switching root.1407builder # [ 4.943464] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).1408server # [ 4.989887] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).1409builder # [ 5.084370] NET: Registered PF_VSOCK protocol family1410server # [ 5.093326] NET: Registered PF_VSOCK protocol family1411builder # [ 5.466516] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1412builder # [ 5.475790] systemd[1]: Detected virtualization kvm.1413builder # [ 5.477522] systemd[1]: Detected architecture x86-64.1414builder # [ 5.479379] systemd[1]: Detected first boot.1415server # [ 5.467859] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1416builder # [ 5.485702] systemd[1]: Initializing machine ID from random generator.1417server # [ 5.477145] systemd[1]: Detected virtualization kvm.1418server # [ 5.478837] systemd[1]: Detected architecture x86-64.1419server # [ 5.480647] systemd[1]: Detected first boot.1420server # [ 5.486173] systemd[1]: Initializing machine ID from random generator.1421server # [ 5.620910] systemd[1]: bpf-restrict-fs: LSM BPF program attached1422builder # [ 5.744084] systemd[1]: bpf-restrict-fs: LSM BPF program attached1423server # [ 5.739901] systemd[1]: Applying preset policy.1424builder # [ 5.872294] systemd[1]: Applying preset policy.1425server # [ 6.177668] systemd[1]: Populated /etc with preset unit settings.1426builder # [ 6.285927] systemd[1]: Populated /etc with preset unit settings.1427server # [ 6.630991] systemd[1]: initrd-switch-root.service: Deactivated successfully.1428server # [ 6.632319] systemd[1]: Stopped initrd-switch-root.service.1429server # [ 6.634954] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1430server # [ 6.637202] systemd[1]: Created slice Slice /system/getty.1431server # [ 6.638674] systemd[1]: Created slice User and Session Slice.1432server # [ 6.639719] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1433server # [ 6.641067] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1434server # [ 6.642241] systemd[1]: Expecting device /dev/hvc0...1435server # [ 6.643008] systemd[1]: Expecting device /dev/ttyS0...1436server # [ 6.643804] systemd[1]: Reached target Local Encrypted Volumes.1437server # [ 6.644575] systemd[1]: Stopped target initrd-fs.target.1438server # [ 6.645422] systemd[1]: Stopped target initrd-root-fs.target.1439server # [ 6.646281] systemd[1]: Stopped target initrd-switch-root.target.1440server # [ 6.647137] systemd[1]: Reached target Virtual Machines and Containers.1441server # [ 6.648066] systemd[1]: Reached target Path Units.1442server # [ 6.648770] systemd[1]: Reached target Remote File Systems.1443server # [ 6.649535] systemd[1]: Reached target Slice Units.1444server # [ 6.661442] systemd[1]: Reached target Swaps.1445server # [ 6.664597] systemd[1]: Listening on Query the User Interactively for a Password.1446server # [ 6.668119] systemd[1]: Listening on Process Core Dump Socket.1447server # [ 6.671007] systemd[1]: Listening on Credential Encryption/Decryption.1448server # [ 6.673670] systemd[1]: Listening on Factory Reset Management.1449server # [ 6.674553] systemd[1]: Listening on Hostname Service Socket.1450server # [ 6.678537] systemd[1]: Starting Journal Log Access Socket...1451server # [ 6.680020] systemd[1]: Listening on Journal Audit Socket.1452server # [ 6.683411] systemd[1]: Listening on Console Output Muting Service Socket.1453server # [ 6.684506] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1454server # [ 6.685788] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1455server # [ 6.687060] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1456server # [ 6.694164] systemd[1]: Listening on Disk Repartitioning Service Socket.1457server # [ 6.695164] systemd[1]: Listening on udev Control Socket.1458server # [ 6.696056] systemd[1]: Listening on udev Varlink Socket.1459server # [ 6.699103] systemd[1]: Mounting Huge Pages File System...1460server # [ 6.702222] systemd[1]: Mounting POSIX Message Queue File System...1461server # [ 6.707804] systemd[1]: Mounting Kernel Debug File System...1462builder # [ 6.721830] systemd[1]: initrd-switch-root.service: Deactivated successfully.1463server # [ 6.713913] systemd[1]: Mounting Kernel Trace File System...1464builder # [ 6.723170] systemd[1]: Stopped initrd-switch-root.service.1465builder # [ 6.725797] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1466builder # [ 6.727919] systemd[1]: Created slice Slice /system/getty.1467server # [ 6.719112] systemd[1]: Starting Create List of Static Device Nodes...1468builder # [ 6.729249] systemd[1]: Created slice User and Session Slice.1469builder # [ 6.730157] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1470builder # [ 6.731315] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1471server # [ 6.721890] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1472builder # [ 6.732406] systemd[1]: Expecting device /dev/hvc0...1473builder # [ 6.733154] systemd[1]: Expecting device /dev/ttyS0...1474builder # [ 6.733900] systemd[1]: Reached target Local Encrypted Volumes.1475builder # [ 6.734703] systemd[1]: Stopped target initrd-fs.target.1476builder # [ 6.735406] systemd[1]: Stopped target initrd-root-fs.target.1477builder # [ 6.736185] systemd[1]: Stopped target initrd-switch-root.target.1478builder # [ 6.737056] systemd[1]: Reached target Virtual Machines and Containers.1479builder # [ 6.737945] systemd[1]: Reached target Path Units.1480builder # [ 6.738596] systemd[1]: Reached target Remote File Systems.1481builder # [ 6.739379] systemd[1]: Reached target Slice Units.1482builder # [ 6.740085] systemd[1]: Reached target Swaps.1483server # [ 6.733910] systemd[1]: Starting Load Kernel Module configfs...1484server # [ 6.734716] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1485server # [ 6.736889] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1486server # [ 6.738502] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1487builder # [ 6.742766] systemd[1]: Listening on Query the User Interactively for a Password.1488server # [ 6.747804] systemd[1]: Mounting FUSE Control File System...1489builder # [ 6.757560] systemd[1]: Listening on Process Core Dump Socket.1490server # [ 6.748603] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671491builder # [ 6.760199] systemd[1]: Listening on Credential Encryption/Decryption.1492builder # [ 6.762805] systemd[1]: Listening on Factory Reset Management.1493builder # [ 6.763692] systemd[1]: Listening on Hostname Service Socket.1494builder # [ 6.767486] systemd[1]: Starting Journal Log Access Socket...1495builder # [ 6.768978] systemd[1]: Listening on Journal Audit Socket.1496builder # [ 6.772051] systemd[1]: Listening on Console Output Muting Service Socket.1497builder # [ 6.773472] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1498builder # [ 6.774832] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1499builder # [ 6.776132] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1500builder # [ 6.783960] systemd[1]: Listening on Disk Repartitioning Service Socket.1501builder # [ 6.784964] systemd[1]: Listening on udev Control Socket.1502builder # [ 6.785849] systemd[1]: Listening on udev Varlink Socket.1503builder # [ 6.788885] systemd[1]: Mounting Huge Pages File System...1504builder # [ 6.792723] systemd[1]: Mounting POSIX Message Queue File System...1505server # [ 6.784322] systemd[1]: Starting Journal Service...1506builder # [ 6.798160] systemd[1]: Mounting Kernel Debug File System...1507builder # [ 6.805173] systemd[1]: Mounting Kernel Trace File System...1508server # [ 6.798714] systemd[1]: Starting Load Kernel Modules...1509builder # [ 6.810422] systemd[1]: Starting Create List of Static Device Nodes...1510builder # [ 6.813496] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1511server # [ 6.806958] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1512server # [ 6.813832] systemd[1]: Starting Remount Root and Kernel File Systems...1513builder # [ 6.824059] systemd[1]: Starting Load Kernel Module configfs...1514server # [ 6.814706] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1515builder # [ 6.825757] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1516builder # [ 6.827250] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1517builder # [ 6.829704] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1518server # [ 6.821826] systemd[1]: Starting Coldplug All udev Devices...1519server # [ 6.826918] systemd[1]: Listening on Journal Log Access Socket.1520server # [ 6.828930] systemd[1]: Mounted Huge Pages File System.1521server # [ 6.830424] systemd[1]: Mounted POSIX Message Queue File System.1522server # [ 6.832973] systemd[1]: Mounted Kernel Debug File System.1523builder # [ 6.843075] systemd[1]: Mounting FUSE Control File System...1524server # [ 6.834601] systemd[1]: Mounted Kernel Trace File System.1525server # [ 6.836524] systemd[1]: Mounted FUSE Control File System.1526builder # [ 6.847716] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671527server # [ 6.844665] systemd[1]: Finished Create List of Static Device Nodes.1528server # [ 6.849827] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1529builder # [ 6.871056] systemd[1]: Starting Journal Service...1530builder # [ 6.879950] systemd[1]: Starting Load Kernel Modules...1531server # [ 6.884905] systemd[1]: modprobe@configfs.service: Deactivated successfully.1532builder # [ 6.894717] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1533server # [ 6.887363] systemd[1]: Finished Load Kernel Module configfs.1534server # [ 6.892547] systemd[1]: Mounting Kernel Configuration File System...1535builder # [ 6.901803] systemd[1]: Starting Remount Root and Kernel File Systems...1536builder # [ 6.903706] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1537builder # [ 6.910931] systemd[1]: Starting Coldplug All udev Devices...1538builder # [ 6.915430] systemd[1]: Listening on Journal Log Access Socket.1539builder # [ 6.918001] systemd[1]: Mounted Huge Pages File System.1540builder # [ 6.919811] systemd[1]: Mounted POSIX Message Queue File System.1541builder # [ 6.921971] systemd[1]: Mounted Kernel Debug File System.1542builder # [ 6.923852] systemd[1]: Mounted Kernel Trace File System.1543builder # [ 6.925530] systemd[1]: Mounted FUSE Control File System.1544server # [ 6.919109] EXT4-fs (vda): re-mounted 87bc6cf8-ab3e-4bef-bd27-9fae73b845f7.1545builder # [ 6.929334] systemd[1]: Finished Create List of Static Device Nodes.1546server # [ 6.921777] systemd[1]: Mounted Kernel Configuration File System.1547builder # [ 6.936487] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1548server # [ 6.934216] systemd[1]: Finished Remount Root and Kernel File Systems.1549server # [ 6.935420] systemd[1]: Listening on Disk Image Download Service Socket.1550server # [ 6.937842] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1551server # [ 6.942860] systemd[1]: Starting Load/Save OS Random Seed...1552server # [ 6.943613] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1553server # [ 6.947022] systemd-journald[288]: Collecting audit messages is enabled.1554builder # [ 6.972500] systemd[1]: modprobe@configfs.service: Deactivated successfully.1555builder # [ 6.973865] systemd[1]: Finished Load Kernel Module configfs.1556server # [ 6.965324] loop: module loaded1557builder # [ 6.979140] systemd[1]: Mounting Kernel Configuration File System...1558server # [ 6.985544] systemd[1]: Started Journal Service.1559server # [ 6.853479] systemd[1]: Queued start job for default target Multi-User System.1560server # [ 6.855079] systemd[1]: systemd-journald.service: Deactivated successfully.1561builder # [ 7.004935] EXT4-fs (vda): re-mounted 5b2f16f1-113b-4ee9-862b-8496fd52187e.1562builder # [ 7.007179] systemd[1]: Mounted Kernel Configuration File System.1563server # [ 6.860421] systemd-modules-load[289]: Inserted module 'loop'1564server # [ 6.865690] systemd[1]: Starting Flush Journal to Persistent Storage...1565builder # [ 7.019555] systemd[1]: Finished Remount Root and Kernel File Systems.1566builder # [ 7.020800] systemd[1]: Listening on Disk Image Download Service Socket.1567builder # [ 7.022589] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1568server # [ 6.879653] systemd-modules-load[289]: Inserted module 'tls'1569builder # [ 7.032862] systemd[1]: Starting Load/Save OS Random Seed...1570builder # [ 7.034402] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1571server # [ 7.028396] tun: Universal TUN/TAP device driver, 1.61572builder # [ 7.038651] systemd-journald[290]: Collecting audit messages is enabled.1573server # [ 6.895251] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1574server # [ 6.898960] systemd[1]: Starting Create Static Device Nodes in /dev...1575server # [ 6.900153] systemd[1]: Finished Load/Save OS Random Seed.1576builder # [ 7.048852] loop: module loaded1577server # [ 6.903310] systemd[1]: Reached target First Boot Complete.1578server # [ 6.904289] systemd-modules-load[289]: Inserted module 'tun'1579server # [ 6.911935] systemd[1]: Finished Load Kernel Modules.1580server # [ 6.915090] systemd[1]: Starting Firewall...1581builder # [ 7.063796] tun: Universal TUN/TAP device driver, 1.61582server # [ 6.919616] systemd[1]: Starting Apply Kernel Variables...1583builder # [ 7.075811] systemd[1]: Finished Load Kernel Modules.1584server # [ 6.927804] systemd-oomd[290]: No swap; memory pressure usage will be degraded1585builder # [ 7.079071] systemd[1]: Starting Firewall...1586builder # [ 7.083488] systemd[1]: Starting Apply Kernel Variables...1587builder # [ 7.085178] systemd[1]: Started Journal Service.1588builder # [ 6.948544] systemd[1]: Queued start job for default target Multi-User System.1589server # [ 6.941111] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1590builder # [ 6.952642] systemd[1]: systemd-journald.service: Deactivated successfully.1591server # [ 7.088467] systemd-journald[288]: Received client request to flush runtime journal.1592builder # [ 6.962331] systemd-modules-load[291]: Inserted module 'loop'1593builder # [ 6.963748] systemd-modules-load[291]: Inserted module 'tun'1594builder # [ 6.969135] systemd[1]: Starting Flush Journal to Persistent Storage...1595builder # [ 6.992256] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1596builder # [ 6.998292] systemd[1]: Starting Create Static Device Nodes in /dev...1597builder # [ 6.999426] systemd[1]: Finished Load/Save OS Random Seed.1598builder # [ 7.001188] systemd[1]: Reached target First Boot Complete.1599builder # [ 7.030097] systemd-oomd[292]: No swap; memory pressure usage will be degraded1600builder # [ 7.179120] systemd-journald[290]: Received client request to flush runtime journal.1601server # [ 7.095962] systemd[1]: Finished Create Static Device Nodes in /dev.1602server # [ 7.100458] systemd[1]: Finished Apply Kernel Variables.1603server # [ 7.101523] systemd[1]: Reached target Preparation for Local File Systems.1604server # [ 7.102565] systemd[1]: Starting Rule-based Manager for Device Events and Files...1605server # [ 7.105571] systemd[1]: Finished Flush Journal to Persistent Storage.1606server # [ 7.152467] systemd-udevd[319]: Using default interface naming scheme 'v261'.1607builder # [ 7.179396] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1608builder # [ 7.181273] systemd[1]: Finished Apply Kernel Variables.1609builder # [ 7.183221] systemd[1]: Finished Create Static Device Nodes in /dev.1610builder # [ 7.186125] systemd[1]: Reached target Preparation for Local File Systems.1611builder # [ 7.188134] systemd[1]: Starting Rule-based Manager for Device Events and Files...1612builder # [ 7.190215] systemd[1]: Finished Flush Journal to Persistent Storage.1613builder # [ 7.237824] systemd-udevd[322]: Using default interface naming scheme 'v261'.1614server # [ 7.251224] systemd[1]: Finished Coldplug All udev Devices.1615server # [ 7.276124] systemd[1]: Started Rule-based Manager for Device Events and Files.1616builder # [ 7.333598] systemd[1]: Finished Coldplug All udev Devices.1617builder # [ 7.364476] systemd[1]: Started Rule-based Manager for Device Events and Files.1618server # [ 7.405442] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1619server # [ 7.474850] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1620builder # [ 7.496684] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1621server # [ 7.495619] systemd[1]: Mounting /run/wrappers...1622server # [ 7.500375] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1623server # [ 7.536247] systemd[1]: Mounted /run/wrappers.1624server # [ 7.537963] systemd[1]: Reached target Local File Systems.1625server # [ 7.542890] systemd[1]: Listening on Boot Loader Control Service Socket.1626server # [ 7.545267] systemd[1]: Starting register-nix-paths.service...1627server # [ 7.549083] systemd[1]: Starting Create SUID/SGID Wrappers...1628server # [ 7.552060] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1629server # [ 7.555204] systemd[1]: Starting Save Transient machine-id to Disk...1630builder # [ 7.568430] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1631server # [ 7.561079] systemd[1]: Starting Create System Files and Directories...1632builder # [ 7.587811] systemd[1]: Mounting /run/wrappers...1633builder # [ 7.599094] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1634server # [ 7.593066] (udev-worker)[354]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1635server # [ 7.600666] (udev-worker)[354]: Network interface NamePolicy= disabled on kernel command line.1636server # [ 7.604334] (udev-worker)[359]: Network interface NamePolicy= disabled on kernel command line.1637builder # [ 7.632090] systemd[1]: Mounted /run/wrappers.1638builder # [ 7.633226] systemd[1]: Reached target Local File Systems.1639builder # [ 7.635658] systemd[1]: Listening on Boot Loader Control Service Socket.1640builder # [ 7.640432] systemd[1]: Starting register-nix-paths.service...1641builder # [ 7.644098] systemd[1]: Starting Create SUID/SGID Wrappers...1642builder # [ 7.645274] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1643builder # [ 7.649312] systemd[1]: Starting Save Transient machine-id to Disk...1644server # [ 7.641122] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1645builder # [ 7.652104] systemd[1]: Starting Create System Files and Directories...1646server # [ 7.643480] systemd[1]: Finished Save Transient machine-id to Disk.1647builder # [ 7.686211] (udev-worker)[364]: Network interface NamePolicy= disabled on kernel command line.1648builder # [ 7.692924] (udev-worker)[366]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1649builder # [ 7.695314] (udev-worker)[366]: Network interface NamePolicy= disabled on kernel command line.1650server # [ 7.707819] systemd[1]: Finished Create System Files and Directories.1651server # [ 7.714956] systemd[1]: Starting Rebuild Journal Catalog...1652server # [ 7.717706] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1653builder # [ 7.732181] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1654builder # [ 7.736360] systemd[1]: Finished Save Transient machine-id to Disk.1655server # [ 7.786210] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1656builder # [ 7.806106] systemd[1]: Finished Create System Files and Directories.1657builder # [ 7.811313] systemd[1]: Starting Rebuild Journal Catalog...1658builder # [ 7.814633] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1659server # [ 7.847259] systemd[1]: Finished Rebuild Journal Catalog.1660server # [ 7.850347] systemd[1]: Starting Update is Completed...1661server # [ 7.860843] systemd[1]: Condition check resulted in Virtio network device being skipped.1662server # [ 7.863165] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1663server # [ 7.865290] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1664server # [ 7.867816] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671665server # [ 7.870546] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1666server # [ 7.872965] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1667server # [ 7.875982] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1668builder # [ 7.887781] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1669server # [ 7.917215] systemd[1]: Finished Update is Completed.1670builder # [ 7.943328] systemd[1]: Finished Rebuild Journal Catalog.1671builder # [ 7.946801] systemd[1]: Starting Update is Completed...1672builder # [ 7.948901] systemd[1]: Condition check resulted in Virtio network device being skipped.1673builder # [ 7.951367] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1674builder # [ 7.953792] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1675builder # [ 7.956153] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671676builder # [ 7.959230] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1677builder # [ 7.961588] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1678builder # [ 7.963979] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1679builder # [ 8.011661] systemd[1]: Finished Update is Completed.1680server # [ 8.260710] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31681server # [ 8.302784] ACPI: button: Power Button [PWRF]1682builder # [ 8.321647] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31683server # [ 8.203828] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1684server # [ 8.205516] systemd[1]: Finished Create SUID/SGID Wrappers.1685server # [ 8.355673] mousedev: PS/2 mouse device common for all mice1686builder # [ 8.376185] ACPI: button: Power Button [PWRF]1687builder # [ 8.417647] mousedev: PS/2 mouse device common for all mice1688server # [ 8.417395] rtc_cmos 00:04: RTC can wake from S41689builder # [ 8.317600] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1690builder # [ 8.320250] systemd[1]: Finished Create SUID/SGID Wrappers.1691builder # [ 8.324134] systemd[1]: Finished Firewall.1692server # [ 8.453639] rtc_cmos 00:04: registered as rtc01693server # [ 8.461828] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1694builder # [ 8.490732] rtc_cmos 00:04: RTC can wake from S41695builder # [ 8.499231] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1696server # [ 8.356530] systemd[1]: Finished Firewall.1697builder # [ 8.506810] parport_pc 00:02: reported by Plug and Play ACPI1698builder # [ 8.512906] rtc_cmos 00:04: registered as rtc01699builder # [ 8.513574] rtc_cmos 00:04: setting system clock to 2026-09-15T08:17:31 UTC (1789460251)1700server # [ 8.380937] systemd[1]: Finished register-nix-paths.service.1701server # [ 8.383143] systemd[1]: Reached target System Initialization.1702server # [ 8.384140] systemd[1]: Started Discard unused filesystem blocks once a week.1703server # [ 8.386143] systemd[1]: Started niks3 garbage collection timer.1704server # [ 8.387203] systemd[1]: Started Daily Cleanup of Temporary Directories.1705server # [ 8.388608] systemd[1]: Reached target Timer Units.1706server # [ 8.389387] systemd[1]: Listening on D-Bus System Message Bus Socket.1707server # [ 8.391444] systemd[1]: Listening on niks3 server socket.1708server # [ 8.398092] systemd[1]: Listening on Nix Daemon Socket.1709server # [ 8.399450] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1710server # [ 8.402138] systemd[1]: Reached target Socket Units.1711server # [ 8.403218] systemd[1]: Reached target Basic System.1712server # [ 8.405070] systemd[1]: Started backdoor.service.1713server # [ 8.409135] systemd[1]: Starting Import lastlog data into lastlog2 database...1714server # [ 8.414114] systemd[1]: Starting Generate test mTLS certs...1715server # [ 8.424164] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1716server # [ 8.430811] systemd[1]: Starting Post-Boot Actions...1717server # [ 8.435573] systemd[1]: Started Reset console on configuration changes.1718server # [ 8.443881] systemd[1]: Starting resolvconf update...1719server # [ 8.448846] systemd[1]: Starting D-Bus System Message Bus...1720builder # [ 8.487978] systemd[1]: Finished register-nix-paths.service.1721builder # [ 8.488991] systemd[1]: Reached target System Initialization.1722builder # [ 8.489843] systemd[1]: Started Discard unused filesystem blocks once a week.1723builder # [ 8.491342] systemd[1]: Started Daily Cleanup of Temporary Directories.1724builder # [ 8.492762] systemd[1]: Reached target Timer Units.1725builder # [ 8.494546] systemd[1]: Listening on D-Bus System Message Bus Socket.1726builder # [ 8.496188] systemd[1]: Starting niks3 auto-upload socket...1727builder # [ 8.501952] systemd[1]: Listening on Nix Daemon Socket.1728builder # [ 8.502872] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1729builder # [ 8.504063] systemd[1]: Listening on niks3 auto-upload socket.1730builder # [ 8.506135] systemd[1]: Reached target Socket Units.1731builder # [ 8.510453] systemd[1]: Starting D-Bus System Message Bus...1732builder # [ 8.539910] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1733builder # [ 8.540258] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1734server # [ 8.534970] systemd[1]: Finished Post-Boot Actions.1735builder # [ 8.546220] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1736builder # [ 8.548826] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input41737builder # [ 8.665847] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1738builder # [ 8.665955] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1739server # connecting to host...1740server # [ 8.571095] systemd[1]: Started Name Service Cache Daemon (nsncd).1741builder # [ 8.670650] Console: switching to colour dummy device 80x251742server # [ 8.574560] systemd[1]: Reached target Host and Network Name Lookups.1743builder # [ 8.722145] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61744server # [ 8.577368] nsncd[524]: Sep 15 08:17:31.735 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1745builder # [ 8.722391] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51746server # [ 8.583731] systemd[1]: Reached target User and Group Name Lookups.1747server # [ 8.585677] systemd[1]: Starting User Login Management...1748builder # [ 8.741934] [drm] Found bochs VGA, ID 0xb0c5.1749server # [ 8.593833] systemd[1]: Finished Import lastlog data into lastlog2 database.1750builder # [ 8.741936] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1751builder # [ 8.744148] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1752builder # [ 8.619070] systemd[1]: Starting Virtual Console Setup...1753builder # [ 8.758734] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01754server: Guest shell says: b'Spawning backdoor root shell...\n'1755builder # [ 8.636839] dbus-broker-launch[513]: Looking up NSS user entry for 'systemd-timesync'...1756server: connected to guest root shell1757builder # [ 8.643706] dbus-broker-launch[513]: NSS returned no entry for 'systemd-timesync'1758server: (connecting took 9.38 seconds)1759builder # [ 8.645510] dbus-broker-launch[513]: Invalid user-name in /nix/store/4izapa6lc8pjjwkdkkys3rcag91nh0f8-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1760server: (finished: waiting for the VM to finish booting, in 9.38 seconds)1761server # [ 8.661094] niks3-test-certs-start[538]: -----1762builder # [ 8.678894] systemd[1]: Started D-Bus System Message Bus.1763server # [ 8.669680] dbus-broker-launch[528]: Looking up NSS user entry for 'systemd-timesync'...1764builder # [ 8.681877] systemd[1]: Reached target Basic System.1765builder # [ 8.685532] systemd[1]: Started backdoor.service.1766builder # [ 8.688948] systemd[1]: Starting Import lastlog data into lastlog2 database...1767builder # [ 8.696329] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1768builder # [ 8.705071] systemd[1]: Starting Post-Boot Actions...1769server # [ 8.694685] dbus-broker-launch[528]: NSS returned no entry for 'systemd-timesync'1770server # [ 8.695906] dbus-broker-launch[528]: Invalid user-name in /nix/store/k07wxdbd2hh3sfh8xhkl4n9v3ggihpjw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1771builder # [ 8.708246] systemd[1]: Started Reset console on configuration changes.1772builder # [ 8.711244] systemd[1]: Starting resolvconf update...1773server # [ 8.715249] niks3-test-certs-start[573]: -----1774server # [ 8.740989] systemd[1]: Started D-Bus System Message Bus.1775server # [ 8.472432] rtc_cmos 00:04: setting system clock to 2026-09-15T08:17:31 UTC (1789460251)1776builder # [ 8.772610] dbus-broker-launch[513]: Ready1777server # [ 8.487884] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1778server # [ 8.496686] parport_pc 00:02: reported by Plug and Play ACPI1779builder # [ 8.810306] systemd[1]: Finished Post-Boot Actions.1780server # [ 8.804319] systemd[1]: Stopped target Host and Network Name Lookups.1781server # [ 8.805233] systemd[1]: Stopping Host and Network Name Lookups...1782server # [ 8.805986] systemd[1]: Stopped target User and Group Name Lookups.1783server # [ 8.806815] systemd[1]: Stopping User and Group Name Lookups...1784server # [ 8.811122] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1785server # [ 8.817093] dbus-broker-launch[528]: Ready1786server # [ 8.827102] systemd[1]: nscd.service: Deactivated successfully.1787server # [ 8.827895] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1788server # [ 8.497317] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1789server # [ 8.499990] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input41790server # [ 8.540464] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1791server # [ 8.876950] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1792server # [ 8.877059] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1793server # [ 8.892220] Console: switching to colour dummy device 80x251794builder # [ 9.046342] Console: switching to colour frame buffer device 160x501795builder # [ 9.056725] ppdev: user-space parallel port driver1796server # [ 9.019042] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61797server # [ 9.019280] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51798server # [ 9.102473] [drm] Found bochs VGA, ID 0xb0c5.1799server # [ 9.102475] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1800server # [ 8.835917] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1801builder # [ 9.117833] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1802server # [ 8.969758] niks3-test-certs-start[586]: Certificate request self-signature ok1803server # [ 8.974191] niks3-test-certs-start[586]: subject=CN=server1804server # [ 8.976900] systemd[1]: Started Name Service Cache Daemon (nsncd).1805server # [ 8.981658] niks3-test-certs-start[620]: -----1806server # [ 8.983457] systemd[1]: Reached target Host and Network Name Lookups.1807server # [ 8.991402] nsncd[610]: Sep 15 08:17:32.081 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1808server # [ 8.993296] systemd[1]: Reached target User and Group Name Lookups.1809builder # [ 9.134713] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1810builder # [ 9.143494] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1811server # [ 8.997462] systemd[1]: Finished resolvconf update.1812builder # [ 8.854661] systemd[1]: Started Name Service Cache Daemon (nsncd).1813server # [ 8.998791] systemd[1]: Reached target Preparation for Network.1814server # [ 9.002831] systemd[1]: Starting DHCP Client...1815builder # connecting to host...1816server # [ 9.004685] systemd[1]: Starting Address configuration of eth1...1817server # [ 9.005567] systemd[1]: Starting Extra networking commands....1818builder # [ 9.016364] systemd[1]: Reached target Host and Network Name Lookups.1819server # [ 9.008242] systemd-logind[549]: New seat seat0.1820builder # [ 9.019312] nsncd[522]: Sep 15 08:17:31.974 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1821server # [ 9.012248] systemd[1]: Started User Login Management.1822server # [ 9.012971] systemd[1]: Starting linger-users.service...1823server # [ 9.013953] niks3-test-certs-start[624]: Certificate request self-signature ok1824builder # [ 9.027243] systemd[1]: Reached target User and Group Name Lookups.1825server # [ 9.016722] niks3-test-certs-start[624]: subject=CN=niks3 test client1826builder # [ 9.030315] systemd[1]: Starting User Login Management...1827builder # [ 9.033675] systemd[1]: Finished Import lastlog data into lastlog2 database.1828builder # [ 9.036806] systemd[1]: Stopped target Host and Network Name Lookups.1829builder # [ 9.043094] systemd[1]: Stopping Host and Network Name Lookups...1830builder # [ 9.045067] systemd[1]: Stopped target User and Group Name Lookups.1831builder # [ 9.045986] systemd[1]: Stopping User and Group Name Lookups...1832builder # [ 9.048774] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1833builder # [ 9.049934] systemd[1]: nscd.service: Deactivated successfully.1834server # [ 9.042439] systemd[1]: Finished Generate test mTLS certs.1835builder # [ 9.052674] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1836builder # [ 9.055823] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1837server # [ 9.187051] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1838server # [ 9.187872] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01839builder # [ 9.059048] systemd-logind[544]: Watching system buttons on /dev/input/event2 (Power Button)1840builder # [ 9.061237] systemd-logind[544]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1841builder # [ 9.064657] systemd-logind[544]: New seat seat0.1842builder # [ 9.066300] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1843builder # [ 9.068539] systemd[1]: Stopped Virtual Console Setup.1844builder # [ 9.069532] systemd[1]: Starting Virtual Console Setup...1845builder # [ 9.071308] systemd[1]: Started User Login Management.1846builder # [ 9.073925] systemd[1]: Starting linger-users.service...1847builder # [ 9.075400] systemd-logind[544]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1848server # [ 9.073977] systemd[1]: linger-users.service: Deactivated successfully.1849server # [ 9.077155] systemd[1]: Finished linger-users.service.1850builder # [ 9.092468] systemd[1]: Started Name Service Cache Daemon (nsncd).1851builder # [ 9.094858] nsncd[590]: Sep 15 08:17:32.214 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1852builder # [ 9.097958] systemd[1]: Reached target Host and Network Name Lookups.1853builder # [ 9.099854] systemd[1]: Reached target User and Group Name Lookups.1854server # [ 9.095102] systemd[1]: Starting Virtual Console Setup...1855builder # [ 9.120628] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1856builder # [ 9.121718] systemd[1]: Stopped Virtual Console Setup.1857builder # [ 9.127868] systemd[1]: Starting Virtual Console Setup...1858builder # [ 9.134937] systemd[1]: linger-users.service: Deactivated successfully.1859builder # [ 9.135932] systemd[1]: Finished linger-users.service.1860server # [ 9.136274] network-addresses-eth1-start[639]: adding address 192.168.1.2/24... done1861builder # [ 9.154361] systemd[1]: Finished resolvconf update.1862builder # [ 9.155189] systemd[1]: Reached target Preparation for Network.1863builder # [ 9.161105] systemd[1]: Starting DHCP Client...1864builder # [ 9.164245] systemd[1]: Starting Address configuration of eth1...1865server # [ 9.157533] network-addresses-eth1-start[639]: adding address 2001:db8:1::2/64... done1866builder # [ 9.168363] systemd[1]: Starting Extra networking commands....1867server # [ 9.185396] systemd[1]: Finished Address configuration of eth1.1868server # [ 9.252742] dhcpcd[653]: dhcpcd-10.3.2 starting1869server # [ 9.260186] systemd-logind[549]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1870server # [ 9.264353] dhcpcd[711]: dev: loaded udev1871server # [ 9.274898] systemd[1]: Finished Extra networking commands..1872server # [ 9.276176] systemd[1]: Reached target Network.1873server # [ 9.278725] systemd[1]: Started Mock OIDC server for testing.1874server # [ 9.284813] systemd[1]: Starting Nginx Web Server...1875server # [ 9.288326] systemd-logind[549]: Watching system buttons on /dev/input/event2 (Power Button)1876server # [ 9.297297] systemd[1]: Starting PostgreSQL Server...1877server # [ 9.300318] systemd[1]: Started RustFS S3-compatible object storage.1878server # [ 9.307762] systemd[1]: Starting Setup RustFS bucket...1879server # [ 9.313699] systemd[1]: Starting Permit User Sessions...1880builder # [ 9.333772] network-addresses-eth1-start[627]: adding address 192.168.1.1/24... done1881builder # [ 9.355144] network-addresses-eth1-start[627]: adding address 2001:db8:1::1/64... done1882builder # [ 9.510261] kvm_amd: TSC scaling supported1883builder # [ 9.514259] kvm_amd: Nested Virtualization enabled1884builder # [ 9.380452] systemd[1]: Finished Address configuration of eth1.1885builder # [ 9.519959] kvm_amd: Nested Paging enabled1886builder # [ 9.520405] kvm_amd: LBR virtualization supported1887builder # [ 9.522975] kvm_amd: Virtual VMLOAD VMSAVE supported1888builder # [ 9.523441] kvm_amd: Virtual GIF supported1889builder # [ 9.526318] kvm_amd: Virtual NMI enabled1890builder # [ 9.440118] dhcpcd[636]: dhcpcd-10.3.2 starting1891builder # [ 9.448244] dhcpcd[691]: dev: loaded udev1892builder # [ 9.454100] systemd[1]: Finished Extra networking commands..1893builder # [ 9.455408] systemd[1]: Reached target Network.1894builder # [ 9.458776] systemd[1]: Starting Permit User Sessions...1895builder # [ 9.602458] EDAC MC: Ver: 3.0.01896builder # [ 9.616154] 8021q: 802.1Q VLAN Support v1.81897builder # [ 9.616567] 8021q: adding VLAN 0 to HW filter on device eth11898builder # [ 9.498510] systemd[1]: Finished Permit User Sessions.1899builder # [ 9.503247] systemd[1]: Started Getty on tty1.1900builder # [ 9.505169] systemd[1]: Reached target Login Prompts.1901builder # [ 9.518528] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1902server # [ 9.432058] 8021q: 802.1Q VLAN Support v1.81903builder # [ 9.710312] cfg80211: Loading compiled-in X.509 certificates for regulatory database1904builder # [ 9.738238] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1905builder # [ 9.739020] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1906server # [ 9.432074] 8021q: adding VLAN 0 to HW filter on device eth11907builder # [ 9.742462] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21908builder # [ 9.743912] cfg80211: failed to load regulatory.db1909server # [ 9.587961] cfg80211: Loading compiled-in X.509 certificates for regulatory database1910builder # [ 9.793806] 8021q: adding VLAN 0 to HW filter on device eth01911builder # [ 9.657243] dhcpcd[691]: eth0: waiting for carrier1912builder # [ 9.658168] dhcpcd[691]: eth0: carrier acquired1913builder # [ 9.667728] dhcpcd[691]: DUID 00:01:00:01:32:3b:bb:9c:52:54:00:12:34:561914builder # [ 9.668784] dhcpcd[691]: eth0: IAID 00:12:34:561915builder # [ 9.669626] dhcpcd[691]: eth0: adding address fe80::5054:ff:fe12:34561916server # [ 9.613972] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1917server # [ 9.614088] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1918server # [ 9.615826] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21919builder # [ 9.747651] systemd-vconsole-setup[622]: Configuration of first virtual console was skipped, ignoring remaining ones.1920builder # [ 9.750576] systemd[1]: Finished Virtual Console Setup.1921server # [ 9.615830] cfg80211: failed to load regulatory.db1922server # [ 9.667964] Console: switching to colour frame buffer device 160x501923server # [ 9.744975] ppdev: user-space parallel port driver1924server # [ 9.758092] 8021q: adding VLAN 0 to HW filter on device eth01925server # [ 9.894702] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1926server # [ 9.921825] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1927server # [ 9.985516] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1928server # [ 9.455049] systemd[1]: Finished Permit User Sessions.1929server # [ 9.850947] dhcpcd[711]: eth0: waiting for carrier1930server # [ 9.858740] mock-oidc-server[715]: Mock OIDC Server running1931server # [ 9.861785] mock-oidc-server[715]: OIDC Address: 127.0.0.1:80801932server # [ 9.863230] mock-oidc-server[715]: Issue Address: 127.0.0.1:80811933server # [ 9.863964] mock-oidc-server[715]: Issuer: http://127.0.0.1:8080/oidc1934server # [ 9.866125] mock-oidc-server[715]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json1935server # [ 9.867932] mock-oidc-server[715]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration1936server # [ 9.869939] mock-oidc-server[715]: Issue tokens: http://127.0.0.1:8081/issue?sub=...1937server # [ 9.872591] systemd-logind[549]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1938server # [ 9.873720] dhcpcd[711]: eth0: carrier acquired1939server # [ 9.875148] systemd[1]: Started Getty on tty1.1940server # [ 9.876940] dhcpcd[711]: DUID 00:01:00:01:32:3b:bb:9c:52:54:00:12:34:561941server # [ 9.879342] systemd[1]: Reached target Login Prompts.1942server # [ 9.882694] dhcpcd[711]: eth0: IAID 00:12:34:561943server # [ 9.883734] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1944server # [ 9.885541] dhcpcd[711]: eth0: adding address fe80::5054:ff:fe12:34561945server # [ 9.889167] systemd[1]: Stopped Virtual Console Setup.1946server # [ 9.890217] nginx-pre-start[749]: nginx: the configuration file /nix/store/qklp9g1w1b16lk2fc6xbcrhhv79wkd5f-nginx.conf syntax is ok1947server # [ 9.892705] nginx-pre-start[749]: nginx: configuration file /nix/store/qklp9g1w1b16lk2fc6xbcrhhv79wkd5f-nginx.conf test is successful1948server # [ 9.896167] systemd[1]: Starting Virtual Console Setup...1949server # [ 9.904531] systemd[1]: Started Nginx Web Server.1950server # [ 9.917777] postgresql-pre-start[754]: The files belonging to this database system will be owned by user "postgres".1951server # [ 9.919432] postgresql-pre-start[754]: This user must also own the server process.1952server # [ 9.935792] postgresql-pre-start[754]: The database cluster will be initialized with locale "en_US.UTF-8".1953server # [ 9.937110] postgresql-pre-start[754]: The default database encoding has accordingly been set to "UTF8".1954server # [ 9.938308] postgresql-pre-start[754]: The default text search configuration will be set to "english".1955server # [ 9.940730] postgresql-pre-start[754]: Data page checksums are enabled.1956server # [ 9.943277] postgresql-pre-start[754]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok1957server # [ 9.946086] postgresql-pre-start[754]: creating subdirectories ... ok1958server # [ 9.947298] postgresql-pre-start[754]: selecting dynamic shared memory implementation ... posix1959server # [ 9.949914] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1960builder # [ 10.031285] dhcpcd[691]: eth0: soliciting a DHCP lease1961server # [ 10.030367] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1962server # [ 10.035413] systemd[1]: Stopped Virtual Console Setup.1963server # [ 10.037137] systemd[1]: Starting Virtual Console Setup...1964builder # [ 10.198505] NET: Registered PF_PACKET protocol family1965builder # [ 10.067860] dhcpcd[691]: eth0: offered 10.0.2.15 from 10.0.2.21966builder # [ 10.072375] dhcpcd[691]: eth0: probing address 10.0.2.15/241967server # [ 10.199409] postgresql-pre-start[754]: selecting default "max_connections" ... 1001968server # [ 10.349667] kvm_amd: TSC scaling supported1969server # [ 10.350340] kvm_amd: Nested Virtualization enabled1970server # [ 10.351332] kvm_amd: Nested Paging enabled1971server # [ 10.352216] kvm_amd: LBR virtualization supported1972server # [ 10.353785] kvm_amd: Virtual VMLOAD VMSAVE supported1973server # [ 10.354382] kvm_amd: Virtual GIF supported1974server # [ 10.356015] kvm_amd: Virtual NMI enabled1975server # [ 10.417383] EDAC MC: Ver: 3.0.01976server # [ 10.315163] postgresql-pre-start[754]: selecting default "shared_buffers" ... 128MB1977server # [ 10.518693] systemd-vconsole-setup[777]: Configuration of first virtual console was skipped, ignoring remaining ones.1978server # [ 10.523140] systemd[1]: Finished Virtual Console Setup.1979server # [ 10.694358] rustfs-setup-start[801]: mb s3://niks3-test1980server # [ 10.700865] systemd[1]: Finished Setup RustFS bucket.1981builder # [ 10.903583] dhcpcd[691]: eth0: soliciting an IPv6 router1982builder # [ 10.906467] dhcpcd[691]: eth0: Router Advertisement from fe80::21983builder # [ 10.908521] dhcpcd[691]: eth0: adding address fec0::5054:ff:fe12:3456/641984builder # [ 10.911102] dhcpcd[691]: eth0: adding route to fec0::/641985builder # [ 10.912940] dhcpcd[691]: eth0: adding default route via fe80::21986server # [ 11.527598] dhcpcd[711]: eth0: soliciting a DHCP lease1987server # [ 11.681865] NET: Registered PF_PACKET protocol family1988server # [ 11.547468] dhcpcd[711]: eth0: offered 10.0.2.15 from 10.0.2.21989server # [ 11.550244] dhcpcd[711]: eth0: probing address 10.0.2.15/241990server # [ 12.143113] dhcpcd[711]: eth0: soliciting an IPv6 router1991server # [ 12.144333] dhcpcd[711]: eth0: Router Advertisement from fe80::21992server # [ 12.145576] dhcpcd[711]: eth0: adding address fec0::5054:ff:fe12:3456/641993server # [ 12.146508] dhcpcd[711]: eth0: adding route to fec0::/641994server # [ 12.148098] dhcpcd[711]: eth0: adding default route via fe80::21995server # [ 12.199758] postgresql-pre-start[754]: selecting default time zone ... UTC1996server # [ 12.204262] postgresql-pre-start[754]: creating configuration files ... ok1997server # [ 12.419646] postgresql-pre-start[754]: running bootstrap script ... ok1998server # [ 12.915601] postgresql-pre-start[754]: performing post-bootstrap initialization ... ok1999server # [ 13.040366] postgresql-pre-start[754]: syncing data to disk ... ok2000server # [ 13.042077] postgresql-pre-start[754]: initdb: warning: enabling "trust" authentication for local connections2001server # [ 13.043258] postgresql-pre-start[754]: 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.2002server # [ 13.044951] postgresql-pre-start[754]: Success. You can now start the database server using:2003server # [ 13.045926] postgresql-pre-start[754]: pg_ctl -D /var/lib/postgresql/18 -l logfile start2004server # [ 13.169514] postgres[825]: [825] LOG: starting PostgreSQL 18.6 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit2005server # [ 13.173181] postgres[825]: [825] LOG: listening on IPv6 address "::1", port 54322006server # [ 13.174184] postgres[825]: [825] LOG: listening on IPv4 address "127.0.0.1", port 54322007server # [ 13.176660] postgres[825]: [825] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"2008server # [ 13.189387] postgres[838]: [838] LOG: database system was shut down at 2026-09-15 08:17:36 GMT2009server # [ 13.195082] postgres[825]: [825] LOG: database system is ready to accept connections2010server # [ 13.201688] systemd[1]: Started PostgreSQL Server.2011server # [ 13.205932] systemd[1]: Starting niks3 server...2012server # [ 13.208603] systemd[1]: Starting PostgreSQL Setup Scripts...2013server # [ 13.429982] postgresql-setup-start[851]: CREATE DATABASE2014server # [ 13.449248] postgres[858]: [858] FATAL: role "niks3" does not exist2015server # [ 13.456299] postgres[859]: [859] FATAL: role "niks3" does not exist2016server # [ 13.460593] niks3-server[842]: 2026/09/15 08:17:36 ERROR Failed to run server error="failed to connect to database: failed to migrate db: failed to connect to `user=niks3 database=niks3`: /var/run/postgresql/.s.PGSQL.5432 (/var/run/postgresql): server error: FATAL: role \"niks3\" does not exist (SQLSTATE 28000); failed to connect to `user=niks3 database=niks3`: /var/run/postgresql/.s.PGSQL.5432 (/var/run/postgresql): server error: FATAL: role \"niks3\" does not exist (SQLSTATE 28000)"2017server # [ 13.468764] systemd[1]: niks3.service: Main process exited, code=exited, status=1/FAILURE2018server # [ 13.470459] systemd[1]: niks3.service: Failed with result 'exit-code'.2019server # [ 13.472524] systemd[1]: Failed to start niks3 server.2020server # [ 13.498772] postgresql-setup-start[864]: CREATE ROLE2021server # [ 13.519468] postgresql-setup-start[866]: ALTER DATABASE2022server # [ 13.525176] systemd[1]: Finished PostgreSQL Setup Scripts.2023server # [ 13.526580] systemd[1]: Reached target PostgreSQL.2024server: (finished: waiting for unit postgresql.service, in 14.94 seconds)2025server: waiting for unit rustfs.service2026server: (finished: waiting for unit rustfs.service, in 0.04 seconds)2027server: waiting for unit rustfs-setup.service2028server: (finished: waiting for unit rustfs-setup.service, in 0.04 seconds)2029server: waiting for unit mock-oidc.service2030server: (finished: waiting for unit mock-oidc.service, in 0.04 seconds)2031server: waiting for unit niks3.service2032builder # [ 15.692481] dhcpcd[691]: eth0: leased 10.0.2.15 for 86400 seconds2033builder # [ 15.695109] dhcpcd[691]: eth0: adding route to 10.0.2.0/242034builder # [ 15.697278] dhcpcd[691]: eth0: adding default route via 10.0.2.22035builder # [ 15.813955] systemd[1]: Started DHCP Client.2036builder # [ 15.816087] systemd[1]: Reached target Multi-User System.2037builder # [ 15.816807] systemd[1]: Startup finished in 846ms (kernel) + 4.044s (initrd) + 10.924s (userspace) = 15.815s.2038server # [ 17.286737] dhcpcd[711]: eth0: leased 10.0.2.15 for 86400 seconds2039server # [ 17.289792] dhcpcd[711]: eth0: adding route to 10.0.2.0/242040server # [ 17.292200] dhcpcd[711]: eth0: adding default route via 10.0.2.22041server # [ 17.422546] systemd[1]: Started DHCP Client.2042server # [ 17.424419] systemd[1]: Reached target Multi-User System.2043server # [ 17.425338] systemd[1]: Startup finished in 845ms (kernel) + 4.061s (initrd) + 12.517s (userspace) = 17.424s.2044server # [ 23.723113] systemd[1]: niks3.service: Scheduled restart job, restart counter is at 1.2045server # [ 23.732192] systemd[1]: Starting niks3 server...2046server # [ 23.832241] postgres[994]: [994] ERROR: relation "goose_db_version" does not exist at character 362047server # [ 23.833766] postgres[994]: [994] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC2048server # [ 23.868599] niks3-server[984]: 2026/09/15 08:17:47 OK 20241026095416_initial_model.sql (13.82ms)2049server # [ 23.877535] niks3-server[984]: 2026/09/15 08:17:47 OK 20251210153512_drop_unused_gin_index.sql (3.28ms)2050server # [ 23.880198] niks3-server[984]: 2026/09/15 08:17:47 OK 20251218171726_add_pins.sql (3.9ms)2051server # [ 23.881972] niks3-server[984]: 2026/09/15 08:17:47 OK 20260628120000_add_object_size_and_stats.sql (6.03ms)2052server # [ 23.883192] niks3-server[984]: 2026/09/15 08:17:47 goose: successfully migrated database to version: 202606281200002053server # [ 23.887955] niks3-server[984]: 2026/09/15 08:17:47 OK 1_commit_pending_closure.sql (5.99ms)2054server # [ 23.890451] niks3-server[984]: 2026/09/15 08:17:47 OK 2_object_stats_trigger.sql (2.5ms)2055server # [ 23.891621] niks3-server[984]: 2026/09/15 08:17:47 goose: up to current file version: 22056server # [ 23.916946] niks3-server[984]: 2026/09/15 08:17:47 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:8080/oidc2057server # [ 23.918507] niks3-server[984]: 2026/09/15 08:17:47 INFO OIDC authentication enabled config=/nix/store/z6045pdivqf5pqlg34fhh8w07v284xnl-niks3-oidc.json2058server # [ 23.921050] niks3-server[984]: 2026/09/15 08:17:47 INFO Loaded signing key name=niks3-test-1 path=/nix/store/q21mypxclhwpjnls1yg49g73bmn6m6f3-niks3-signing-key2059server # [ 23.976361] niks3-server[984]: 2026/09/15 08:17:47 INFO Using socket-activated listener address=0.0.0.0:57512060server # [ 23.978520] niks3-server[984]: 2026/09/15 08:17:47 INFO systemd watchdog enabled interval=15s2061server # [ 23.979675] systemd[1]: Started niks3 server.2062server # [ 23.981556] niks3-server[984]: 2026/09/15 08:17:47 INFO Starting HTTP server address=0.0.0.0:57512063server: (finished: waiting for unit niks3.service, in 10.61 seconds)2064server: waiting for TCP port 5751 on localhost2065server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded!2066server: (finished: waiting for TCP port 5751 on localhost, in 0.06 seconds)2067server: waiting for TCP port 8080 on localhost2068server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded!2069server: (finished: waiting for TCP port 8080 on localhost, in 0.03 seconds)2070server: waiting for TCP port 9000 on localhost2071server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded!2072server: (finished: waiting for TCP port 9000 on localhost, in 0.03 seconds)2073server: must succeed: mkdir -p /tmp/test-config2074server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.02 seconds)2075server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token2076server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.01 seconds)2077server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32078server # [ 25.317970] systemd[1]: Created slice Slice /system/nix-daemon.2079server # [ 25.321864] systemd[1]: Started Nix Daemon instance (PID 1028/UID 0).2080server # [ 25.402262] nix-daemon[1030]: remote pid 1028 is unknown user (trusted)2081server # [ 25.433035] systemd[1]: nix-daemon@0-1-1028_1029-0.service: Deactivated successfully.2082server # [ 25.469508] niks3-server[984]: 2026/09/15 08:17:48 INFO Received uploads request method=POST path=/api/pending_closures2083server # time=2026-09-15T08:17:48.671Z level=INFO msg="Uploading 5 paths to server (0 already cached)"2084server # time=2026-09-15T08:17:48.674Z level=INFO msg="Uploading n51dhmdbik1kfrsm62j5knavmigwrl1a-glibc-2.42-84 (33.4MB)"2085server # time=2026-09-15T08:17:48.677Z level=INFO msg="Uploading ssvq1r0xd8f7paf6zqgpfql1a4drwhy2-xgcc-15.3.0-libgcc (193.0KB)"2086server # time=2026-09-15T08:17:48.684Z level=INFO msg="Uploading wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3 (273.1KB)"2087server # time=2026-09-15T08:17:48.701Z level=INFO msg="Uploading nga9d6m9iplygw3iqghk2g840nz7b0gy-libidn2-2.3.8 (359.5KB)"2088server # time=2026-09-15T08:17:48.702Z level=INFO msg="Uploading yh8rykx8wakl1ccn8rc351f6r2wbg4cn-libunistring-1.4.2 (2.0MB)"2089server # [ 25.605903] niks3-server[984]: 2026/09/15 08:17:48 INFO Registered completed upload object_key=nar/1bvhsl1iqnbbgmxgp2hi0j8nj5ip5n6h6m2awanbnhwrl8fazjvh.nar.zst2090server # [ 25.634284] niks3-server[984]: 2026/09/15 08:17:48 INFO Registered completed upload object_key=ssvq1r0xd8f7paf6zqgpfql1a4drwhy2.ls2091server # [ 25.678232] niks3-server[984]: 2026/09/15 08:17:48 INFO Registered completed upload object_key=nar/178xz8sz0llzvvx7zc6zhqyhdv4d6n621g6yxmxhiabma9bwb14s.nar.zst2092server # [ 25.692677] niks3-server[984]: 2026/09/15 08:17:48 INFO Registered completed upload object_key=yh8rykx8wakl1ccn8rc351f6r2wbg4cn.ls2093server # [ 25.814281] niks3-server[984]: 2026/09/15 08:17:48 INFO Registered completed upload object_key=nar/0l0mcrrkj3msvp6j9f4mhkpkc3i3xvhf1yx4brgs6q0wb6nrwhx6.nar.zst2094server # [ 25.826336] niks3-server[984]: 2026/09/15 08:17:48 INFO Registered completed upload object_key=nga9d6m9iplygw3iqghk2g840nz7b0gy.ls2095server # [ 25.898728] niks3-server[984]: 2026/09/15 08:17:49 INFO Registered completed upload object_key=nar/1mklj120xjz7k0pddd6pvfisaxv5ym1lpb1bvvha6jiqykr17kcq.nar.zst2096server # [ 25.905825] niks3-server[984]: 2026/09/15 08:17:49 INFO Registered completed upload object_key=wzr035k31pmpn2caabq8qwv1npg571z9.ls2097server # [ 27.257395] niks3-server[984]: 2026/09/15 08:17:50 INFO Received complete multipart upload request method=POST path=/api/multipart/complete2098server # [ 27.279144] niks3-server[984]: 2026/09/15 08:17:50 INFO Completed multipart upload object_key=nar/0ypl8gla1lil5zqb5ib1pi8p7niwiq11mcvpmbfnypvnz4n1k2j2.nar.zst upload_id=YmU0Mzg3MTYtNGZkMC00MmY2LWI0MzUtODc5NWFmMmE5Y2NmLmM1MmI3YzgyLTFhZmItNGEyNC1iZDAxLTkzMjUwY2YyMDdiN3gxNzg5NDYwMjY4NjU2OTA5MDQ2 parts=12099server # [ 27.288760] niks3-server[984]: 2026/09/15 08:17:50 INFO Registered completed upload object_key=n51dhmdbik1kfrsm62j5knavmigwrl1a.ls2100server # [ 27.290785] niks3-server[984]: 2026/09/15 08:17:50 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign2101server # [ 27.294977] niks3-server[984]: 2026/09/15 08:17:50 INFO Signed narinfos id=1 count=52102server # time=2026-09-15T08:17:50.462Z level=INFO msg="Uploading 5 narinfos"2103server # [ 27.312940] niks3-server[984]: 2026/09/15 08:17:50 INFO Registered completed upload object_key=yh8rykx8wakl1ccn8rc351f6r2wbg4cn.narinfo2104server # [ 27.333859] niks3-server[984]: 2026/09/15 08:17:50 INFO Registered completed upload object_key=n51dhmdbik1kfrsm62j5knavmigwrl1a.narinfo2105server # [ 27.355503] niks3-server[984]: 2026/09/15 08:17:50 INFO Registered completed upload object_key=nga9d6m9iplygw3iqghk2g840nz7b0gy.narinfo2106server # [ 27.357888] niks3-server[984]: 2026/09/15 08:17:50 INFO Registered completed upload object_key=ssvq1r0xd8f7paf6zqgpfql1a4drwhy2.narinfo2107server # [ 27.359771] niks3-server[984]: 2026/09/15 08:17:50 INFO Registered completed upload object_key=wzr035k31pmpn2caabq8qwv1npg571z9.narinfo2108server # [ 27.361871] niks3-server[984]: 2026/09/15 08:17:50 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete2109server # [ 27.367473] niks3-server[984]: 2026/09/15 08:17:50 INFO Completed upload id=12110server # time=2026-09-15T08:17:50.534Z level=INFO msg="Upload complete. (2.115s)"2111server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3, in 2.28 seconds)2112server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token2113server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.01 seconds)2114server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32115server # [ 27.502551] systemd[1]: Started Nix Daemon instance (PID 1062/UID 0).2116server # [ 27.563315] nix-daemon[1064]: remote pid 1062 is unknown user (trusted)2117server # [ 27.577255] systemd[1]: nix-daemon@1-2-1062_1063-0.service: Deactivated successfully.2118server # [ 27.584303] niks3-server[984]: 2026/09/15 08:17:50 WARN Authentication failed token_preview=invalid-token token_length=13 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2119server # time=2026-09-15T08:17:50.753Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2120server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3, in 0.20 seconds)2121server: waiting for unit nginx.service2122server: (finished: waiting for unit nginx.service, in 0.04 seconds)2123server: waiting for TCP port 443 on localhost2124server # Connection to localhost (::1) 443 port [tcp/https] succeeded!2125server: (finished: waiting for TCP port 443 on localhost, in 0.03 seconds)2126server: must succeed: /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32127server # time=2026-09-15T08:17:50.887Z level=INFO msg="Configuring client TLS" cert=/etc/niks3-test-certs/client.pem key=/etc/niks3-test-certs/client.key ca=/etc/niks3-test-certs/ca.pem2128server # [ 27.779098] systemd[1]: Started Nix Daemon instance (PID 1090/UID 0).2129server # [ 27.838871] nix-daemon[1092]: remote pid 1090 is unknown user (trusted)2130server # [ 27.852292] systemd[1]: nix-daemon@2-3-1090_1091-0.service: Deactivated successfully.2131server # [ 27.885739] niks3-server[984]: 2026/09/15 08:17:51 INFO Received uploads request method=POST path=/api/pending_closures2132server # time=2026-09-15T08:17:51.057Z level=INFO msg="Uploading 0 paths to server (5 already cached)"2133server # [ 27.893074] niks3-server[984]: 2026/09/15 08:17:51 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete2134server # [ 27.895594] niks3-server[984]: 2026/09/15 08:17:51 INFO Completed upload id=22135server # time=2026-09-15T08:17:51.063Z level=INFO msg="Upload complete. (168ms)"2136server: (finished: must succeed: /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3, in 0.24 seconds)2137server: must fail: /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32138server # time=2026-09-15T08:17:51.082Z level=ERROR msg="Fatal error" error="auth token is required (use --auth-token-path, --auth-token-script, NIKS3_AUTH_TOKEN_FILE, or $XDG_CONFIG_HOME/niks3/auth-token)"2139server: (finished: must fail: /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3, in 0.02 seconds)2140server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32141server # time=2026-09-15T08:17:51.150Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem2142server # [ 28.036933] systemd[1]: Started Nix Daemon instance (PID 1117/UID 0).2143server # [ 28.097862] nix-daemon[1119]: remote pid 1117 is unknown user (trusted)2144server # [ 28.110239] systemd[1]: nix-daemon@3-4-1117_1118-0.service: Deactivated successfully.2145server # [ 28.120229] niks3-server[984]: 2026/09/15 08:17:51 INFO Received uploads request method=POST path=/api/pending_closures2146server # time=2026-09-15T08:17:51.290Z level=INFO msg="Uploading 0 paths to server (5 already cached)"2147server # [ 28.125547] niks3-server[984]: 2026/09/15 08:17:51 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete2148server # [ 28.128262] niks3-server[984]: 2026/09/15 08:17:51 INFO Completed upload id=32149server # time=2026-09-15T08:17:51.295Z level=INFO msg="Upload complete. (144ms)"2150server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3, in 0.21 seconds)2151server: must succeed: cd /etc/niks3-test-certs && /nix/store/63b5kdhvzs4sq7xgg8bikkvdv3kf7c7p-openssl-3.6.3-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client'2152server # -----2153server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/63b5kdhvzs4sq7xgg8bikkvdv3kf7c7p-openssl-3.6.3-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client', in 0.02 seconds)2154server: must succeed: cd /etc/niks3-test-certs && /nix/store/63b5kdhvzs4sq7xgg8bikkvdv3kf7c7p-openssl-3.6.3-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem2155server # Certificate request self-signature ok2156server # subject=CN=other client2157server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/63b5kdhvzs4sq7xgg8bikkvdv3kf7c7p-openssl-3.6.3-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem, in 0.03 seconds)2158server: must fail: /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32159server # time=2026-09-15T08:17:51.416Z level=INFO msg="Configuring client TLS" cert=/etc/niks3-test-certs/other.pem key=/etc/niks3-test-certs/other.key ca=/etc/niks3-test-certs/ca.pem2160server # [ 28.302345] systemd[1]: Started Nix Daemon instance (PID 1144/UID 0).2161server # [ 28.362416] nix-daemon[1146]: remote pid 1144 is unknown user (trusted)2162server # [ 28.375083] systemd[1]: nix-daemon@4-5-1144_1145-0.service: Deactivated successfully.2163server # [ 28.385200] niks3-server[984]: 2026/09/15 08:17:51 WARN mTLS auth: subject not in bound subjects subject="CN=other client"2164server # time=2026-09-15T08:17:51.553Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2165server: (finished: must fail: /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3, in 0.20 seconds)2166server: must succeed: mkdir -p /tmp/test-store2167server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.02 seconds)2168server: must succeed: 2169 export AWS_ACCESS_KEY_ID=rustfsadmin2170export AWS_SECRET_ACCESS_KEY=rustfsadmin2171 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.321722173server: (finished: must succeed: 2174 export AWS_ACCESS_KEY_ID=rustfsadmin2175export AWS_SECRET_ACCESS_KEY=rustfsadmin2176 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32177, in 0.64 seconds)2178server: must succeed: 2179cat > /tmp/test-drv.nix << 'EOF'2180derivation {2181 name = "test-build-log";2182 system = builtins.currentSystem;2183 builder = "/bin/sh";2184 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];2185}2186EOF21872188server: (finished: must succeed: 2189cat > /tmp/test-drv.nix << 'EOF'2190derivation {2191 name = "test-build-log";2192 system = builtins.currentSystem;2193 builder = "/bin/sh";2194 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];2195}2196EOF2197, in 0.03 seconds)2198server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix2199server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2200server # [ 29.164196] systemd[1]: Started Nix Daemon instance (PID 1190/UID 0).2201server # [ 29.249596] nix-daemon[1194]: remote pid 1190 is unknown user (trusted)2202server # this derivation will be built:2203server # /nix/store/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv2204server # building '/nix/store/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv'...2205server # test-build-log> test build log output2206server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.36 seconds)2207server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2208server # [ 29.434755] systemd[1]: nix-daemon@5-6-1190_1191-0.service: Deactivated successfully.2209server # [ 29.574418] systemd[1]: Started Nix Daemon instance (PID 1221/UID 0).2210server # [ 29.648328] nix-daemon[1223]: remote pid 1221 is unknown user (trusted)2211server # [ 29.662256] systemd[1]: nix-daemon@6-7-1221_1222-0.service: Deactivated successfully.2212server # [ 29.669940] niks3-server[984]: 2026/09/15 08:17:52 INFO Received uploads request method=POST path=/api/pending_closures2213server # time=2026-09-15T08:17:52.841Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2214server # time=2026-09-15T08:17:52.842Z level=INFO msg="Uploading dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log (128B)"2215server # [ 29.692468] niks3-server[984]: 2026/09/15 08:17:52 INFO Registered completed upload object_key=nar/00zns3gj9hwz2a4b0i07y7nmxybq59lh24bl3xsxblcl6333mjil.nar.zst2216server # [ 29.697901] niks3-server[984]: 2026/09/15 08:17:52 INFO Registered completed upload object_key=log/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv2217server # [ 29.702985] niks3-server[984]: 2026/09/15 08:17:52 INFO Registered completed upload object_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.ls2218server # [ 29.705031] niks3-server[984]: 2026/09/15 08:17:52 INFO Received sign narinfos request method=POST path=/api/pending_closures/4/sign2219server # [ 29.706739] niks3-server[984]: 2026/09/15 08:17:52 INFO Signed narinfos id=4 count=12220server # time=2026-09-15T08:17:52.874Z level=INFO msg="Uploading 1 narinfos"2221server # [ 29.714704] niks3-server[984]: 2026/09/15 08:17:52 INFO Registered completed upload object_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.narinfo2222server # [ 29.716815] niks3-server[984]: 2026/09/15 08:17:52 INFO Received complete upload request method=POST path=/api/pending_closures/4/complete2223server # time=2026-09-15T08:17:52.885Z level=INFO msg="Upload complete. (206ms)"2224server # [ 29.720188] niks3-server[984]: 2026/09/15 08:17:52 INFO Completed upload id=42225server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log, in 0.29 seconds)2226server: must succeed: 2227 export AWS_ACCESS_KEY_ID=rustfsadmin2228export AWS_SECRET_ACCESS_KEY=rustfsadmin2229 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log22302231server: (finished: must succeed: 2232 export AWS_ACCESS_KEY_ID=rustfsadmin2233export AWS_SECRET_ACCESS_KEY=rustfsadmin2234 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2235, in 0.15 seconds)2236subtest: push --stdin streams paths and reports each one2237server: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }'2238server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2239server # [ 29.954942] systemd[1]: Started Nix Daemon instance (PID 1239/UID 0).2240server # [ 30.030826] nix-daemon[1243]: remote pid 1239 is unknown user (trusted)2241server # this derivation will be built:2242server # /nix/store/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv2243server # building '/nix/store/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv'...2244server: (finished: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }', in 0.29 seconds)2245server: must succeed: printf '%s\n\n%s\n' /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log | NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --stdin2246server # [ 30.165449] systemd[1]: nix-daemon@7-8-1239_1240-0.service: Deactivated successfully.2247server # [ 30.314989] systemd[1]: Started Nix Daemon instance (PID 1271/UID 0).2248server # [ 30.390279] nix-daemon[1273]: remote pid 1271 is unknown user (trusted)2249server # [ 30.403758] systemd[1]: nix-daemon@8-9-1271_1272-0.service: Deactivated successfully.2250server # [ 30.411710] niks3-server[984]: 2026/09/15 08:17:53 INFO Received uploads request method=POST path=/api/pending_closures2251server # [ 30.416103] niks3-server[984]: 2026/09/15 08:17:53 INFO Received uploads request method=POST path=/api/pending_closures2252server # time=2026-09-15T08:17:53.585Z level=INFO msg="Uploading 1 paths to server (1 already cached)"2253server # time=2026-09-15T08:17:53.586Z level=INFO msg="Uploading 3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test (120B)"2254server # [ 30.433260] niks3-server[984]: 2026/09/15 08:17:53 INFO Registered completed upload object_key=log/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv2255server # [ 30.437784] niks3-server[984]: 2026/09/15 08:17:53 INFO Registered completed upload object_key=nar/1hj1r0g515rfxjx685mfn3dgr6akqbn4gf64snlv6bxaxph69lna.nar.zst2256server # [ 30.444553] niks3-server[984]: 2026/09/15 08:17:53 INFO Registered completed upload object_key=3lzkfq9fx7rridh3i11ly8xyqdbqb44w.ls2257server # [ 30.446600] niks3-server[984]: 2026/09/15 08:17:53 INFO Received sign narinfos request method=POST path=/api/pending_closures/5/sign2258server # [ 30.448321] niks3-server[984]: 2026/09/15 08:17:53 INFO Signed narinfos id=5 count=12259server # [ 30.449671] niks3-server[984]: 2026/09/15 08:17:53 INFO Received sign narinfos request method=POST path=/api/pending_closures/6/sign2260server # [ 30.451518] niks3-server[984]: 2026/09/15 08:17:53 INFO Signed narinfos id=6 count=02261server # time=2026-09-15T08:17:53.618Z level=INFO msg="Uploading 1 narinfos"2262server # [ 30.459633] niks3-server[984]: 2026/09/15 08:17:53 INFO Registered completed upload object_key=3lzkfq9fx7rridh3i11ly8xyqdbqb44w.narinfo2263server # [ 30.462087] niks3-server[984]: 2026/09/15 08:17:53 INFO Received complete upload request method=POST path=/api/pending_closures/5/complete2264server # [ 30.464373] niks3-server[984]: 2026/09/15 08:17:53 INFO Completed upload id=52265server # [ 30.465670] niks3-server[984]: 2026/09/15 08:17:53 INFO Received complete upload request method=POST path=/api/pending_closures/6/complete2266server # [ 30.468256] niks3-server[984]: 2026/09/15 08:17:53 INFO Completed upload id=62267server # time=2026-09-15T08:17:53.635Z level=INFO msg="Upload complete. (218ms)"2268server: (finished: must succeed: printf '%s\n\n%s\n' /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log | NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --stdin, in 0.31 seconds)2269server: must succeed: 2270 export AWS_ACCESS_KEY_ID=rustfsadmin2271export AWS_SECRET_ACCESS_KEY=rustfsadmin2272 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test2273 2274server: (finished: must succeed: 2275 export AWS_ACCESS_KEY_ID=rustfsadmin2276export AWS_SECRET_ACCESS_KEY=rustfsadmin2277 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test2278 , in 0.18 seconds)2279(finished: subtest: push --stdin streams paths and reports each one, in 0.78 seconds)2280server: must succeed: readlink /etc/niks3-test/symlink-wrapper2281server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.02 seconds)2282server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper2283server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.02 seconds)2284server: must succeed: test -L /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper2285server: (finished: must succeed: test -L /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper, in 0.02 seconds)2286server: must succeed: readlink /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper2287server: (finished: must succeed: readlink /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper, in 0.02 seconds)2288server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper2289server # [ 30.902584] systemd[1]: Started Nix Daemon instance (PID 1318/UID 0).2290server # [ 30.983331] nix-daemon[1320]: remote pid 1318 is unknown user (trusted)2291server # [ 30.997911] systemd[1]: nix-daemon@9-10-1318_1319-0.service: Deactivated successfully.2292server # [ 31.005962] niks3-server[984]: 2026/09/15 08:17:54 INFO Received uploads request method=POST path=/api/pending_closures2293server # time=2026-09-15T08:17:54.176Z level=INFO msg="Uploading 2 paths to server (0 already cached)"2294server # time=2026-09-15T08:17:54.177Z level=INFO msg="Uploading ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper (192B)"2295server # time=2026-09-15T08:17:54.178Z level=INFO msg="Uploading la1lgrdh5wdf2jsxac162fx3pm4xmz7i-base-package (536B)"2296server # [ 31.030821] niks3-server[984]: 2026/09/15 08:17:54 INFO Registered completed upload object_key=nar/1pm27rpdcg3viv7qb7xqnvwv3khbddfj346mvzadxaiy7ydifcaw.nar.zst2297server # [ 31.038905] niks3-server[984]: 2026/09/15 08:17:54 INFO Registered completed upload object_key=nar/0820av37plmfgrxw6pcbym6phk1sv14dilyrablrnkzb7z1fd5js.nar.zst2298server # [ 31.044323] niks3-server[984]: 2026/09/15 08:17:54 INFO Registered completed upload object_key=ljlv7af0zhbpp672nplky93kj5hm7m9h.ls2299server # [ 31.049769] niks3-server[984]: 2026/09/15 08:17:54 INFO Registered completed upload object_key=la1lgrdh5wdf2jsxac162fx3pm4xmz7i.ls2300server # [ 31.051748] niks3-server[984]: 2026/09/15 08:17:54 INFO Received sign narinfos request method=POST path=/api/pending_closures/7/sign2301server # time=2026-09-15T08:17:54.220Z level=INFO msg="Uploading 2 narinfos"2302server # [ 31.055243] niks3-server[984]: 2026/09/15 08:17:54 INFO Signed narinfos id=7 count=22303server # [ 31.066155] niks3-server[984]: 2026/09/15 08:17:54 INFO Registered completed upload object_key=ljlv7af0zhbpp672nplky93kj5hm7m9h.narinfo2304server # [ 31.068403] niks3-server[984]: 2026/09/15 08:17:54 INFO Registered completed upload object_key=la1lgrdh5wdf2jsxac162fx3pm4xmz7i.narinfo2305server # [ 31.070274] niks3-server[984]: 2026/09/15 08:17:54 INFO Received complete upload request method=POST path=/api/pending_closures/7/complete2306server # [ 31.073061] niks3-server[984]: 2026/09/15 08:17:54 INFO Completed upload id=72307server # time=2026-09-15T08:17:54.240Z level=INFO msg="Upload complete. (241ms)"2308server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper, in 0.33 seconds)2309server: must succeed: 2310 export AWS_ACCESS_KEY_ID=rustfsadmin2311export AWS_SECRET_ACCESS_KEY=rustfsadmin2312 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper23132314server: (finished: must succeed: 2315 export AWS_ACCESS_KEY_ID=rustfsadmin2316export AWS_SECRET_ACCESS_KEY=rustfsadmin2317 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper2318, in 0.20 seconds)2319server: must succeed: 2320cat > /tmp/oidc-test.nix << 'EOF'2321derivation {2322 name = "oidc-test";2323 system = builtins.currentSystem;2324 builder = "/bin/sh";2325 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2326}2327EOF23282329server: (finished: must succeed: 2330cat > /tmp/oidc-test.nix << 'EOF'2331derivation {2332 name = "oidc-test";2333 system = builtins.currentSystem;2334 builder = "/bin/sh";2335 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2336}2337EOF2338, in 0.03 seconds)2339server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link2340server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2341server # [ 31.385499] systemd[1]: Started Nix Daemon instance (PID 1348/UID 0).2342server # [ 31.462573] nix-daemon[1352]: remote pid 1348 is unknown user (trusted)2343server # this derivation will be built:2344server # /nix/store/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv2345server # building '/nix/store/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv'...2346server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link, in 0.31 seconds)2347server: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://server:5751&repository_owner=myorg'2348server # [ 31.622654] systemd[1]: nix-daemon@10-11-1348_1349-0.service: Deactivated successfully.2349server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://server:5751&repository_owner=myorg', in 0.07 seconds)2350server: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODk0NjM4NzQsImlhdCI6MTc4OTQ2MDI3NCwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.egbaOzLbyS4nUMUp-feaaRIveBkeHxsvBIj6qe1NjPkWV3MGXns9k5ietC3d8dKIOPqT8gmIofT87Lp1X1LMMrUj3aAfdqV_-MIc-rIscVMj0LpqBpUAfGsIVR7ExF_WR2zGth9hhBZK0Cb849FxhCfCTYN_jhF6GnMiRJ0Q2aEi7dhH1fh1vebFjoa6y8VIJPzTJiSR_cUinST93bk7XsmFqPn9ddsuPuJYjmDcsaLC8_TTediTIzT22od1Ac_U6dRLbc9xrX-1x0kEVG3YyI2ZnPEIeInSnzRGi_vyFxSc8TE0NuoWS7iNH4Vbzz_BWs6MzJX-3Q1Jj78HMS0C3A' /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test2351server # time=2026-09-15T08:17:54.875Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2352server # [ 31.837403] systemd[1]: Started Nix Daemon instance (PID 1381/UID 0).2353server # [ 31.929895] nix-daemon[1383]: remote pid 1381 is unknown user (trusted)2354server # [ 31.943865] systemd[1]: nix-daemon@11-12-1381_1382-0.service: Deactivated successfully.2355server # [ 31.959426] niks3-server[984]: 2026/09/15 08:17:55 INFO OIDC auth successful provider=test scopes=[write]2356server # [ 31.960760] niks3-server[984]: 2026/09/15 08:17:55 INFO Received uploads request method=POST path=/api/pending_closures2357server # time=2026-09-15T08:17:55.130Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2358server # time=2026-09-15T08:17:55.131Z level=INFO msg="Uploading hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test (136B)"2359server # [ 31.976269] niks3-server[984]: 2026/09/15 08:17:55 INFO OIDC auth successful provider=test scopes=[write]2360server # [ 31.981726] niks3-server[984]: 2026/09/15 08:17:55 INFO Registered completed upload object_key=nar/17h0qgwf5334wia961ibqy9hmpsyvfhrvj5ji9m031klp6sx4v0q.nar.zst2361server # [ 31.984726] niks3-server[984]: 2026/09/15 08:17:55 INFO OIDC auth successful provider=test scopes=[write]2362server # [ 31.988861] niks3-server[984]: 2026/09/15 08:17:55 INFO Registered completed upload object_key=log/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv2363server # [ 31.992207] niks3-server[984]: 2026/09/15 08:17:55 INFO OIDC auth successful provider=test scopes=[write]2364server # [ 31.995518] niks3-server[984]: 2026/09/15 08:17:55 INFO Registered completed upload object_key=hp57zlhhih1lr1f0hfrf8sca8fvgxc2i.ls2365server # [ 31.997328] niks3-server[984]: 2026/09/15 08:17:55 INFO OIDC auth successful provider=test scopes=[write]2366server # [ 31.998643] niks3-server[984]: 2026/09/15 08:17:55 INFO Received sign narinfos request method=POST path=/api/pending_closures/8/sign2367server # [ 32.000408] niks3-server[984]: 2026/09/15 08:17:55 INFO Signed narinfos id=8 count=12368server # time=2026-09-15T08:17:55.167Z level=INFO msg="Uploading 1 narinfos"2369server # [ 32.006507] niks3-server[984]: 2026/09/15 08:17:55 INFO OIDC auth successful provider=test scopes=[write]2370server # [ 32.009967] niks3-server[984]: 2026/09/15 08:17:55 INFO Registered completed upload object_key=hp57zlhhih1lr1f0hfrf8sca8fvgxc2i.narinfo2371server # [ 32.012138] niks3-server[984]: 2026/09/15 08:17:55 INFO OIDC auth successful provider=test scopes=[write]2372server # [ 32.013282] niks3-server[984]: 2026/09/15 08:17:55 INFO Received complete upload request method=POST path=/api/pending_closures/8/complete2373server # time=2026-09-15T08:17:55.182Z level=INFO msg="Upload complete. (243ms)"2374server # [ 32.017095] niks3-server[984]: 2026/09/15 08:17:55 INFO Completed upload id=82375server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODk0NjM4NzQsImlhdCI6MTc4OTQ2MDI3NCwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.egbaOzLbyS4nUMUp-feaaRIveBkeHxsvBIj6qe1NjPkWV3MGXns9k5ietC3d8dKIOPqT8gmIofT87Lp1X1LMMrUj3aAfdqV_-MIc-rIscVMj0LpqBpUAfGsIVR7ExF_WR2zGth9hhBZK0Cb849FxhCfCTYN_jhF6GnMiRJ0Q2aEi7dhH1fh1vebFjoa6y8VIJPzTJiSR_cUinST93bk7XsmFqPn9ddsuPuJYjmDcsaLC8_TTediTIzT22od1Ac_U6dRLbc9xrX-1x0kEVG3YyI2ZnPEIeInSnzRGi_vyFxSc8TE0NuoWS7iNH4Vbzz_BWs6MzJX-3Q1Jj78HMS0C3A' /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test, in 0.33 seconds)2376server: must succeed: 2377cat > /tmp/oidc-test2.nix << 'EOF'2378derivation {2379 name = "oidc-test2";2380 system = builtins.currentSystem;2381 builder = "/bin/sh";2382 args = [ "-c" "echo 'OIDC test 2' > $out" ];2383}2384EOF23852386server: (finished: must succeed: 2387cat > /tmp/oidc-test2.nix << 'EOF'2388derivation {2389 name = "oidc-test2";2390 system = builtins.currentSystem;2391 builder = "/bin/sh";2392 args = [ "-c" "echo 'OIDC test 2' > $out" ];2393}2394EOF2395, in 0.03 seconds)2396server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link2397server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2398server # [ 32.131209] systemd[1]: Started Nix Daemon instance (PID 1395/UID 0).2399server # [ 32.208978] nix-daemon[1399]: remote pid 1395 is unknown user (trusted)2400server # this derivation will be built:2401server # /nix/store/1sl2zp65pyaksf29w1lxwgl7mc3mrw4x-oidc-test2.drv2402server # building '/nix/store/1sl2zp65pyaksf29w1lxwgl7mc3mrw4x-oidc-test2.drv'...2403server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link, in 0.29 seconds)2404server: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:otherorg/repo:ref:refs/heads/main&aud=http://server:5751&repository_owner=otherorg'2405server # [ 32.346063] systemd[1]: nix-daemon@12-13-1395_1396-0.service: Deactivated successfully.2406server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:otherorg/repo:ref:refs/heads/main&aud=http://server:5751&repository_owner=otherorg', in 0.05 seconds)2407server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODk0NjM4NzUsImlhdCI6MTc4OTQ2MDI3NSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.rlbh-KC3LFuTGGeS4k_RMFh9rb-3a__aElhlNa7H9I2qwbkUrIPh2tfxooa40OpFu5nWz-qG9_3vcK-d-OyB73Qgf_iRwhdWh4iieKF_3xhrPcMq6k317D1JypsbS7PEMOTmgJHeTDL-U6CaH3tER8dYby57QTtksOqpyT34GZX6yhjo_IKiLgxdIinV2TSnliSw5732iY2xjbDsOVLNj3XlETvDs4TCrM3qAo-2mJrdebhrDYU08pVftHIOG4YNHC5FVV4xESLZy7hbRJUcPpH-bk8GaWwWH5tzRV1TRzxKymcsSxDTjyqmtXrAqzQetkTcX9mwnv-ZMSIxygj8Ew' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22408server # time=2026-09-15T08:17:55.572Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2409server # [ 32.530539] systemd[1]: Started Nix Daemon instance (PID 1428/UID 0).2410server # [ 32.606567] nix-daemon[1430]: remote pid 1428 is unknown user (trusted)2411server # [ 32.620509] systemd[1]: nix-daemon@13-14-1428_1429-0.service: Deactivated successfully.2412server # [ 32.628666] niks3-server[984]: 2026/09/15 08:17:55 WARN Authentication failed token_preview=eyJhbGciOi...MSIxygj8Ew token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2413server # time=2026-09-15T08:17:55.798Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2414server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODk0NjM4NzUsImlhdCI6MTc4OTQ2MDI3NSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.rlbh-KC3LFuTGGeS4k_RMFh9rb-3a__aElhlNa7H9I2qwbkUrIPh2tfxooa40OpFu5nWz-qG9_3vcK-d-OyB73Qgf_iRwhdWh4iieKF_3xhrPcMq6k317D1JypsbS7PEMOTmgJHeTDL-U6CaH3tER8dYby57QTtksOqpyT34GZX6yhjo_IKiLgxdIinV2TSnliSw5732iY2xjbDsOVLNj3XlETvDs4TCrM3qAo-2mJrdebhrDYU08pVftHIOG4YNHC5FVV4xESLZy7hbRJUcPpH-bk8GaWwWH5tzRV1TRzxKymcsSxDTjyqmtXrAqzQetkTcX9mwnv-ZMSIxygj8Ew' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.25 seconds)2415server: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://wrong:5751&repository_owner=myorg'2416server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://wrong:5751&repository_owner=myorg', in 0.04 seconds)2417server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc4OTQ2Mzg3NSwiaWF0IjoxNzg5NDYwMjc1LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.kSOHv09ykEaX8Ez-nUjf-7YtbTwfFTRzo8kigFDDOj_6pFm8Z0-wy9-tj_yZa8thgBmIiEouc0205e4J8hcpDo4WlyfXUCKX-iYXvj51TNe-7Oh9BWXqeVBndardc8eUDOXAPfNdysEaAAfRHyp9NecCFRK9M-a1OPvL1RLqIgQSeyc3OllrurhkLHzw2oexE7uQeuJA_I5abuIUxzv3Te0bYOhAQOzpY6zVWgFCT7mfqRX3kiV-5XmzJNylnUTEXNf-lVrbwGdP_q0YIslBwrB_3B0KvQaiPsm4E7yTlx-f5EEGKDD3EmQpZ-2R8kQugUixGjIUjxh9gMCT2FYfbw' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22418server # time=2026-09-15T08:17:55.865Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2419server # [ 32.824174] systemd[1]: Started Nix Daemon instance (PID 1452/UID 0).2420server # [ 32.899906] nix-daemon[1454]: remote pid 1452 is unknown user (trusted)2421server # [ 32.915251] systemd[1]: nix-daemon@14-15-1452_1453-0.service: Deactivated successfully.2422server # [ 32.922657] niks3-server[984]: 2026/09/15 08:17:56 WARN Authentication failed token_preview=eyJhbGciOi...gMCT2FYfbw token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2423server # time=2026-09-15T08:17:56.091Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2424server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc4OTQ2Mzg3NSwiaWF0IjoxNzg5NDYwMjc1LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.kSOHv09ykEaX8Ez-nUjf-7YtbTwfFTRzo8kigFDDOj_6pFm8Z0-wy9-tj_yZa8thgBmIiEouc0205e4J8hcpDo4WlyfXUCKX-iYXvj51TNe-7Oh9BWXqeVBndardc8eUDOXAPfNdysEaAAfRHyp9NecCFRK9M-a1OPvL1RLqIgQSeyc3OllrurhkLHzw2oexE7uQeuJA_I5abuIUxzv3Te0bYOhAQOzpY6zVWgFCT7mfqRX3kiV-5XmzJNylnUTEXNf-lVrbwGdP_q0YIslBwrB_3B0KvQaiPsm4E7yTlx-f5EEGKDD3EmQpZ-2R8kQugUixGjIUjxh9gMCT2FYfbw' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.25 seconds)2425server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22426server # time=2026-09-15T08:17:56.114Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2427server # [ 33.072466] systemd[1]: Started Nix Daemon instance (PID 1472/UID 0).2428server # [ 33.152713] nix-daemon[1474]: remote pid 1472 is unknown user (trusted)2429server # [ 33.167135] systemd[1]: nix-daemon@15-16-1472_1473-0.service: Deactivated successfully.2430server # [ 33.175058] niks3-server[984]: 2026/09/15 08:17:56 WARN Authentication failed token_preview=not-a-valid-jwt-token token_length=21 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2431server # time=2026-09-15T08:17:56.344Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2432server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.25 seconds)2433server: must succeed: 2434 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins create hello-pin /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.324352436server # [ 33.267303] niks3-server[984]: 2026/09/15 08:17:56 INFO Received create pin request method=POST path=/api/pins/hello-pin2437server # [ 33.275393] niks3-server[984]: 2026/09/15 08:17:56 INFO Created/updated pin name=hello-pin store_path=/nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3 narinfo_key=wzr035k31pmpn2caabq8qwv1npg571z9.narinfo2438server # time=2026-09-15T08:17:56.444Z level=INFO msg="Created pin" name=hello-pin store_path=/nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32439server: (finished: must succeed: 2440 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins create hello-pin /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32441, in 0.10 seconds)2442server: must succeed: 2443 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins list24442445server # [ 33.366284] niks3-server[984]: 2026/09/15 08:17:56 INFO Received list pins request method=GET path=/api/pins2446server: (finished: must succeed: 2447 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins list2448, in 0.09 seconds)2449server: must succeed: 2450 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins list --names-only24512452server # [ 33.455562] niks3-server[984]: 2026/09/15 08:17:56 INFO Received list pins request method=GET path=/api/pins2453server: (finished: must succeed: 2454 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins list --names-only2455, in 0.09 seconds)2456server: must succeed: 2457 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins list --json24582459server # [ 33.543283] niks3-server[984]: 2026/09/15 08:17:56 INFO Received list pins request method=GET path=/api/pins2460server: (finished: must succeed: 2461 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins list --json2462, in 0.09 seconds)2463server: must succeed: 2464 export S3_ENDPOINT_URL=http://localhost:90002465 export AWS_ACCESS_KEY_ID=rustfsadmin2466 export AWS_SECRET_ACCESS_KEY=rustfsadmin2467 /nix/store/x86g0y89gaymx0y932zhypbj1iwib7mp-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin24682469server: (finished: must succeed: 2470 export S3_ENDPOINT_URL=http://localhost:90002471 export AWS_ACCESS_KEY_ID=rustfsadmin2472 export AWS_SECRET_ACCESS_KEY=rustfsadmin2473 /nix/store/x86g0y89gaymx0y932zhypbj1iwib7mp-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin2474, in 0.04 seconds)2475server: must succeed: 2476 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --pin ca-pin /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log24772478server # [ 33.725666] systemd[1]: Started Nix Daemon instance (PID 1547/UID 0).2479server # [ 33.807027] nix-daemon[1549]: remote pid 1547 is unknown user (trusted)2480server # [ 33.820626] systemd[1]: nix-daemon@16-17-1547_1548-0.service: Deactivated successfully.2481server # [ 33.828731] niks3-server[984]: 2026/09/15 08:17:56 INFO Received uploads request method=POST path=/api/pending_closures2482server # time=2026-09-15T08:17:56.998Z level=INFO msg="Uploading 0 paths to server (1 already cached)"2483server # [ 33.833927] niks3-server[984]: 2026/09/15 08:17:56 INFO Received complete upload request method=POST path=/api/pending_closures/9/complete2484server # [ 33.836159] niks3-server[984]: 2026/09/15 08:17:57 INFO Completed upload id=92485server # time=2026-09-15T08:17:57.003Z level=INFO msg="Upload complete. (173ms)"2486server # [ 33.838182] niks3-server[984]: 2026/09/15 08:17:57 INFO Received create pin request method=POST path=/api/pins/ca-pin2487server # [ 33.845023] niks3-server[984]: 2026/09/15 08:17:57 INFO Created/updated pin name=ca-pin store_path=/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log narinfo_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.narinfo2488server # time=2026-09-15T08:17:57.013Z level=INFO msg="Created pin" name=ca-pin store_path=/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2489server: (finished: must succeed: 2490 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 push --pin ca-pin /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2491, in 0.27 seconds)2492server: must succeed: 2493 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins list --names-only24942495server # [ 33.936583] niks3-server[984]: 2026/09/15 08:17:57 INFO Received list pins request method=GET path=/api/pins2496server: (finished: must succeed: 2497 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins list --names-only2498, in 0.09 seconds)2499server: must succeed: 2500 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins delete hello-pin25012502server # [ 34.023740] niks3-server[984]: 2026/09/15 08:17:57 INFO Received delete pin request method=DELETE path=/api/pins/hello-pin2503server # [ 34.038229] niks3-server[984]: 2026/09/15 08:17:57 INFO Deleted pin name=hello-pin2504server # time=2026-09-15T08:17:57.205Z level=INFO msg="Deleted pin" name=hello-pin2505server: (finished: must succeed: 2506 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins delete hello-pin2507, in 0.10 seconds)2508server: must succeed: 2509 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins list --names-only25102511server # [ 34.127284] niks3-server[984]: 2026/09/15 08:17:57 INFO Received list pins request method=GET path=/api/pins2512server: (finished: must succeed: 2513 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins list --names-only2514, in 0.09 seconds)2515server: must fail: 2516 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent25172518server # [ 34.216358] niks3-server[984]: 2026/09/15 08:17:57 INFO Received create pin request method=POST path=/api/pins/bad-pin2519server # [ 34.218330] niks3-server[984]: 2026/09/15 08:17:57 ERROR Failed to get closure for pin narinfo_key=aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.narinfo error="no rows in result set"2520server # time=2026-09-15T08:17:57.386Z level=ERROR msg="Fatal error" error="creating pin: server returned 404: closure not found: store path must be pushed before pinning\n"2521server: (finished: must fail: 2522 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/jqx81yyqgbbs1fzi8h89lqyj4xflybcp-niks3-1.11.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent2523, in 0.09 seconds)2524server: must succeed: systemctl start niks3-gc.service2525server # [ 34.252133] systemd[1]: Starting niks3 garbage collection...2526server # [ 34.307159] niks3[1609]: time=2026-09-15T08:17:57.473Z level=INFO msg="Starting garbage collection" older-than=720h failed-uploads-older-than=6h force=false2527server # [ 34.310826] niks3-server[984]: 2026/09/15 08:17:57 INFO Starting cleanup of old closures method=DELETE path=/api/closures2528server # [ 34.313715] niks3[1609]: time=2026-09-15T08:17:57.479Z level=INFO msg="Garbage collection started"2529server # [ 34.317361] niks3-server[984]: 2026/09/15 08:17:57 INFO Aborted multipart uploads count=02530server # [ 34.325214] niks3-server[984]: 2026/09/15 08:17:57 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=3 objects-deleted-after-grace-period=0 objects-failed-to-delete=02531server # [ 34.330388] niks3-server[984]: 2026/09/15 08:17:57 INFO Vacuumed table table=pending_closures2532server # [ 34.334258] niks3-server[984]: 2026/09/15 08:17:57 INFO Vacuumed table table=pending_objects2533server # [ 34.337935] niks3-server[984]: 2026/09/15 08:17:57 INFO Vacuumed table table=multipart_uploads2534server # [ 34.340907] niks3-server[984]: 2026/09/15 08:17:57 INFO Vacuumed table table=closures2535server # [ 34.344080] niks3-server[984]: 2026/09/15 08:17:57 INFO Vacuumed table table=objects2536server # [ 36.317242] niks3[1609]: time=2026-09-15T08:17:59.482Z level=INFO msg="Garbage collection progress" phase="" failed_uploads_deleted=0 old_closures_deleted=0 objects_marked=3 objects_deleted=0 objects_failed=02537server # [ 36.323937] niks3[1609]: time=2026-09-15T08:17:59.482Z level=INFO msg="Garbage collection completed successfully" failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=3 objects-deleted-after-grace-period=0 objects-failed-to-delete=02538server # [ 36.336654] systemd[1]: niks3-gc.service: Deactivated successfully.2539server # [ 36.339650] systemd[1]: Finished niks3 garbage collection.2540server # [ 36.344585] systemd[1]: niks3-gc.service: Consumed 35ms CPU time over 2.084s wall clock time, 2.7M memory peak, 1.2K incoming IP traffic, 823B outgoing IP traffic.2541server: (finished: must succeed: systemctl start niks3-gc.service, in 2.13 seconds)2542builder: waiting for unit niks3-auto-upload.socket2543builder: waiting for the VM to finish booting2544builder: Guest shell says: b'Spawning backdoor root shell...\n'2545builder: connected to guest root shell2546builder: (connecting took 0.00 seconds)2547builder: (finished: waiting for the VM to finish booting, in 0.00 seconds)2548builder: (finished: waiting for unit niks3-auto-upload.socket, in 0.10 seconds)2549builder: must succeed: test -S /run/niks3/upload-to-cache.sock2550builder: (finished: must succeed: test -S /run/niks3/upload-to-cache.sock, in 0.02 seconds)2551builder: must succeed: grep post-build-hook /etc/nix/nix.conf2552builder: (finished: must succeed: grep post-build-hook /etc/nix/nix.conf, in 0.03 seconds)2553builder: must succeed: 2554cat > /tmp/test-drv.nix << 'EOF'2555derivation {2556 name = "post-build-hook-test";2557 system = builtins.currentSystem;2558 builder = "/bin/sh";2559 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2560}2561EOF25622563builder: (finished: must succeed: 2564cat > /tmp/test-drv.nix << 'EOF'2565derivation {2566 name = "post-build-hook-test";2567 system = builtins.currentSystem;2568 builder = "/bin/sh";2569 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2570}2571EOF2572, in 0.03 seconds)2573builder: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link2574builder # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2575builder # [ 36.670775] systemd[1]: Created slice Slice /system/nix-daemon.2576builder # [ 36.674711] systemd[1]: Started Nix Daemon instance (PID 795/UID 0).2577builder # [ 36.758974] nix-daemon[799]: remote pid 795 is unknown user (trusted)2578builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 406 ms (attempt 1/5)2579builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 977 ms (attempt 2/5)2580builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 1919 ms (attempt 3/5)2581builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 3972 ms (attempt 4/5)2582builder # warning: Failed to setup the substituter at URI 'https://cache.nixos.org/': error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6)2583builder # this derivation will be built:2584builder # /nix/store/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv2585builder # building '/nix/store/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv'...2586builder # [ 44.426477] systemd[1]: Started niks3 auto-upload daemon.2587builder # [ 44.635937] niks3-hook[822]: time=2026-09-15T08:18:07.758Z level=INFO msg="niks3-hook serve starting" socket=/run/niks3/upload-to-cache.sock socket-activated=true db-path=/var/lib/niks3-hook/upload-queue.db batch-size=5 idle-exit-timeout=5s drain-timeout=0s2588builder # [ 44.650871] niks3-hook[822]: time=2026-09-15T08:18:07.774Z level=INFO msg="Upload queue status" pending=12589builder # [ 44.652494] niks3-hook[822]: time=2026-09-15T08:18:07.774Z level=INFO msg="Uploading batch" count=12590builder: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link, in 8.12 seconds)2591builder: waiting for unit niks3-auto-upload.service2592builder # [ 44.672678] systemd[1]: nix-daemon@0-1-795_796-0.service: Deactivated successfully.2593builder # [ 44.673990] systemd[1]: nix-daemon@0-1-795_796-0.service: Consumed 145ms CPU time over 7.997s wall clock time, 17.1M memory peak, 288B outgoing IP traffic.2594builder: (finished: waiting for unit niks3-auto-upload.service, in 0.11 seconds)2595??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2596 File "/nix/store/wqwvv2w9ih8r1f0lsa0h3fqf91nblq7g-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392597builder: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive2598??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2599 File "/nix/store/wqwvv2w9ih8r1f0lsa0h3fqf91nblq7g-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392600builder # [ 44.790964] systemd[1]: Started Nix Daemon instance (PID 833/UID 0).2601builder # [ 44.878352] nix-daemon[844]: remote pid 833 is unknown user (trusted)2602builder # [ 44.893688] systemd[1]: nix-daemon@1-2-833_834-0.service: Deactivated successfully.2603server # [ 44.917517] niks3-server[984]: 2026/09/15 08:18:08 INFO Received uploads request method=POST path=/api/pending_closures2604builder # [ 44.939046] niks3-hook[822]: time=2026-09-15T08:18:08.062Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2605builder # [ 44.942147] niks3-hook[822]: time=2026-09-15T08:18:08.065Z level=INFO msg="Uploading ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test (144B)"2606server # [ 44.975659] niks3-server[984]: 2026/09/15 08:18:08 INFO Registered completed upload object_key=nar/1a0vfw93shmvlz6lac7d1lv82jxzfdswigh5s32hpvncf74i1i0y.nar.zst2607server # [ 44.981576] niks3-server[984]: 2026/09/15 08:18:08 INFO Registered completed upload object_key=log/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv2608server # [ 44.997890] niks3-server[984]: 2026/09/15 08:18:08 INFO Registered completed upload object_key=ykg16qhbiwqmava2ahq779zd0dfd9mjd.ls2609server # [ 45.001153] niks3-server[984]: 2026/09/15 08:18:08 INFO Received sign narinfos request method=POST path=/api/pending_closures/10/sign2610server # [ 45.004809] niks3-server[984]: 2026/09/15 08:18:08 INFO Signed narinfos id=10 count=12611builder # [ 45.017520] niks3-hook[822]: time=2026-09-15T08:18:08.141Z level=INFO msg="Uploading 1 narinfos"2612server # [ 45.022659] niks3-server[984]: 2026/09/15 08:18:08 INFO Registered completed upload object_key=ykg16qhbiwqmava2ahq779zd0dfd9mjd.narinfo2613server # [ 45.024417] niks3-server[984]: 2026/09/15 08:18:08 INFO Received complete upload request method=POST path=/api/pending_closures/10/complete2614server # [ 45.028524] niks3-server[984]: 2026/09/15 08:18:08 INFO Completed upload id=102615builder # [ 45.039573] niks3-hook[822]: time=2026-09-15T08:18:08.163Z level=INFO msg="Upload complete. (387ms)"2616builder # [ 49.647716] niks3-hook[822]: time=2026-09-15T08:18:12.770Z level=INFO msg="Idle timeout reached and queue is empty, shutting down"2617builder # [ 49.652590] niks3-hook[822]: time=2026-09-15T08:18:12.775Z level=INFO msg="niks3-hook serve stopped"2618builder # [ 49.667557] systemd[1]: niks3-auto-upload.service: Deactivated successfully.2619builder # [ 49.671198] systemd[1]: niks3-auto-upload.service: Consumed 129ms CPU time over 5.241s wall clock time, 9.9M memory peak, 68K written to disk, 5.1K incoming IP traffic, 7.5K outgoing IP traffic.2620builder: (finished: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive, in 5.32 seconds)2621server: must succeed: 2622 export AWS_ACCESS_KEY_ID=rustfsadmin2623export AWS_SECRET_ACCESS_KEY=rustfsadmin2624 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/hook-test-store /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test26252626server: (finished: must succeed: 2627 export AWS_ACCESS_KEY_ID=rustfsadmin2628export AWS_SECRET_ACCESS_KEY=rustfsadmin2629 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/hook-test-store /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test2630, in 0.22 seconds)2631server: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test2632server: (finished: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test, in 0.08 seconds)2633(finished: run the VM test script, in 51.41 seconds)2634test script finished in 51.53s2635cleanup2636kill QemuMachine (pid 47)2637builder # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14)2638kill QemuMachine (pid 48)2639server # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14)2640(finished: cleanup, in 0.39 seconds)2641additionally exposed symbols:2642 builder, server,2643 vlan1,2644 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_ssh2645Hello store path: /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32646Test output path: /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2647Symlink wrapper store path: /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper2648Symlink wrapper points to: /nix/store/la1lgrdh5wdf2jsxac162fx3pm4xmz7i-base-package/bin/test-program2649OIDC test store path: /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test2650Valid OIDC token obtained (length=677)2651OIDC push with valid token: SUCCESS2652Invalid OIDC token obtained (wrong org)2653OIDC push with wrong org: correctly rejected2654Wrong audience OIDC token obtained2655OIDC push with wrong audience: correctly rejected2656OIDC push with malformed token: correctly rejected2657All OIDC tests passed!2658All pin tests passed!2659Built store path: /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test2660Post-build-hook pipeline test passed!