tribuchet: building on jamie Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script start all VMs builder: starting vm server: starting vm server # Disk image does not exist, creating the virtualisation disk image... server: QEMU running (pid 48) server # Formatting '/build/vm-state-server/tmp.v1WTgdNIfh', fmt=raw size=1073741824 server # mke2fs 1.47.4 (6-Mar-2025) server # Discarding device blocks: 0/262144 done server # Creating filesystem with 262144 4k blocks and 65536 inodes server # Filesystem UUID: 6679bf37-24ce-400a-9362-d5546ab16efc server # Superblock backups stored on blocks: server # 32768, 98304, 163840, 229376 server # server # Allocating group tables: 0/8 done server # Writing inode tables: 0/8 done server # Creating journal (8192 blocks): done server # Writing superblocks and filesystem accounting information: 0/8 done server # server # Virtualisation disk image created. server # Starting virtiofs daemons... server # [2026-09-20T16:24:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) server # [2026-09-20T16:24:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether server # [2026-09-20T16:24:24Z INFO virtiofsd] Waiting for vhost-user socket connection... server # [2026-09-20T16:24:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) server # [2026-09-20T16:24:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether server # [2026-09-20T16:24:24Z INFO virtiofsd] Waiting for vhost-user socket connection... server # [2026-09-20T16:24:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) server # [2026-09-20T16:24:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether server # [2026-09-20T16:24:24Z INFO virtiofsd] Waiting for vhost-user socket connection... server # [2026-09-20T16:24:24Z INFO virtiofsd] Client connected, servicing requests server # [2026-09-20T16:24:24Z INFO virtiofsd] Client connected, servicing requests server # [2026-09-20T16:24:24Z INFO virtiofsd] Client connected, servicing requests builder # Disk image does not exist, creating the virtualisation disk image... builder: QEMU running (pid 47) builder # Formatting '/build/vm-state-builder/tmp.YbBBtkkKch', fmt=raw size=1073741824 builder # mke2fs 1.47.4 (6-Mar-2025) builder # Discarding device blocks: 0/262144 done builder # Creating filesystem with 262144 4k blocks and 65536 inodes builder # Filesystem UUID: 13b3f27a-a3e9-41f6-a945-4c19fd1ed89e builder # Superblock backups stored on blocks: builder # 32768, 98304, 163840, 229376 builder # (finished: start all VMs, in 0.25 seconds) builder # Allocating group tables: 0/8 done server: waiting for unit postgresql.service builder # Writing inode tables: 0/8 done server: waiting for the VM to finish booting builder # Creating journal (8192 blocks): done builder # Writing superblocks and filesystem accounting information: 0/8 done builder # builder # Virtualisation disk image created. builder # Starting virtiofs daemons... builder # [2026-09-20T16:24:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) builder # [2026-09-20T16:24:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether builder # [2026-09-20T16:24:24Z INFO virtiofsd] Waiting for vhost-user socket connection... builder # [2026-09-20T16:24:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) builder # [2026-09-20T16:24:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether builder # [2026-09-20T16:24:24Z INFO virtiofsd] Waiting for vhost-user socket connection... builder # [2026-09-20T16:24:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) builder # [2026-09-20T16:24:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether builder # [2026-09-20T16:24:24Z INFO virtiofsd] Waiting for vhost-user socket connection... builder # [2026-09-20T16:24:24Z INFO virtiofsd] Client connected, servicing requests builder # [2026-09-20T16:24:24Z INFO virtiofsd] Client connected, servicing requests builder # [2026-09-20T16:24:24Z INFO virtiofsd] Client connected, servicing requests builder # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) server # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) builder # builder # builder # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA00 builder # Press Ctrl-B to configure iPXE (PCI 00:02.0)... builder # builder # builder # builder # builder # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB00 builder # Press Ctrl-B to configure iPXE (PCI 00:05.0)... builder # builder # server # server # server # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA00 server # Press Ctrl-B to configure iPXE (PCI 00:02.0)... server # server # server # server # server # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB00 server # Press Ctrl-B to configure iPXE (PCI 00:05.0)... server # server # builder # Booting from ROM... server # Booting from ROM... server # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.51 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 11 09:49:46 UTC 2026 builder # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.51 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 11 09:49:46 UTC 2026 server # [ 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/gd8jdfspmhwwrcj2i60j73dnjdx677vh-nixos-system-server-test/init regInfo=/nix/store/z65fnbi9cr18rai2m782g5p1qny7ky4r-closure-info/registration console=ttyS0,115200n8 console=tty0 server # [ 0.000000] BIOS-provided physical RAM map: builder # [ 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/5rlfcjmnnixczg27wrrplh25f8najxw4-nixos-system-builder-test/init regInfo=/nix/store/8i0qhk0fbbmcrn2r4g7jfi9a2w5cafjv-closure-info/registration console=ttyS0,115200n8 console=tty0 builder # [ 0.000000] BIOS-provided physical RAM map: server # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable builder # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable server # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved builder # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved server # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved builder # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved server # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable builder # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable server # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved builder # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved server # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved builder # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved server # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved builder # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved server # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved builder # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved server # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved builder # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved server # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved server # [ 0.000000] NX (Execute Disable) protection: active builder # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved server # [ 0.000000] APIC: Static calls initialized builder # [ 0.000000] NX (Execute Disable) protection: active server # [ 0.000000] SMBIOS 2.8 present. builder # [ 0.000000] APIC: Static calls initialized builder # [ 0.000000] SMBIOS 2.8 present. server # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 server # [ 0.000000] DMI: Memory slots populated: 1/1 builder # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 server # [ 0.000000] Hypervisor detected: KVM builder # [ 0.000000] DMI: Memory slots populated: 1/1 server # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 builder # [ 0.000000] Hypervisor detected: KVM server # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 builder # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 server # [ 0.000000] kvm-clock: using sched offset of 491624872 cycles builder # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 builder # [ 0.000001] kvm-clock: using sched offset of 481059355 cycles server # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns server # [ 0.000004] tsc: Detected 2400.012 MHz processor builder # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns server # [ 0.000809] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 builder # [ 0.000005] tsc: Detected 2400.012 MHz processor builder # [ 0.000812] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 server # [ 0.000837] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs server # [ 0.000840] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT builder # [ 0.000841] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs server # [ 0.002753] found SMP MP-table at [mem 0x000f5450-0x000f545f] builder # [ 0.000843] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT server # [ 0.002763] Using GB pages for direct mapping builder # [ 0.002737] found SMP MP-table at [mem 0x000f5450-0x000f545f] server # [ 0.002807] RAMDISK: [mem 0x3e36c000-0x3ffcffff] builder # [ 0.002749] Using GB pages for direct mapping server # [ 0.002814] ACPI: Early table checksum verification disabled builder # [ 0.002834] RAMDISK: [mem 0x3e36c000-0x3ffcffff] server # [ 0.002817] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) builder # [ 0.002842] ACPI: Early table checksum verification disabled builder # [ 0.002845] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) server # [ 0.002821] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) builder # [ 0.002849] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) server # [ 0.002825] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) builder # [ 0.002853] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) server # [ 0.002832] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) server # [ 0.002833] ACPI: FACS 0x000000003FFE0000 000040 builder # [ 0.002861] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) builder # [ 0.002863] ACPI: FACS 0x000000003FFE0000 000040 server # [ 0.002835] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) builder # [ 0.002864] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) server # [ 0.002837] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) builder # [ 0.002866] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) server # [ 0.002838] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) builder # [ 0.002867] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) server # [ 0.002840] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) server # [ 0.002841] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] builder # [ 0.002869] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) server # [ 0.002842] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] builder # [ 0.002870] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] server # [ 0.002843] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] builder # [ 0.002871] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] server # [ 0.002843] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] builder # [ 0.002872] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] server # [ 0.002844] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] builder # [ 0.002872] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] server # [ 0.002844] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] builder # [ 0.002873] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] server # [ 0.002845] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] builder # [ 0.002873] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] server # [ 0.003062] No NUMA configuration found builder # [ 0.002874] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] server # [ 0.003063] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] builder # [ 0.003107] No NUMA configuration found server # [ 0.003065] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] server # [ 0.005322] Zone ranges: builder # [ 0.003108] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] server # [ 0.005323] DMA [mem 0x0000000000001000-0x0000000000ffffff] builder # [ 0.003110] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] builder # [ 0.005492] Zone ranges: server # [ 0.005324] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] server # [ 0.005325] Normal empty builder # [ 0.005493] DMA [mem 0x0000000000001000-0x0000000000ffffff] server # [ 0.005326] Device empty builder # [ 0.005495] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] server # [ 0.005326] Movable zone start for each node builder # [ 0.005496] Normal empty server # [ 0.005327] Early memory node ranges builder # [ 0.005497] Device empty builder # [ 0.005497] Movable zone start for each node server # [ 0.005328] node 0: [mem 0x0000000000001000-0x000000000009efff] builder # [ 0.005498] Early memory node ranges server # [ 0.005329] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] builder # [ 0.005498] node 0: [mem 0x0000000000001000-0x000000000009efff] server # [ 0.005330] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] builder # [ 0.005499] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] server # [ 0.005349] On node 0, zone DMA: 1 pages in unavailable ranges builder # [ 0.005500] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] server # [ 0.005617] On node 0, zone DMA: 97 pages in unavailable ranges builder # [ 0.005521] On node 0, zone DMA: 1 pages in unavailable ranges server # [ 0.022665] On node 0, zone DMA32: 40 pages in unavailable ranges server # [ 0.023107] ACPI: PM-Timer IO Port: 0x608 builder # [ 0.005803] On node 0, zone DMA: 97 pages in unavailable ranges server # [ 0.023117] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) builder # [ 0.024523] On node 0, zone DMA32: 40 pages in unavailable ranges builder # [ 0.024988] ACPI: PM-Timer IO Port: 0x608 server # [ 0.023141] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 builder # [ 0.024999] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) server # [ 0.023143] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) builder # [ 0.025029] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 server # [ 0.023145] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) builder # [ 0.025032] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) server # [ 0.023146] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) builder # [ 0.025033] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) server # [ 0.023147] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) builder # [ 0.025034] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) server # [ 0.023148] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) builder # [ 0.025035] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) server # [ 0.023150] ACPI: Using ACPI (MADT) for SMP configuration information builder # [ 0.025036] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) server # [ 0.023151] ACPI: HPET id: 0x8086a201 base: 0xfed00000 server # [ 0.023154] TSC deadline timer available builder # [ 0.025038] ACPI: Using ACPI (MADT) for SMP configuration information server # [ 0.023158] CPU topo: Max. logical packages: 1 builder # [ 0.025039] ACPI: HPET id: 0x8086a201 base: 0xfed00000 server # [ 0.023158] CPU topo: Max. logical dies: 1 builder # [ 0.025042] TSC deadline timer available server # [ 0.023159] CPU topo: Max. dies per package: 1 builder # [ 0.025046] CPU topo: Max. logical packages: 1 server # [ 0.023162] CPU topo: Max. threads per core: 1 builder # [ 0.025047] CPU topo: Max. logical dies: 1 server # [ 0.023163] CPU topo: Num. cores per package: 1 builder # [ 0.025047] CPU topo: Max. dies per package: 1 server # [ 0.023163] CPU topo: Num. threads per package: 1 builder # [ 0.025051] CPU topo: Max. threads per core: 1 builder # [ 0.025051] CPU topo: Num. cores per package: 1 server # [ 0.023163] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs builder # [ 0.025052] CPU topo: Num. threads per package: 1 server # [ 0.023178] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() builder # [ 0.025052] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs server # [ 0.023206] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] builder # [ 0.025068] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() server # [ 0.023207] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] builder # [ 0.025099] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] server # [ 0.023209] [mem 0x40000000-0xafffffff] available for PCI devices server # [ 0.023210] Booting paravirtualized kernel on KVM builder # [ 0.025101] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] builder # [ 0.025102] [mem 0x40000000-0xafffffff] available for PCI devices server # [ 0.023212] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns builder # [ 0.025104] Booting paravirtualized kernel on KVM server # [ 0.027648] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 builder # [ 0.025107] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns server # [ 0.029751] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 builder # [ 0.029564] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 server # [ 0.029792] kvm-guest: PV spinlocks disabled, single CPU builder # [ 0.031847] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 builder # [ 0.031892] kvm-guest: PV spinlocks disabled, single CPU server # [ 0.029793] 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/gd8jdfspmhwwrcj2i60j73dnjdx677vh-nixos-system-server-test/init regInfo=/nix/store/z65fnbi9cr18rai2m782g5p1qny7ky4r-closure-info/registration console=ttyS0,115200n8 console=tty0 builder # [ 0.031893] 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/5rlfcjmnnixczg27wrrplh25f8najxw4-nixos-system-builder-test/init regInfo=/nix/store/8i0qhk0fbbmcrn2r4g7jfi9a2w5cafjv-closure-info/registration console=ttyS0,115200n8 console=tty0 server # [ 0.029881] Unknown kernel command line parameters "regInfo=/nix/store/z65fnbi9cr18rai2m782g5p1qny7ky4r-closure-info/registration", will be passed to user space. server # [ 0.029894] random: crng init done server # [ 0.029895] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes builder # [ 0.031987] Unknown kernel command line parameters "regInfo=/nix/store/8i0qhk0fbbmcrn2r4g7jfi9a2w5cafjv-closure-info/registration", will be passed to user space. builder # [ 0.031999] random: crng init done server # [ 0.030930] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) builder # [ 0.031999] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes server # [ 0.030943] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) server # [ 0.030972] Fallback order for Node 0: 0 builder # [ 0.033118] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) server # [ 0.030974] Built 1 zonelists, mobility grouping on. Total pages: 262006 builder # [ 0.033130] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) server # [ 0.030975] Policy zone: DMA32 builder # [ 0.033161] Fallback order for Node 0: 0 server # [ 0.033507] mem auto-init: stack:all(zero), heap alloc:on, heap free:off builder # [ 0.033163] Built 1 zonelists, mobility grouping on. Total pages: 262006 builder # [ 0.033164] Policy zone: DMA32 server # [ 0.035824] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 server # [ 0.037972] allocated 2097152 bytes of page_ext builder # [ 0.036016] mem auto-init: stack:all(zero), heap alloc:on, heap free:off server # [ 0.047245] ftrace: allocating 48736 entries in 192 pages builder # [ 0.038531] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 server # [ 0.047247] ftrace: allocated 192 pages with 2 groups builder # [ 0.040887] allocated 2097152 bytes of page_ext server # [ 0.048055] Dynamic Preempt: lazy builder # [ 0.050750] ftrace: allocating 48736 entries in 192 pages server # [ 0.048211] rcu: Preemptible hierarchical RCU implementation. builder # [ 0.050752] ftrace: allocated 192 pages with 2 groups server # [ 0.048212] rcu: RCU event tracing is enabled. builder # [ 0.051646] Dynamic Preempt: lazy server # [ 0.048212] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. builder # [ 0.051765] rcu: Preemptible hierarchical RCU implementation. server # [ 0.048214] Trampoline variant of Tasks RCU enabled. builder # [ 0.051766] rcu: RCU event tracing is enabled. server # [ 0.048214] Rude variant of Tasks RCU enabled. builder # [ 0.051767] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. server # [ 0.048215] Tracing variant of Tasks RCU enabled. builder # [ 0.051768] Trampoline variant of Tasks RCU enabled. builder # [ 0.051769] Rude variant of Tasks RCU enabled. server # [ 0.048215] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. builder # [ 0.051769] Tracing variant of Tasks RCU enabled. server # [ 0.048216] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 builder # [ 0.051770] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. server # [ 0.048229] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. builder # [ 0.051770] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 server # [ 0.048231] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. builder # [ 0.051829] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. server # [ 0.048232] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. builder # [ 0.051830] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. server # [ 0.052644] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 builder # [ 0.051831] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. server # [ 0.052910] rcu: srcu_init: Setting srcu_struct sizes based on contention. builder # [ 0.056233] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 server # [ 0.052915] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns builder # [ 0.056526] rcu: srcu_init: Setting srcu_struct sizes based on contention. server # [ 0.053014] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) builder # [ 0.056534] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns server # [ 0.056538] Console: colour VGA+ 80x25 server # [ 0.056540] printk: legacy console [tty0] enabled builder # [ 0.056651] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) server # [ 0.086062] printk: legacy console [ttyS0] enabled builder # [ 0.060222] Console: colour VGA+ 80x25 server # [ 0.186402] ACPI: Core revision 20250807 builder # [ 0.060225] printk: legacy console [tty0] enabled builder # [ 0.089843] printk: legacy console [ttyS0] enabled builder # [ 0.191058] ACPI: Core revision 20250807 server # [ 0.187302] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns server # [ 0.188835] APIC: Switch to symmetric I/O mode setup builder # [ 0.192034] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns server # [ 0.189881] x2apic enabled builder # [ 0.193614] APIC: Switch to symmetric I/O mode setup server # [ 0.190617] APIC: Switched APIC routing to: physical x2apic builder # [ 0.194670] x2apic enabled builder # [ 0.195425] APIC: Switched APIC routing to: physical x2apic server # [ 0.192463] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 builder # [ 0.197315] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 server # [ 0.193472] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns server # [ 0.195210] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012) builder # [ 0.198340] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns server # [ 0.196293] x86/cpu: User Mode Instruction Prevention (UMIP) activated builder # [ 0.200058] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012) server # [ 0.198236] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 builder # [ 0.201143] x86/cpu: User Mode Instruction Prevention (UMIP) activated server # [ 0.199186] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 builder # [ 0.203182] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 builder # [ 0.204055] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 server # [ 0.200212] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto server # [ 0.201208] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl builder # [ 0.205059] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto server # [ 0.203208] Transient Scheduler Attacks: Vulnerable: No microcode builder # [ 0.206055] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl server # [ 0.204207] Spectre V2 : Mitigation: Enhanced / Automatic IBRS builder # [ 0.208055] Transient Scheduler Attacks: Vulnerable: No microcode server # [ 0.205207] Speculative Return Stack Overflow: Mitigation: Safe RET builder # [ 0.209054] Spectre V2 : Mitigation: Enhanced / Automatic IBRS builder # [ 0.210055] Speculative Return Stack Overflow: Mitigation: Safe RET server # [ 0.206207] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization server # [ 0.208214] Spectre V2 : Enabling IBPB for BPF builder # [ 0.211054] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization server # [ 0.209208] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier builder # [ 0.213062] Spectre V2 : Enabling IBPB for BPF server # [ 0.210208] active return thunk: srso_alias_return_thunk builder # [ 0.214055] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier server # [ 0.211229] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' builder # [ 0.215055] active return thunk: srso_alias_return_thunk server # [ 0.212208] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' builder # [ 0.216077] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' server # [ 0.213207] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' builder # [ 0.217055] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' server # [ 0.214207] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' builder # [ 0.218806] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' server # [ 0.216002] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' builder # [ 0.219816] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' server # [ 0.216984] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' builder # [ 0.220854] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' server # [ 0.218032] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' builder # [ 0.221802] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' server # [ 0.219208] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 builder # [ 0.222853] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' server # [ 0.220207] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 builder # [ 0.224055] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 server # [ 0.221207] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 builder # [ 0.225055] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 server # [ 0.222207] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 builder # [ 0.226055] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 server # [ 0.223208] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 builder # [ 0.227055] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 builder # [ 0.228055] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 server # [ 0.224207] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. builder # [ 0.229054] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. server # [ 0.258013] Freeing SMP alternatives memory: 44K server # [ 0.258795] pid_max: default: 32768 minimum: 301 server # [ 0.259306] LSM: initializing lsm=capability,landlock,yama,bpf,ima server # [ 0.260312] landlock: Up and running. builder # [ 0.264322] Freeing SMP alternatives memory: 44K server # [ 0.261208] Yama: becoming mindful. builder # [ 0.265058] pid_max: default: 32768 minimum: 301 server # [ 0.262410] LSM support for eBPF active builder # [ 0.265903] LSM: initializing lsm=capability,landlock,yama,bpf,ima server # [ 0.263167] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) builder # [ 0.267165] landlock: Up and running. builder # [ 0.267771] Yama: becoming mindful. server # [ 0.264227] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) builder # [ 0.268270] LSM support for eBPF active builder # [ 0.269168] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) server # [ 0.266763] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1) builder # [ 0.270077] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) server # [ 0.267756] Performance Events: Fam17h+ core perfctr, AMD PMU driver. server # [ 0.268212] ... version: 2 builder # [ 0.273016] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1) server # [ 0.268940] ... bit width: 48 server # [ 0.269210] ... generic counters: 6 builder # [ 0.273644] Performance Events: Fam17h+ core perfctr, AMD PMU driver. server # [ 0.269983] ... generic bitmap: 000000000000003f builder # [ 0.274060] ... version: 2 server # [ 0.270216] ... fixed-purpose counters: 0 builder # [ 0.274757] ... bit width: 48 server # [ 0.270917] ... fixed-purpose bitmap: 0000000000000000 builder # [ 0.275057] ... generic counters: 6 server # [ 0.271211] ... value mask: 0000ffffffffffff builder # [ 0.275786] ... generic bitmap: 000000000000003f server # [ 0.272148] ... max period: 00007fffffffffff builder # [ 0.276068] ... fixed-purpose counters: 0 builder # [ 0.276760] ... fixed-purpose bitmap: 0000000000000000 server # [ 0.272916] ... global_ctrl mask: 000000000000003f server # [ 0.273306] signal: max sigframe size: 3376 builder # [ 0.277057] ... value mask: 0000ffffffffffff server # [ 0.274110] rcu: Hierarchical SRCU implementation. builder # [ 0.277943] ... max period: 00007fffffffffff server # [ 0.274839] rcu: Max phase no-delay instances is 400. builder # [ 0.278751] ... global_ctrl mask: 000000000000003f builder # [ 0.279173] signal: max sigframe size: 3376 builder # [ 0.279980] rcu: Hierarchical SRCU implementation. builder # [ 0.280671] rcu: Max phase no-delay instances is 400. server # [ 0.279706] smp: Bringing up secondary CPUs ... server # [ 0.280224] smp: Brought up 1 node, 1 CPU server # [ 0.280923] smpboot: Total of 1 processors activated (4800.02 BogoMIPS) builder # [ 0.285663] smp: Bringing up secondary CPUs ... builder # [ 0.286071] smp: Brought up 1 node, 1 CPU server # [ 0.281353] Memory: 941040K/1048024K available (17231K kernel code, 2726K rwdata, 13600K rodata, 3644K init, 2988K bss, 99596K reserved, 0K cma-reserved) builder # [ 0.286736] smpboot: Total of 1 processors activated (4800.02 BogoMIPS) server # [ 0.282435] devtmpfs: initialized server # [ 0.283304] x86/mm: Memory block size: 128MB builder # [ 0.287310] Memory: 941040K/1048024K available (17231K kernel code, 2726K rwdata, 13600K rodata, 3644K init, 2988K bss, 99596K reserved, 0K cma-reserved) builder # [ 0.288308] devtmpfs: initialized server # [ 0.285037] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) builder # [ 0.289151] x86/mm: Memory block size: 128MB server # [ 0.286181] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). server # [ 0.287292] pinctrl core: initialized pinctrl subsystem builder # [ 0.290933] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) server # [ 0.288344] PM: RTC time: 16:24:24, date: 2026-09-20 builder # [ 0.291959] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). builder # [ 0.293137] pinctrl core: initialized pinctrl subsystem builder # [ 0.294306] PM: RTC time: 16:24:24, date: 2026-09-20 server # [ 0.292173] NET: Registered PF_NETLINK/PF_ROUTE protocol family server # [ 0.293299] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations server # [ 0.294226] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations builder # [ 0.298139] NET: Registered PF_NETLINK/PF_ROUTE protocol family server # [ 0.295360] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations builder # [ 0.299413] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations server # [ 0.296218] audit: initializing netlink subsys (disabled) builder # [ 0.300074] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations server # [ 0.297393] thermal_sys: Registered thermal governor 'fair_share' builder # [ 0.301210] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations server # [ 0.297395] thermal_sys: Registered thermal governor 'bang_bang' builder # [ 0.302067] audit: initializing netlink subsys (disabled) server # [ 0.298212] thermal_sys: Registered thermal governor 'step_wise' builder # [ 0.303162] thermal_sys: Registered thermal governor 'fair_share' server # [ 0.299175] thermal_sys: Registered thermal governor 'user_space' builder # [ 0.303164] thermal_sys: Registered thermal governor 'bang_bang' server # [ 0.299990] audit: type=2000 audit(1789921465.274:1): state=initialized audit_enabled=0 res=1 builder # [ 0.304058] thermal_sys: Registered thermal governor 'step_wise' server # [ 0.302213] thermal_sys: Registered thermal governor 'power_allocator' builder # [ 0.305006] thermal_sys: Registered thermal governor 'user_space' server # [ 0.302239] cpuidle: using governor menu builder # [ 0.305791] audit: type=2000 audit(1789921465.276:1): state=initialized audit_enabled=0 res=1 server # [ 0.304429] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 builder # [ 0.307060] thermal_sys: Registered thermal governor 'power_allocator' builder # [ 0.307072] cpuidle: using governor menu server # [ 0.305481] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] builder # [ 0.309281] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 server # [ 0.306213] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry server # [ 0.307222] PCI: Using configuration type 1 for base access builder # [ 0.310280] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] builder # [ 0.311060] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry server # [ 0.308292] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. builder # [ 0.312069] PCI: Using configuration type 1 for base access builder # [ 0.313191] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. server # [ 0.315399] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages server # [ 0.316211] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page builder # [ 0.320338] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages builder # [ 0.321058] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page server # [ 0.321211] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages server # [ 0.322211] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page builder # [ 0.326058] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages builder # [ 0.327058] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page server # [ 0.332613] ACPI: Added _OSI(Module Device) builder # [ 0.335688] ACPI: Added _OSI(Module Device) server # [ 0.333212] ACPI: Added _OSI(Processor Device) builder # [ 0.336059] ACPI: Added _OSI(Processor Device) server # [ 0.333965] ACPI: Added _OSI(Processor Aggregator Device) builder # [ 0.336799] ACPI: Added _OSI(Processor Aggregator Device) server # [ 0.340734] ACPI: 1 ACPI AML tables successfully acquired and loaded builder # [ 0.343606] ACPI: 1 ACPI AML tables successfully acquired and loaded builder # [ 0.346965] ACPI: Interpreter enabled server # [ 0.344610] ACPI: Interpreter enabled builder # [ 0.347514] ACPI: PM: (supports S0 S3 S4 S5) server # [ 0.345224] ACPI: PM: (supports S0 S3 S4 S5) builder # [ 0.348058] ACPI: Using IOAPIC for interrupt routing server # [ 0.345970] ACPI: Using IOAPIC for interrupt routing builder # [ 0.348914] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug server # [ 0.348260] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug server # [ 0.349211] PCI: Using E820 reservations for host bridge windows builder # [ 0.354059] PCI: Using E820 reservations for host bridge windows builder # [ 0.355180] ACPI: Enabled 2 GPEs in block 00 to 3F server # [ 0.352346] ACPI: Enabled 2 GPEs in block 00 to 3F server # [ 0.359614] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) builder # [ 0.363824] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) server # [ 0.360217] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] server # [ 0.361300] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] builder # [ 0.365380] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] server # [ 0.362333] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] builder # [ 0.366134] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] server # [ 0.363549] PCI host bridge to bus 0000:00 builder # [ 0.367180] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] server # [ 0.364215] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] builder # [ 0.368423] PCI host bridge to bus 0000:00 server # [ 0.365210] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] builder # [ 0.369062] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] server # [ 0.366210] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] builder # [ 0.370057] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] server # [ 0.367210] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] builder # [ 0.371057] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] server # [ 0.368210] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] builder # [ 0.372057] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] server # [ 0.369210] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] builder # [ 0.373057] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] server # [ 0.370210] pci_bus 0000:00: root bus resource [bus 00-ff] builder # [ 0.374057] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] builder # [ 0.375058] pci_bus 0000:00: root bus resource [bus 00-ff] server # [ 0.371202] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint builder # [ 0.376027] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint server # [ 0.372638] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint builder # [ 0.377499] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint server # [ 0.375337] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] server # [ 0.376262] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] server # [ 0.377232] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] builder # [ 0.380164] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] builder # [ 0.381070] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] server # [ 0.378523] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] builder # [ 0.382013] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] server # [ 0.379918] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint builder # [ 0.383464] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] builder # [ 0.384784] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint server # [ 0.382248] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f] server # [ 0.383117] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] server # [ 0.384029] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] builder # [ 0.388065] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f] server # [ 0.385137] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] builder # [ 0.388892] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] builder # [ 0.389819] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] server # [ 0.386546] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint builder # [ 0.390063] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] server # [ 0.388244] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] builder # [ 0.392147] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint server # [ 0.389110] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] server # [ 0.389975] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] builder # [ 0.394985] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] server # [ 0.390792] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint builder # [ 0.395692] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] builder # [ 0.396079] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] builder # [ 0.397661] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint server # [ 0.393250] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f] server # [ 0.394217] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] server # [ 0.395232] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] builder # [ 0.400066] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f] server # [ 0.396799] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint builder # [ 0.401678] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] builder # [ 0.402079] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] server # [ 0.398218] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f] server # [ 0.399112] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] builder # [ 0.403615] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint server # [ 0.400003] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] server # [ 0.401165] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref] builder # [ 0.406065] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f] builder # [ 0.406946] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] server # [ 0.402749] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint builder # [ 0.407757] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] server # [ 0.404024] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] builder # [ 0.408064] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref] server # [ 0.405021] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] builder # [ 0.409600] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint server # [ 0.406638] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint builder # [ 0.411070] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] server # [ 0.408255] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] builder # [ 0.412010] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] server # [ 0.409232] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] builder # [ 0.413442] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint server # [ 0.411310] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint builder # [ 0.415070] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] server # [ 0.413000] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] server # [ 0.413999] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] builder # [ 0.416023] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] server # [ 0.415668] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint builder # [ 0.417670] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint server # [ 0.417223] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] builder # [ 0.419070] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] server # [ 0.418215] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] builder # [ 0.420026] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] builder # [ 0.421467] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint server # [ 0.419775] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint server # [ 0.421230] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf] builder # [ 0.423071] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] server # [ 0.422117] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] builder # [ 0.424081] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] server # [ 0.423029] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] builder # [ 0.425604] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint server # [ 0.424652] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint builder # [ 0.427852] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf] server # [ 0.426249] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f] builder # [ 0.428720] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] server # [ 0.427217] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] builder # [ 0.429079] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] server # [ 0.428232] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] builder # [ 0.430603] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint server # [ 0.429808] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint server # [ 0.430772] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f] builder # [ 0.433065] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f] server # [ 0.431420] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint builder # [ 0.433886] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] server # [ 0.432746] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf] builder # [ 0.434816] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] server # [ 0.433419] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint builder # [ 0.435613] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint server # [ 0.434789] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df] builder # [ 0.436807] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f] builder # [ 0.437262] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint builder # [ 0.438754] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf] builder # [ 0.439277] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint builder # [ 0.440862] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df] server # [ 0.435456] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint server # [ 0.437760] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] server # [ 0.438496] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint server # [ 0.439494] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO server # [ 0.440457] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint builder # [ 0.441953] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint server # [ 0.442091] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff] builder # [ 0.443770] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] server # [ 0.442917] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] builder # [ 0.444328] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint server # [ 0.443535] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint builder # [ 0.445354] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO server # [ 0.444976] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] builder # [ 0.446302] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint builder # [ 0.448085] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff] builder # [ 0.448951] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] server # [ 0.448329] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 server # [ 0.449324] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 builder # [ 0.450084] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint server # [ 0.450312] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 builder # [ 0.451801] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] server # [ 0.451309] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 server # [ 0.452306] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 server # [ 0.453311] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 server # [ 0.454323] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 server # [ 0.455307] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 builder # [ 0.455743] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 server # [ 0.456246] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 builder # [ 0.456165] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 server # [ 0.457225] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 builder # [ 0.457154] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 server # [ 0.458228] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 builder # [ 0.458157] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 server # [ 0.459202] ACPI: PCI: Interrupt link GSID configured for IRQ 19 builder # [ 0.459152] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 server # [ 0.460194] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 builder # [ 0.460152] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 server # [ 0.460949] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 builder # [ 0.461156] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 server # [ 0.461225] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 builder # [ 0.462154] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 server # [ 0.462223] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 builder # [ 0.463093] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 builder # [ 0.464073] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 server # [ 0.464220] iommu: Default domain type: Translated builder # [ 0.465069] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 server # [ 0.465050] iommu: DMA domain TLB invalidation policy: lazy mode builder # [ 0.466077] ACPI: PCI: Interrupt link GSID configured for IRQ 19 server # [ 0.466210] ACPI: bus type USB registered builder # [ 0.467070] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 server # [ 0.466967] usbcore: registered new interface driver usbfs builder # [ 0.468046] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 server # [ 0.467226] usbcore: registered new interface driver hub builder # [ 0.468803] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 server # [ 0.468096] usbcore: registered new device driver usb builder # [ 0.469072] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 server # [ 0.469839] NetLabel: Initializing server # [ 0.470211] NetLabel: domain hash size = 128 builder # [ 0.471151] iommu: Default domain type: Translated server # [ 0.470954] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO builder # [ 0.471960] iommu: DMA domain TLB invalidation policy: lazy mode server # [ 0.471245] NetLabel: unlabeled traffic allowed by default builder # [ 0.473065] ACPI: bus type USB registered server # [ 0.472185] PCI: Using ACPI for IRQ routing builder # [ 0.473807] usbcore: registered new interface driver usbfs builder # [ 0.474077] usbcore: registered new interface driver hub builder # [ 0.474973] usbcore: registered new device driver usb builder # [ 0.476703] NetLabel: Initializing builder # [ 0.477058] NetLabel: domain hash size = 128 builder # [ 0.477776] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO builder # [ 0.478091] NetLabel: unlabeled traffic allowed by default builder # [ 0.479025] PCI: Using ACPI for IRQ routing server # [ 0.516505] pci 0000:00:01.0: vgaarb: setting as boot VGA device server # [ 0.517206] pci 0000:00:01.0: vgaarb: bridge control possible server # [ 0.517206] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none server # [ 0.517216] vgaarb: loaded server # [ 0.517989] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 server # [ 0.518212] hpet0: 3 comparators, 64-bit 100.000000 MHz counter builder # [ 0.522618] pci 0000:00:01.0: vgaarb: setting as boot VGA device builder # [ 0.523053] pci 0000:00:01.0: vgaarb: bridge control possible server # [ 0.522287] clocksource: Switched to clocksource kvm-clock builder # [ 0.523053] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none builder # [ 0.523062] vgaarb: loaded builder # [ 0.523828] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 builder # [ 0.524060] hpet0: 3 comparators, 64-bit 100.000000 MHz counter server # [ 0.525741] VFS: Disk quotas dquot_6.6.0 server # [ 0.526462] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) server # [ 0.527852] pnp: PnP ACPI init builder # [ 0.526144] clocksource: Switched to clocksource kvm-clock server # [ 0.528660] ACPI: IRQ 4 override to edge(!), high(!) server # [ 0.529627] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved server # [ 0.531220] pnp: PnP ACPI: found 5 devices builder # [ 0.529693] VFS: Disk quotas dquot_6.6.0 builder # [ 0.530387] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) builder # [ 0.531742] pnp: PnP ACPI init builder # [ 0.532504] ACPI: IRQ 4 override to edge(!), high(!) builder # [ 0.533453] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved builder # [ 0.535000] pnp: PnP ACPI: found 5 devices server # [ 0.538783] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns server # [ 0.540358] clocksource: Switched to clocksource acpi_pm server # [ 0.541383] NET: Registered PF_INET protocol family server # [ 0.542460] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) builder # [ 0.542600] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns builder # [ 0.544067] clocksource: Switched to clocksource acpi_pm builder # [ 0.545056] NET: Registered PF_INET protocol family builder # [ 0.546108] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) server # [ 0.559563] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) server # [ 0.561134] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) server # [ 0.562544] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) server # [ 0.563941] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) server # [ 0.565224] TCP: Hash tables configured (established 8192 bind 8192) builder # [ 0.562925] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) server # [ 0.566336] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) builder # [ 0.564421] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) server # [ 0.567662] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) builder # [ 0.565725] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) server # [ 0.568817] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) builder # [ 0.567029] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) server # [ 0.570064] NET: Registered PF_UNIX/PF_LOCAL protocol family builder # [ 0.568226] TCP: Hash tables configured (established 8192 bind 8192) server # [ 0.571076] NET: Registered PF_XDP protocol family builder # [ 0.569297] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) server # [ 0.571977] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] builder # [ 0.570579] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) server # [ 0.573020] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] builder # [ 0.571678] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) server # [ 0.574050] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] builder # [ 0.572846] NET: Registered PF_UNIX/PF_LOCAL protocol family server # [ 0.575174] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] builder # [ 0.573880] NET: Registered PF_XDP protocol family server # [ 0.576381] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] builder # [ 0.574714] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] server # [ 0.577516] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] builder # [ 0.575689] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] builder # [ 0.576667] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] server # [ 0.579251] ACPI: \_SB_.GSIA: Enabled at IRQ 16 builder # [ 0.577761] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] builder # [ 0.578876] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] server # [ 0.581482] ACPI: \_SB_.GSIB: Enabled at IRQ 17 builder # [ 0.579967] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] server # [ 0.583407] ACPI: \_SB_.GSIC: Enabled at IRQ 18 builder # [ 0.581731] ACPI: \_SB_.GSIA: Enabled at IRQ 16 server # [ 0.585419] ACPI: \_SB_.GSID: Enabled at IRQ 19 builder # [ 0.584353] ACPI: \_SB_.GSIB: Enabled at IRQ 17 server # [ 0.587083] PCI: CLS 0 bytes, default 64 builder # [ 0.586306] ACPI: \_SB_.GSIC: Enabled at IRQ 18 server # [ 0.587977] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns server # [ 0.589756] Trying to unpack rootfs image as initramfs... builder # [ 0.588278] ACPI: \_SB_.GSID: Enabled at IRQ 19 builder # [ 0.589979] PCI: CLS 0 bytes, default 64 builder # [ 0.590905] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns builder # [ 0.592652] Trying to unpack rootfs image as initramfs... server # [ 0.632273] Initialise system trusted keyrings server # [ 0.635372] workingset: timestamp_bits=40 max_order=18 bucket_order=0 builder # [ 0.636444] Initialise system trusted keyrings builder # [ 0.638597] workingset: timestamp_bits=40 max_order=18 bucket_order=0 server # [ 0.656672] Key type asymmetric registered server # [ 0.659324] Asymmetric key parser 'x509' registered server # [ 0.660217] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) server # [ 0.663426] io scheduler mq-deadline registered builder # [ 0.661605] Key type asymmetric registered server # [ 0.664251] io scheduler kyber registered builder # [ 0.662317] Asymmetric key parser 'x509' registered builder # [ 0.663174] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) server # [ 0.666826] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled server # [ 0.668132] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A builder # [ 0.666693] io scheduler mq-deadline registered builder # [ 0.667451] io scheduler kyber registered builder # [ 0.670103] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled builder # [ 0.671390] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A server # [ 0.674080] Linux agpgart interface v0.103 server # [ 0.674838] ACPI: bus type drm_connector registered server # [ 0.677715] usbcore: registered new interface driver usbserial_generic server # [ 0.678833] usbserial: USB Serial support registered for generic builder # [ 0.677280] Linux agpgart interface v0.103 builder # [ 0.678054] ACPI: bus type drm_connector registered server # [ 0.681332] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. server # [ 0.681332] Please enable it if your BIOS has the CPPC option. builder # [ 0.681010] usbcore: registered new interface driver usbserial_generic server # [ 0.683766] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled builder # [ 0.682093] usbserial: USB Serial support registered for generic builder # [ 0.684562] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. server # [ 0.687466] drop_monitor: Initializing network drop monitor service builder # [ 0.684562] Please enable it if your BIOS has the CPPC option. server # [ 0.688605] NET: Registered PF_INET6 protocol family builder # [ 0.686760] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled server # [ 0.693745] Segment Routing with IPv6 builder # [ 0.691745] drop_monitor: Initializing network drop monitor service server # [ 0.694425] In-situ OAM (IOAM) with IPv6 builder # [ 0.692875] NET: Registered PF_INET6 protocol family server # [ 0.697596] IPI shorthand broadcast: enabled builder # [ 0.696029] Segment Routing with IPv6 builder # [ 0.698569] In-situ OAM (IOAM) with IPv6 builder # [ 0.699635] IPI shorthand broadcast: enabled server # [ 0.705910] sched_clock: Marking stable (567015378, 138291348)->(775328773, -70022047) server # [ 0.709395] registered taskstats version 1 server # [ 0.710447] Loading compiled-in X.509 certificates builder # [ 0.707923] sched_clock: Marking stable (569014326, 138520313)->(782890745, -75356106) builder # [ 0.712713] registered taskstats version 1 builder # [ 0.713696] Loading compiled-in X.509 certificates server # [ 0.729324] Demotion targets for Node 0: null server # [ 0.730224] Key type .fscrypt registered server # [ 0.733311] Key type fscrypt-provisioning registered server # [ 0.734274] ima: No TPM chip found, activating TPM-bypass! builder # [ 0.732548] Demotion targets for Node 0: null server # [ 0.735239] ima: Allocated hash algorithm: sha1 builder # [ 0.733397] Key type .fscrypt registered server # [ 0.737341] ima: No architecture policies found builder # [ 0.735548] Key type fscrypt-provisioning registered builder # [ 0.736448] ima: No TPM chip found, activating TPM-bypass! builder # [ 0.737388] ima: Allocated hash algorithm: sha1 server # [ 0.740317] PM: Magic number: 10:593:439 server # [ 0.741928] RAS: Correctable Errors collector initialized. builder # [ 0.741575] ima: No architecture policies found builder # [ 0.742543] PM: Magic number: 10:593:439 builder # [ 0.744157] RAS: Correctable Errors collector initialized. server # [ 0.749938] clk: Disabling unused clocks server # [ 0.753337] PM: genpd: Disabling unused power domains builder # [ 0.753159] clk: Disabling unused clocks builder # [ 0.756564] PM: genpd: Disabling unused power domains server # [ 0.916554] Freeing initrd memory: 29072K server # [ 0.919689] Freeing unused decrypted memory: 2028K server # [ 0.922238] Freeing unused kernel image (initmem) memory: 3644K server # [ 0.923435] Write protecting the kernel read-only data: 32768k server # [ 0.925312] Freeing unused kernel image (text/rodata gap) memory: 1200K server # [ 0.926839] Freeing unused kernel image (rodata/data gap) memory: 736K builder # [ 0.927675] Freeing initrd memory: 29072K builder # [ 0.930861] Freeing unused decrypted memory: 2028K builder # [ 0.933501] Freeing unused kernel image (initmem) memory: 3644K builder # [ 0.934572] Write protecting the kernel read-only data: 32768k builder # [ 0.936452] Freeing unused kernel image (text/rodata gap) memory: 1200K builder # [ 0.937922] Freeing unused kernel image (rodata/data gap) memory: 736K server # [ 0.977953] x86/mm: Checked W+X mappings: passed, no W+X pages found. server # [ 0.979053] Run /init as init process server # [ 0.989604] systemd[1]: Inserted module 'autofs4' builder # [ 0.989142] x86/mm: Checked W+X mappings: passed, no W+X pages found. builder # [ 0.990348] Run /init as init process builder # [ 1.001638] systemd[1]: Inserted module 'autofs4' server # [ 1.009816] fuse: init (API version 7.45) server # [ 1.017123] ACPI: \_SB_.GSIG: Enabled at IRQ 22 server # [ 1.019507] ACPI: \_SB_.GSIH: Enabled at IRQ 23 server # [ 1.022334] ACPI: \_SB_.GSIE: Enabled at IRQ 20 builder # [ 1.021878] fuse: init (API version 7.45) server # [ 1.024191] ACPI: \_SB_.GSIF: Enabled at IRQ 21 server # [ 1.028859] virtiofs virtio5: discovered new tag: nix-store server # [ 1.030558] virtiofs virtio5: virtio_fs_setup_dax: No cache capability builder # [ 1.029213] ACPI: \_SB_.GSIG: Enabled at IRQ 22 builder # [ 1.031999] ACPI: \_SB_.GSIH: Enabled at IRQ 23 builder # [ 1.035219] ACPI: \_SB_.GSIE: Enabled at IRQ 20 server # [ 1.039134] virtiofs virtio6: discovered new tag: shared builder # [ 1.037603] ACPI: \_SB_.GSIF: Enabled at IRQ 21 server # [ 1.040938] virtiofs virtio6: virtio_fs_setup_dax: No cache capability server # [ 1.044378] virtiofs virtio7: discovered new tag: xchg builder # [ 1.043738] virtiofs virtio5: discovered new tag: nix-store server # [ 1.045859] virtiofs virtio7: virtio_fs_setup_dax: No cache capability builder # [ 1.045242] virtiofs virtio5: virtio_fs_setup_dax: No cache capability builder # [ 1.052314] virtiofs virtio6: discovered new tag: shared builder # [ 1.053873] virtiofs virtio6: virtio_fs_setup_dax: No cache capability builder # [ 1.057339] virtiofs virtio7: discovered new tag: xchg builder # [ 1.058818] virtiofs virtio7: virtio_fs_setup_dax: No cache capability server # [ 1.067426] systemd[1]: Successfully made /usr/ read-only. builder # [ 1.079366] systemd[1]: Successfully made /usr/ read-only. server # [ 1.404056] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) server # [ 1.416261] systemd[1]: Detected virtualization kvm. server # [ 1.418512] systemd[1]: Detected architecture x86-64. server # [ 1.420628] systemd[1]: Running in initrd. server # [ 1.423073] systemd[1]: Initializing machine ID from random generator. server # [ 1.425993] systemd[1]: Hostname set to . builder # [ 1.416009] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) builder # [ 1.427788] systemd[1]: Detected virtualization kvm. builder # [ 1.429889] systemd[1]: Detected architecture x86-64. builder # [ 1.431957] systemd[1]: Running in initrd. builder # [ 1.434300] systemd[1]: Initializing machine ID from random generator. builder # [ 1.437234] systemd[1]: Hostname set to . server # [ 1.647885] systemd[1]: bpf-restrict-fs: LSM BPF program attached builder # [ 1.660192] systemd[1]: bpf-restrict-fs: LSM BPF program attached server # [ 1.702792] systemd[1]: Queued start job for default target Initrd Default Target. server # [ 1.706652] systemd[1]: Created slice Slice /system/modprobe. server # [ 1.707867] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. server # [ 1.709375] systemd[1]: Expecting device /dev/disk/by-label/nixos... server # [ 1.710543] systemd[1]: Reached target Path Units. builder # [ 1.708126] systemd[1]: Queued start job for default target Initrd Default Target. server # [ 1.711463] systemd[1]: Reached target Slice Units. server # [ 1.712385] systemd[1]: Reached target Swaps. server # [ 1.713202] systemd[1]: Reached target Timer Units. builder # [ 1.711765] systemd[1]: Created slice Slice /system/modprobe. server # [ 1.714233] systemd[1]: Listening on D-Bus System Message Bus Socket. builder # [ 1.712883] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. server # [ 1.715500] systemd[1]: Listening on Journal Socket (/dev/log). builder # [ 1.714206] systemd[1]: Expecting device /dev/disk/by-label/nixos... server # [ 1.716683] systemd[1]: Listening on Journal Sockets. builder # [ 1.715240] systemd[1]: Reached target Path Units. server # [ 1.717674] systemd[1]: Listening on udev Control Socket. builder # [ 1.716062] systemd[1]: Reached target Slice Units. server # [ 1.718735] systemd[1]: Listening on udev Kernel Socket. builder # [ 1.716889] systemd[1]: Reached target Swaps. server # [ 1.719688] systemd[1]: Reached target Socket Units. builder # [ 1.717650] systemd[1]: Reached target Timer Units. builder # [ 1.718575] systemd[1]: Listening on D-Bus System Message Bus Socket. server # [ 1.721571] systemd[1]: Starting Create List of Static Device Nodes... builder # [ 1.719710] systemd[1]: Listening on Journal Socket (/dev/log). builder # [ 1.720804] systemd[1]: Listening on Journal Sockets. builder # [ 1.721744] systemd[1]: Listening on udev Control Socket. builder # [ 1.722706] systemd[1]: Listening on udev Kernel Socket. server # [ 1.725387] systemd[1]: Starting Load Kernel Module configfs... builder # [ 1.723609] systemd[1]: Reached target Socket Units. builder # [ 1.725285] systemd[1]: Starting Create List of Static Device Nodes... builder # [ 1.729603] systemd[1]: Starting Load Kernel Module configfs... server # [ 1.734520] systemd[1]: Starting Journal Service... builder # [ 1.737605] systemd[1]: Starting Journal Service... server # [ 1.760949] systemd[1]: Starting Load Kernel Modules... builder # [ 1.762741] systemd[1]: Starting Load Kernel Modules... server # [ 1.765454] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os builder # [ 1.766639] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os server # [ 1.774517] systemd[1]: Starting Coldplug All udev Devices... builder # [ 1.774711] systemd[1]: Starting Coldplug All udev Devices... server # [ 1.786715] systemd-journald[66]: Collecting audit messages is disabled. builder # [ 1.786915] systemd[1]: Finished Create List of Static Device Nodes. server # [ 1.791596] systemd[1]: Finished Create List of Static Device Nodes. builder # [ 1.789991] systemd-journald[66]: Collecting audit messages is disabled. builder # [ 1.793736] systemd[1]: modprobe@configfs.service: Deactivated successfully. server # [ 1.795977] systemd[1]: modprobe@configfs.service: Deactivated successfully. server # [ 1.802719] systemd[1]: Finished Load Kernel Module configfs. builder # [ 1.800925] systemd[1]: Finished Load Kernel Module configfs. builder # [ 1.805970] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config server # [ 1.808802] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config builder # [ 1.817649] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... server # [ 1.820384] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... server # [ 1.824135] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. builder # [ 1.829905] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. server # [ 1.838509] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev builder # [ 1.837733] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev builder # [ 1.859088] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. server # [ 1.868010] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. builder # [ 1.866720] systemd[1]: Starting Create Static Device Nodes in /dev... builder # [ 1.878049] systemd[1]: Finished Load Kernel Modules. server # [ 1.880413] systemd[1]: Starting Create Static Device Nodes in /dev... server # [ 1.887867] systemd[1]: Finished Load Kernel Modules. builder # [ 1.885782] systemd[1]: Starting Apply Kernel Variables... server # [ 1.900607] systemd[1]: Starting Apply Kernel Variables... server # [ 1.766255] systemd-modules-load[67]: Inserted module 'dm_mod' server # [ 1.769267] systemd-modules-load[67]: Inserted module 'virtio_balloon' builder # [ 1.906832] systemd[1]: Finished Create Static Device Nodes in /dev. server # [ 1.771306] systemd-modules-load[67]: Inserted module 'virtio_gpu' server # [ 1.910718] systemd[1]: Started Journal Service. builder # [ 1.772523] systemd-modules-load[67]: Inserted module 'dm_mod'[ 1.912300] systemd[1]: Started Journal Service. builder # builder # [ 1.775419] systemd-modules-load[67]: Inserted module 'virtio_balloon' builder # [ 1.778276] systemd-modules-load[67]: Inserted module 'virtio_gpu' builder # [ 1.780252] systemd[1]: Reached target Preparation for Local File Systems. builder # [ 1.781372] systemd[1]: Reached target Local File Systems. builder # [ 1.786313] systemd[1]: Starting Create System Files and Directories... server # [ 1.791145] systemd[1]: Finished Create Static Device Nodes in /dev. server # [ 1.792185] systemd[1]: Reached target Preparation for Local File Systems. server # [ 1.793184] systemd[1]: Reached target Local File Systems. builder # [ 1.793847] systemd[1]: Starting Rule-based Manager for Device Events and Files... server # [ 1.800121] systemd[1]: Starting Create System Files and Directories... builder # [ 1.800824] systemd[1]: Finished Apply Kernel Variables. server # [ 1.808713] systemd[1]: Starting Rule-based Manager for Device Events and Files... server # [ 1.813680] systemd[1]: Finished Apply Kernel Variables. builder # [ 1.819075] systemd[1]: Finished Create System Files and Directories. server # [ 1.840619] systemd[1]: Finished Create System Files and Directories. builder # [ 1.845411] systemd-udevd[77]: Using default interface naming scheme 'v261'. server # [ 1.862391] systemd-udevd[81]: Using default interface naming scheme 'v261'. builder # [ 1.868704] systemd[1]: Started Rule-based Manager for Device Events and Files. server # [ 1.886129] systemd[1]: Started Rule-based Manager for Device Events and Files. builder # [ 1.923093] systemd[1]: Finished Coldplug All udev Devices. builder # [ 1.923978] systemd[1]: Reached target System Initialization. builder # [ 1.924826] systemd[1]: Reached target Basic System. server # [ 1.930161] systemd[1]: Finished Coldplug All udev Devices. server # [ 1.932476] systemd[1]: Reached target System Initialization. server # [ 1.933321] systemd[1]: Reached target Basic System. server # [ 2.235628] virtio_blk virtio2: 1/0/0 default/read/poll queues builder # [ 2.244480] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 server # [ 2.253164] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) builder # [ 2.256973] virtio_blk virtio2: 1/0/0 default/read/poll queues builder # [ 2.261087] ehci-pci 0000:00:1d.7: EHCI Host Controller builder # [ 2.261869] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 builder # [ 2.263587] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 builder # [ 2.265748] serio: i8042 KBD port at 0x60,0x64 irq 1 builder # [ 2.266430] serio: i8042 AUX port at 0x60,0x64 irq 12 builder # [ 2.269740] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 server # [ 2.271738] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 builder # [ 2.270869] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 builder # [ 2.272164] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 builder # [ 2.275567] usb usb1: Product: EHCI Host Controller builder # [ 2.276215] usb usb1: Manufacturer: Linux 6.18.51 ehci_hcd builder # [ 2.277252] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) builder # [ 2.278980] usb usb1: SerialNumber: 0000:00:1d.7 builder # [ 2.280251] hub 1-0:1.0: USB hub found builder # [ 2.282572] hub 1-0:1.0: 6 ports detected server # [ 2.286408] serio: i8042 KBD port at 0x60,0x64 irq 1 server # [ 2.287328] serio: i8042 AUX port at 0x60,0x64 irq 12 builder # [ 2.287435] uhci_hcd 0000:00:1d.0: UHCI Host Controller builder # [ 2.288166] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 server # [ 2.294901] ehci-pci 0000:00:1d.7: EHCI Host Controller server # [ 2.295637] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 server # [ 2.296931] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 builder # [ 2.298490] uhci_hcd 0000:00:1d.0: detected 2 ports builder # [ 2.300123] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180 server # [ 2.304322] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 server # [ 2.305590] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 server # [ 2.308323] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 server # [ 2.310657] usb usb1: Product: EHCI Host Controller server # [ 2.312321] usb usb1: Manufacturer: Linux 6.18.51 ehci_hcd server # [ 2.313080] usb usb1: SerialNumber: 0000:00:1d.7 builder # [ 2.311200] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 builder # [ 2.312336] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 server # [ 2.315517] hub 1-0:1.0: USB hub found server # [ 2.317214] hub 1-0:1.0: 6 ports detected server # [ 2.319505] uhci_hcd 0000:00:1d.0: UHCI Host Controller server # [ 2.320246] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 builder # [ 2.323276] usb usb2: Product: UHCI Host Controller builder # [ 2.328562] usb usb2: Manufacturer: Linux 6.18.51 uhci_hcd builder # [ 2.329354] usb usb2: SerialNumber: 0000:00:1d.0 server # [ 2.335058] uhci_hcd 0000:00:1d.0: detected 2 ports server # [ 2.341131] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180 builder # [ 2.340191] hub 2-0:1.0: USB hub found builder # [ 2.343477] hub 2-0:1.0: 2 ports detected server # [ 2.346373] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 server # [ 2.347513] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 builder # [ 2.218989] (udev-worker)[88]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. builder # [ 2.223056] systemd[1]: Starting Virtual Console Setup... server # [ 2.364545] usb usb2: Product: UHCI Host Controller server # [ 2.228371] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line. server # [ 2.233129] systemd[1]: Starting Virtual Console Setup... builder # [ 2.231605] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. builder # [ 2.234239] (udev-worker)[88]: Network interface NamePolicy= disabled on kernel command line. server # [ 2.378619] usb usb2: Manufacturer: Linux 6.18.51 uhci_hcd builder # [ 2.377146] uhci_hcd 0000:00:1d.1: UHCI Host Controller builder # [ 2.377890] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 builder # [ 2.387382] SCSI subsystem initialized server # [ 2.254486] (udev-worker)[90]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. server # [ 2.256621] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line. builder # [ 2.392849] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 server # [ 2.397442] usb usb2: SerialNumber: 0000:00:1d.0 builder # [ 2.401568] uhci_hcd 0000:00:1d.1: detected 2 ports builder # [ 2.402350] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0 server # [ 2.411500] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 server # [ 2.276083] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones. builder # [ 2.276151] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones. server # [ 2.280238] systemd[1]: Finished Virtual Console Setup. server # [ 2.419785] hub 2-0:1.0: USB hub found builder # [ 2.280073] systemd[1]: Finished Virtual Console Setup. server # [ 2.425485] hub 2-0:1.0: 2 ports detected builder # [ 2.422982] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 builder # [ 2.428367] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 builder # [ 2.429413] usb usb3: Product: UHCI Host Controller server # [ 2.432145] SCSI subsystem initialized builder # [ 2.299118] systemd[1]: Found device /dev/disk/by-label/nixos. server # [ 2.440484] uhci_hcd 0000:00:1d.1: UHCI Host Controller builder # [ 2.299947] systemd[1]: Reached target Initrd Root Device. builder # [ 2.439230] usb usb3: Manufacturer: Linux 6.18.51 uhci_hcd server # [ 2.441219] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 builder # [ 2.302136] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... builder # [ 2.442552] usb usb3: SerialNumber: 0000:00:1d.1 server # [ 2.308980] systemd[1]: Found device /dev/disk/by-label/nixos. builder # [ 2.447342] hub 3-0:1.0: USB hub found server # [ 2.311730] systemd[1]: Reached target Initrd Root Device. server # [ 2.313720] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... builder # [ 2.451568] hub 3-0:1.0: 2 ports detected server # [ 2.457854] uhci_hcd 0000:00:1d.1: detected 2 ports builder # [ 2.455877] uhci_hcd 0000:00:1d.2: UHCI Host Controller server # [ 2.461458] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0 builder # [ 2.460786] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 server # [ 2.463991] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 server # [ 2.465131] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 builder # [ 2.463579] uhci_hcd 0000:00:1d.2: detected 2 ports builder # [ 2.464376] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0 builder # [ 2.467806] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 builder # [ 2.470597] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 server # [ 2.475321] usb usb3: Product: UHCI Host Controller builder # [ 2.473563] usb usb4: Product: UHCI Host Controller server # [ 2.476037] usb usb3: Manufacturer: Linux 6.18.51 uhci_hcd builder # [ 2.474289] usb usb4: Manufacturer: Linux 6.18.51 uhci_hcd builder # [ 2.476581] usb usb4: SerialNumber: 0000:00:1d.2 server # [ 2.479358] usb usb3: SerialNumber: 0000:00:1d.1 server # [ 2.481059] hub 3-0:1.0: USB hub found builder # [ 2.479898] hub 4-0:1.0: USB hub found server # [ 2.483361] hub 3-0:1.0: 2 ports detected builder # [ 2.482766] hub 4-0:1.0: 2 ports detected builder # [ 2.346677] systemd-fsck[105]: nixos: clean, 12/65536 files, 13019/262144 blocks server # [ 2.489548] uhci_hcd 0000:00:1d.2: UHCI Host Controller server # [ 2.493198] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 builder # [ 2.353457] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. server # [ 2.496445] uhci_hcd 0000:00:1d.2: detected 2 ports server # [ 2.497273] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0 server # [ 2.360745] systemd-fsck[105]: nixos: clean, 12/65536 files, 13019/262144 blocks server # [ 2.365479] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. server # [ 2.505484] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 server # [ 2.506652] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 server # [ 2.511369] usb usb4: Product: UHCI Host Controller server # [ 2.512124] usb usb4: Manufacturer: Linux 6.18.51 uhci_hcd server # [ 2.514344] usb usb4: SerialNumber: 0000:00:1d.2 server # [ 2.516395] hub 4-0:1.0: USB hub found server # [ 2.518351] hub 4-0:1.0: 2 ports detected builder # [ 2.523555] usb 1-1: new high-speed USB device number 2 using ehci-pci builder # [ 2.527198] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode builder # [ 2.528329] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) builder # [ 2.529805] ahci 0000:00:1f.2: flags: 64bit ncq only builder # [ 2.540604] scsi host0: ahci builder # [ 2.541927] scsi host1: ahci builder # [ 2.543921] scsi host2: ahci builder # [ 2.545507] scsi host3: ahci builder # [ 2.547142] scsi host4: ahci builder # [ 2.548505] scsi host5: ahci builder # [ 2.549943] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1 builder # [ 2.551077] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1 builder # [ 2.552551] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1 builder # [ 2.553690] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1 builder # [ 2.554905] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1 builder # [ 2.556024] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1 server # [ 2.558832] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode server # [ 2.559996] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) server # [ 2.560883] usb 1-1: new high-speed USB device number 2 using ehci-pci server # [ 2.563210] ahci 0000:00:1f.2: flags: 64bit ncq only server # [ 2.567125] scsi host0: ahci server # [ 2.569053] scsi host1: ahci server # [ 2.570728] scsi host2: ahci server # [ 2.572365] scsi host3: ahci server # [ 2.573985] scsi host4: ahci server # [ 2.575711] scsi host5: ahci server # [ 2.577252] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1 server # [ 2.578430] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1 server # [ 2.579659] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1 server # [ 2.580788] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1 server # [ 2.581994] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1 server # [ 2.583188] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1 builder # [ 2.653410] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 builder # [ 2.656292] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 builder # [ 2.659391] usb 1-1: Product: QEMU USB Tablet builder # [ 2.661264] usb 1-1: Manufacturer: QEMU builder # [ 2.662874] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 server # [ 2.692535] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 server # [ 2.695397] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 builder # [ 2.695027] hid: raw HID events driver (C) Jiri Kosina server # [ 2.698556] usb 1-1: Product: QEMU USB Tablet server # [ 2.700333] usb 1-1: Manufacturer: QEMU server # [ 2.702039] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 server # [ 2.734614] hid: raw HID events driver (C) Jiri Kosina builder # [ 2.645334] systemd[1]: Mounting /sysroot... server # [ 2.648207] systemd[1]: Mounting /sysroot... builder # [ 2.865922] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) builder # [ 2.868295] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 builder # [ 2.870567] ata3.00: applying bridge limits builder # [ 2.872506] ata5: SATA link down (SStatus 0 SControl 300) builder # [ 2.874828] ata6: SATA link down (SStatus 0 SControl 300) builder # [ 2.876904] ata4: SATA link down (SStatus 0 SControl 300) builder # [ 2.879182] ata2: SATA link down (SStatus 0 SControl 300) builder # [ 2.881398] ata1: SATA link down (SStatus 0 SControl 300) builder # [ 2.883692] ata3.00: configured for UDMA/100 builder # [ 2.885873] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 server # [ 2.896396] ata6: SATA link down (SStatus 0 SControl 300) server # [ 2.898608] ata5: SATA link down (SStatus 0 SControl 300) server # [ 2.901242] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) server # [ 2.903789] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 server # [ 2.905911] ata3.00: applying bridge limits server # [ 2.907882] ata4: SATA link down (SStatus 0 SControl 300) server # [ 2.910191] ata2: SATA link down (SStatus 0 SControl 300) server # [ 2.912763] ata1: SATA link down (SStatus 0 SControl 300) server # [ 2.915123] ata3.00: configured for UDMA/100 server # [ 2.917660] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 builder # [ 2.958263] usbcore: registered new interface driver usbhid builder # [ 2.960529] usbhid: USB HID core driver builder # [ 2.977181] 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/input2 builder # [ 2.980777] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0 builder # [ 2.986910] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray server # [ 2.992557] usbcore: registered new interface driver usbhid server # [ 2.998056] usbhid: USB HID core driver builder # [ 3.002676] cdrom: Uniform CD-ROM driver Revision: 3.20 server # [ 3.013460] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray builder # [ 3.015410] EXT4-fs (vda): mounted filesystem 13b3f27a-a3e9-41f6-a945-4c19fd1ed89e r/w with ordered data mode. Quota mode: none. builder # [ 2.881196] systemd[1]: Mounted /sysroot. builder # [ 2.882178] systemd[1]: Reached target Initrd Root File System. builder # [ 2.883887] systemd[1]: Starting Mountpoints Configured in the Real Root... server # [ 3.026560] 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/input2 server # [ 3.028384] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0 server # [ 3.030633] cdrom: Uniform CD-ROM driver Revision: 3.20 builder # [ 2.896867] systemd-sysroot-fstab-check[138]: /sysroot should be mounted in the initrd, will request daemon-reload. builder # [ 2.902107] systemd[1]: Reload requested from client PID 138 ('systemd-sysroot') (unit initrd-parse-etc.service)... builder # [ 2.903465] systemd[1]: Reloading... server # [ 3.050724] EXT4-fs (vda): mounted filesystem 6679bf37-24ce-400a-9362-d5546ab16efc r/w with ordered data mode. Quota mode: none. server # [ 2.920750] systemd[1]: Mounted /sysroot. server # [ 2.922439] systemd[1]: Reached target Initrd Root File System. server # [ 2.925140] systemd[1]: Starting Mountpoints Configured in the Real Root... server # [ 2.941758] systemd-sysroot-fstab-check[138]: /sysroot should be mounted in the initrd, will request daemon-reload. server # [ 2.947189] systemd[1]: Reload requested from client PID 138 ('systemd-sysroot') (unit initrd-parse-etc.service)... server # [ 2.948680] systemd[1]: Reloading... builder # [ 2.981639] systemd[1]: Reloading finished in 79 ms. builder # [ 2.989677] systemd-sysroot-fstab-check[138]: Requesting initrd-fs.target/start/replace... builder # [ 2.992149] systemd-sysroot-fstab-check[138]: Requesting swap.target/start/replace... builder # [ 2.998327] systemd[1]: initrd-parse-etc.service: Deactivated successfully. builder # [ 2.999686] systemd[1]: Finished Mountpoints Configured in the Real Root. builder # [ 3.000749] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. server # [ 3.030069] systemd[1]: Reloading finished in 83 ms. server # [ 3.039934] systemd-sysroot-fstab-check[138]: Requesting initrd-fs.target/start/replace... server # [ 3.044079] systemd-sysroot-fstab-check[138]: Requesting swap.target/start/replace... server # [ 3.047779] systemd[1]: initrd-parse-etc.service: Deactivated successfully. server # [ 3.050085] systemd[1]: Finished Mountpoints Configured in the Real Root. server # [ 3.050997] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. builder # [ 3.648185] systemd[1]: Mounting /sysroot/nix/.ro-store... server # [ 3.650238] systemd[1]: Mounting /sysroot/nix/.ro-store... builder # [ 3.653172] systemd[1]: Mounting /sysroot/nix/.rw-store... server # [ 3.657224] systemd[1]: Mounting /sysroot/nix/.rw-store... builder # [ 3.660159] systemd[1]: Mounting /sysroot/run... server # [ 3.666221] systemd[1]: Mounting /sysroot/run... builder # [ 3.672183] systemd[1]: Mounting /sysroot/tmp/shared... server # [ 3.677176] systemd[1]: Mounting /sysroot/tmp/shared... builder # [ 3.682606] systemd[1]: Mounting /sysroot/tmp/xchg... server # [ 3.689837] systemd[1]: Mounting /sysroot/tmp/xchg... builder # [ 3.714688] systemd[1]: Mounted /sysroot/nix/.ro-store. builder # [ 3.718072] systemd[1]: Mounted /sysroot/nix/.rw-store. builder # [ 3.718793] systemd[1]: Mounted /sysroot/run. builder # [ 3.721737] systemd[1]: Mounted /sysroot/tmp/shared. builder # [ 3.722652] systemd[1]: Mounted /sysroot/tmp/xchg. builder # [ 3.726147] systemd[1]: Starting rw-sysroot-nix-store.service... server # [ 3.732204] systemd[1]: Mounted /sysroot/nix/.ro-store. server # [ 3.733111] systemd[1]: Mounted /sysroot/nix/.rw-store. server # [ 3.736346] systemd[1]: Mounted /sysroot/run. server # [ 3.738103] systemd[1]: Mounted /sysroot/tmp/shared. builder # [ 3.736170] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. server # [ 3.739200] systemd[1]: Mounted /sysroot/tmp/xchg. builder # [ 3.737600] systemd[1]: Finished rw-sysroot-nix-store.service. builder # [ 3.739625] systemd[1]: Mounting /sysroot/nix/store... server # [ 3.743891] systemd[1]: Starting rw-sysroot-nix-store.service... server # [ 3.754959] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. server # [ 3.757143] systemd[1]: Finished rw-sysroot-nix-store.service. server # [ 3.758755] systemd[1]: Mounting /sysroot/nix/store... builder # [ 3.759437] systemd[1]: Mounted /sysroot/nix/store. builder # [ 3.760991] systemd[1]: Reached target Initrd File Systems. builder # [ 3.762075] systemd[1]: Starting Find NixOS closure... builder # [ 3.764557] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... server # [ 3.780796] systemd[1]: Mounted /sysroot/nix/store. server # [ 3.782089] systemd[1]: Reached target Initrd File Systems. server # [ 3.783741] systemd[1]: Starting Find NixOS closure... builder # [ 3.782188] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. server # [ 3.787160] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... builder # [ 3.789486] systemd[1]: Finished Find NixOS closure. builder # [ 3.790732] systemd[1]: Reached target Initrd Default Target. builder # [ 3.792810] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... server # [ 3.805356] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. builder # [ 3.806218] systemd[1]: Stopped target Initrd Default Target. builder # [ 3.807026] systemd[1]: Stopped target Basic System. builder # [ 3.807691] systemd[1]: Stopped target Initrd Root Device. builder # [ 3.808421] systemd[1]: Stopped target Path Units. builder # [ 3.809083] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. builder # [ 3.810421] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. server # [ 3.813425] systemd[1]: Finished Find NixOS closure. builder # [ 3.811546] systemd[1]: Stopped target Slice Units. server # [ 3.815254] systemd[1]: Reached target Initrd Default Target. builder # [ 3.813141] systemd[1]: Stopped target Socket Units. builder # [ 3.813818] systemd[1]: Stopped target System Initialization. server # [ 3.816463] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... builder # [ 3.814727] systemd[1]: Stopped target Swaps. builder # [ 3.815369] systemd[1]: Stopped target Timer Units. builder # [ 3.816056] systemd[1]: dbus.socket: Deactivated successfully. builder # [ 3.817035] systemd[1]: Closed D-Bus System Message Bus Socket. builder # [ 3.818106] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. builder # [ 3.819146] systemd[1]: Stopped Find NixOS closure. builder # [ 3.820378] systemd[1]: Starting rw-sysroot-nix-store.service... builder # [ 3.822171] systemd[1]: systemd-sysctl.service: Deactivated successfully. builder # [ 3.823243] systemd[1]: Stopped Apply Kernel Variables. builder # [ 3.824258] systemd[1]: systemd-modules-load.service: Deactivated successfully. builder # [ 3.825447] systemd[1]: Stopped Load Kernel Modules. builder # [ 3.826427] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. builder # [ 3.827782] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. server # [ 3.831022] systemd[1]: Stopped target Initrd Default Target. builder # [ 3.829118] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. server # [ 3.832444] systemd[1]: Stopped target Basic System. builder # [ 3.830123] systemd[1]: Stopped Create System Files and Directories. server # [ 3.834140] systemd[1]: Stopped target Initrd Root Device. builder # [ 3.832100] systemd[1]: Stopped target Local File Systems. server # [ 3.834943] systemd[1]: Stopped target Path Units. builder # [ 3.832835] systemd[1]: Stopped target Preparation for Local File Systems. server # [ 3.835812] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. builder # [ 3.833884] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. server # [ 3.836918] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. builder # [ 3.835062] systemd[1]: Stopped Coldplug All udev Devices. server # [ 3.837987] systemd[1]: Stopped target Slice Units. builder # [ 3.836116] systemd[1]: Stopping Rule-based Manager for Device Events and Files... server # [ 3.838949] systemd[1]: Stopped target Socket Units. server # [ 3.840245] systemd[1]: Stopped target System Initialization. builder # [ 3.838106] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. builder # [ 3.839107] systemd[1]: Stopped Virtual Console Setup. server # [ 3.841528] systemd[1]: Stopped target Swaps. server # [ 3.842408] systemd[1]: Stopped target Timer Units. server # [ 3.843367] systemd[1]: dbus.socket: Deactivated successfully. server # [ 3.845133] systemd[1]: Closed D-Bus System Message Bus Socket. server # [ 3.845984] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. server # [ 3.847098] systemd[1]: Stopped Find NixOS closure. server # [ 3.849123] systemd[1]: Starting rw-sysroot-nix-store.service... server # [ 3.850257] systemd[1]: systemd-sysctl.service: Deactivated successfully. builder # [ 3.848156] systemd[1]: initrd-cleanup.service: Deactivated successfully. builder # [ 3.849171] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. server # [ 3.852156] systemd[1]: Stopped Apply Kernel Variables. server # [ 3.852935] systemd[1]: systemd-modules-load.service: Deactivated successfully. server # [ 3.854236] systemd[1]: Stopped Load Kernel Modules. server # [ 3.854965] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. server # [ 3.856157] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. server # [ 3.858118] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. server # [ 3.859166] systemd[1]: Stopped Create System Files and Directories. builder # [ 3.856772] systemd[1]: systemd-udevd.service: Deactivated successfully. server # [ 3.860033] systemd[1]: Stopped target Local File Systems. builder # [ 3.857919] systemd[1]: Stopped Rule-based Manager for Device Events and Files. server # [ 3.860898] systemd[1]: Stopped target Preparation for Local File Systems. builder # [ 3.859483] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. server # [ 3.861949] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. builder # [ 3.860873] systemd[1]: Finished rw-sysroot-nix-store.service. server # [ 3.863852] systemd[1]: Stopped Coldplug All udev Devices. server # [ 3.865140] systemd[1]: Stopping Rule-based Manager for Device Events and Files... builder # [ 3.863145] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. builder # [ 3.864113] systemd[1]: Closed udev Control Socket. builder # [ 3.864967] systemd[1]: Starting Cleanup udev Database... server # [ 3.867152] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. server # [ 3.868189] systemd[1]: Stopped Virtual Console Setup. builder # [ 3.865776] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. builder # [ 3.866844] systemd[1]: Stopped Create Static Device Nodes in /dev. builder # [ 3.868242] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. builder # [ 3.869370] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. builder # [ 3.870388] systemd[1]: kmod-static-nodes.service: Deactivated successfully. builder # [ 3.871601] systemd[1]: Stopped Create List of Static Device Nodes. server # [ 3.875891] systemd[1]: initrd-cleanup.service: Deactivated successfully. server # [ 3.881141] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. server # [ 3.885444] systemd[1]: systemd-udevd.service: Deactivated successfully. builder # [ 3.886401] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. server # [ 3.889152] systemd[1]: Stopped Rule-based Manager for Device Events and Files. builder # [ 3.887594] systemd[1]: Finished Cleanup udev Database. server # [ 3.890264] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. builder # [ 3.888742] systemd[1]: Reached target Switch Root. server # [ 3.891396] systemd[1]: Closed udev Control Socket. builder # [ 3.890278] systemd[1]: Starting NixOS Activation... server # [ 3.894375] systemd[1]: Starting Cleanup udev Database... server # [ 3.896215] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. server # [ 3.897354] systemd[1]: Stopped Create Static Device Nodes in /dev. server # [ 3.898309] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. server # [ 3.899440] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. server # [ 3.900543] systemd[1]: kmod-static-nodes.service: Deactivated successfully. server # [ 3.901512] systemd[1]: Stopped Create List of Static Device Nodes. server # [ 3.903141] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. server # [ 3.905748] systemd[1]: Finished rw-sysroot-nix-store.service. server # [ 3.922194] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. server # [ 3.923746] systemd[1]: Finished Cleanup udev Database. server # [ 3.925254] systemd[1]: Reached target Switch Root. server # [ 3.926947] systemd[1]: Starting NixOS Activation... builder # [ 3.959372] initrd-nixos-activation-start[190]: booting system configuration /nix/store/5rlfcjmnnixczg27wrrplh25f8najxw4-nixos-system-builder-test builder # [ 3.985791] initrd-nixos-activation-start[190]: running activation script... server # [ 3.988783] initrd-nixos-activation-start[189]: booting system configuration /nix/store/gd8jdfspmhwwrcj2i60j73dnjdx677vh-nixos-system-server-test server # [ 4.013885] initrd-nixos-activation-start[189]: running activation script... builder # [ 4.186353] initrd-nixos-activation-start[213]: setting up /etc... server # [ 4.204798] initrd-nixos-activation-start[212]: setting up /etc... builder # [ 4.288457] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. builder # [ 4.290059] systemd[1]: Finished NixOS Activation. builder # [ 4.291866] systemd[1]: Starting Switch Root... builder # [ 4.304096] systemd[1]: Switching root. server # [ 4.306297] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. server # [ 4.307826] systemd[1]: Finished NixOS Activation. server # [ 4.309727] systemd[1]: Starting Switch Root... server # [ 4.321659] systemd[1]: Switching root. builder # [ 4.570338] systemd-journald[66]: Received SIGTERM from PID 1 (systemd). server # [ 4.587172] systemd-journald[66]: Received SIGTERM from PID 1 (systemd). builder # [ 4.649377] NET: Registered PF_VSOCK protocol family server # [ 4.669572] NET: Registered PF_VSOCK protocol family builder # [ 5.008152] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) builder # [ 5.017559] systemd[1]: Detected virtualization kvm. builder # [ 5.019289] systemd[1]: Detected architecture x86-64. builder # [ 5.021250] systemd[1]: Detected first boot. builder # [ 5.025121] systemd[1]: Initializing machine ID from random generator. server # [ 5.028692] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) server # [ 5.038358] systemd[1]: Detected virtualization kvm. server # [ 5.040134] systemd[1]: Detected architecture x86-64. server # [ 5.042084] systemd[1]: Detected first boot. server # [ 5.045887] systemd[1]: Initializing machine ID from random generator. builder # [ 5.266806] systemd[1]: bpf-restrict-fs: LSM BPF program attached server # [ 5.311859] systemd[1]: bpf-restrict-fs: LSM BPF program attached builder # [ 5.374134] systemd[1]: Applying preset policy. server # [ 5.394591] systemd[1]: Applying preset policy. builder # [ 5.544608] systemd[1]: Populated /etc with preset unit settings. server # [ 5.570178] systemd[1]: Populated /etc with preset unit settings. builder # [ 5.725924] systemd[1]: initrd-switch-root.service: Deactivated successfully. builder # [ 5.727298] systemd[1]: Stopped initrd-switch-root.service. builder # [ 5.729843] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. builder # [ 5.731871] systemd[1]: Created slice Slice /system/getty. builder # [ 5.733229] systemd[1]: Created slice User and Session Slice. builder # [ 5.734110] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. builder # [ 5.735290] systemd[1]: Started Forward Password Requests to Wall Directory Watch. builder # [ 5.736347] systemd[1]: Expecting device /dev/hvc0... builder # [ 5.737053] systemd[1]: Expecting device /dev/ttyS0... builder # [ 5.737803] systemd[1]: Reached target Local Encrypted Volumes. builder # [ 5.738601] systemd[1]: Stopped target initrd-fs.target. builder # [ 5.739309] systemd[1]: Stopped target initrd-root-fs.target. builder # [ 5.740079] systemd[1]: Stopped target initrd-switch-root.target. builder # [ 5.740914] systemd[1]: Reached target Virtual Machines and Containers. builder # [ 5.741788] systemd[1]: Reached target Path Units. builder # [ 5.742452] systemd[1]: Reached target Remote File Systems. builder # [ 5.743208] systemd[1]: Reached target Slice Units. builder # [ 5.743893] systemd[1]: Reached target Swaps. builder # [ 5.745649] systemd[1]: Listening on Query the User Interactively for a Password. builder # [ 5.748004] systemd[1]: Listening on Process Core Dump Socket. builder # [ 5.749757] systemd[1]: Listening on Credential Encryption/Decryption. builder # [ 5.762886] systemd[1]: Listening on Factory Reset Management. builder # [ 5.763800] systemd[1]: Listening on Hostname Service Socket. builder # [ 5.766455] systemd[1]: Starting Journal Log Access Socket... builder # [ 5.767808] systemd[1]: Listening on Journal Audit Socket. builder # [ 5.770487] systemd[1]: Listening on Console Output Muting Service Socket. builder # [ 5.771611] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. server # [ 5.774543] systemd[1]: initrd-switch-root.service: Deactivated successfully. builder # [ 5.772705] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os server # [ 5.775955] systemd[1]: Stopped initrd-switch-root.service. builder # [ 5.773967] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki server # [ 5.777957] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. builder # [ 5.777882] systemd[1]: Listening on Disk Repartitioning Service Socket. server # [ 5.780118] systemd[1]: Created slice Slice /system/getty. builder # [ 5.778886] systemd[1]: Listening on udev Control Socket. server # [ 5.781570] systemd[1]: Created slice User and Session Slice. builder # [ 5.779751] systemd[1]: Listening on udev Varlink Socket. server # [ 5.782505] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. server # [ 5.783783] systemd[1]: Started Forward Password Requests to Wall Directory Watch. builder # [ 5.782016] systemd[1]: Mounting Huge Pages File System... server # [ 5.784904] systemd[1]: Expecting device /dev/hvc0... server # [ 5.785620] systemd[1]: Expecting device /dev/ttyS0... server # [ 5.786387] systemd[1]: Reached target Local Encrypted Volumes. server # [ 5.787227] systemd[1]: Stopped target initrd-fs.target. server # [ 5.788020] systemd[1]: Stopped target initrd-root-fs.target. builder # [ 5.785903] systemd[1]: Mounting POSIX Message Queue File System... server # [ 5.788817] systemd[1]: Stopped target initrd-switch-root.target. server # [ 5.789661] systemd[1]: Reached target Virtual Machines and Containers. server # [ 5.790546] systemd[1]: Reached target Path Units. server # [ 5.791311] systemd[1]: Reached target Remote File Systems. server # [ 5.792097] systemd[1]: Reached target Slice Units. server # [ 5.792801] systemd[1]: Reached target Swaps. builder # [ 5.791959] systemd[1]: Mounting Kernel Debug File System... server # [ 5.794694] systemd[1]: Listening on Query the User Interactively for a Password. server # [ 5.797126] systemd[1]: Listening on Process Core Dump Socket. server # [ 5.798922] systemd[1]: Listening on Credential Encryption/Decryption. builder # [ 5.798178] systemd[1]: Mounting Kernel Trace File System... builder # [ 5.806659] systemd[1]: Starting Create List of Static Device Nodes... server # [ 5.800811] systemd[1]: Listening on Factory Reset Management. server # [ 5.813231] systemd[1]: Listening on Hostname Service Socket. server # [ 5.816148] systemd[1]: Starting Journal Log Access Socket... builder # [ 5.815334] systemd[1]: Starting Load Kernel Module configfs... server # [ 5.817709] systemd[1]: Listening on Journal Audit Socket. builder # [ 5.817902] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm server # [ 5.820589] systemd[1]: Listening on Console Output Muting Service Socket. server # [ 5.821706] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. server # [ 5.822796] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os server # [ 5.824096] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki builder # [ 5.822879] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore server # [ 5.828156] systemd[1]: Listening on Disk Repartitioning Service Socket. builder # [ 5.825878] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse server # [ 5.829190] systemd[1]: Listening on udev Control Socket. server # [ 5.830094] systemd[1]: Listening on udev Varlink Socket. server # [ 5.832527] systemd[1]: Mounting Huge Pages File System... server # [ 5.836672] systemd[1]: Mounting POSIX Message Queue File System... server # [ 5.842431] systemd[1]: Mounting Kernel Debug File System... builder # [ 5.841081] systemd[1]: Mounting FUSE Control File System... server # [ 5.847820] systemd[1]: Mounting Kernel Trace File System... builder # [ 5.848367] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 server # [ 5.855330] systemd[1]: Starting Create List of Static Device Nodes... server # [ 5.866367] systemd[1]: Starting Load Kernel Module configfs... builder # [ 5.865007] systemd[1]: Starting Journal Service... server # [ 5.867202] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm builder # [ 5.870836] systemd[1]: Starting Load Kernel Modules... server # [ 5.873194] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore server # [ 5.876692] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse builder # [ 5.877801] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... builder # [ 5.881567] systemd[1]: Starting Remount Root and Kernel File Systems... builder # [ 5.882454] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os builder # [ 5.888907] systemd[1]: Starting Coldplug All udev Devices... server # [ 5.892213] systemd[1]: Mounting FUSE Control File System... builder # [ 5.893354] systemd[1]: Listening on Journal Log Access Socket. builder # [ 5.895930] systemd[1]: Mounted Huge Pages File System. builder # [ 5.898172] systemd[1]: Mounted POSIX Message Queue File System. server # [ 5.899565] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 builder # [ 5.901355] systemd[1]: Mounted Kernel Debug File System. builder # [ 5.903261] systemd[1]: Mounted Kernel Trace File System. builder # [ 5.905111] systemd[1]: Mounted FUSE Control File System. builder # [ 5.911855] systemd[1]: Finished Create List of Static Device Nodes. server # [ 5.916397] systemd[1]: Starting Journal Service... builder # [ 5.917112] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... server # [ 5.924235] systemd[1]: Starting Load Kernel Modules... server # [ 5.929387] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... server # [ 5.933975] systemd[1]: Starting Remount Root and Kernel File Systems... server # [ 5.936429] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os server # [ 5.943723] systemd[1]: Starting Coldplug All udev Devices... server # [ 5.947994] systemd[1]: Listening on Journal Log Access Socket. builder # [ 5.945704] systemd[1]: modprobe@configfs.service: Deactivated successfully. builder # [ 5.947038] systemd[1]: Finished Load Kernel Module configfs. server # [ 5.950569] systemd[1]: Mounted Huge Pages File System. server # [ 5.952492] systemd[1]: Mounted POSIX Message Queue File System. server # [ 5.954478] systemd[1]: Mounted Kernel Debug File System. builder # [ 5.952557] systemd[1]: Mounting Kernel Configuration File System... server # [ 5.956619] systemd[1]: Mounted Kernel Trace File System. server # [ 5.958642] systemd[1]: Mounted FUSE Control File System. server # [ 5.962142] systemd[1]: Finished Create List of Static Device Nodes. server # [ 5.965616] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... builder # [ 5.974191] systemd[1]: Mounted Kernel Configuration File System. builder # [ 5.986561] EXT4-fs (vda): re-mounted 13b3f27a-a3e9-41f6-a945-4c19fd1ed89e. server # [ 5.993073] systemd[1]: modprobe@configfs.service: Deactivated successfully. server # [ 5.994493] systemd[1]: Finished Load Kernel Module configfs. builder # [ 5.995069] systemd[1]: Finished Remount Root and Kernel File Systems. builder # [ 5.996246] systemd[1]: Listening on Disk Image Download Service Socket. server # [ 5.999477] systemd[1]: Mounting Kernel Configuration File System... builder # [ 5.997809] systemd-journald[283]: Collecting audit messages is enabled. builder # [ 5.998785] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore builder # [ 6.004414] systemd[1]: Starting Load/Save OS Random Seed... builder # [ 6.006565] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os builder # [ 6.014872] loop: module loaded server # [ 6.025507] systemd[1]: Mounted Kernel Configuration File System. builder # [ 6.037397] systemd[1]: Started Journal Service. builder # [ 6.038764] tun: Universal TUN/TAP device driver, 1.6 server # [ 6.040973] systemd-journald[282]: Collecting audit messages is enabled. server # [ 6.043157] EXT4-fs (vda): re-mounted 6679bf37-24ce-400a-9362-d5546ab16efc. builder # [ 5.905402] systemd[1]: Queued start job for default target Multi-User System. builder # [ 5.909345] systemd[1]: systemd-journald.service: Deactivated successfully. server # [ 6.052045] systemd[1]: Finished Remount Root and Kernel File Systems. server # [ 6.054664] systemd[1]: Listening on Disk Image Download Service Socket. builder # [ 5.914382] systemd-modules-load[284]: Inserted module 'loop' server # [ 6.056378] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore builder # [ 5.919304] systemd[1]: Starting Flush Journal to Persistent Storage... server # [ 6.062782] systemd[1]: Starting Load/Save OS Random Seed... builder # [ 5.923561] systemd-modules-load[284]: Inserted module 'tun' server # [ 6.064475] loop: module loaded server # [ 6.065058] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os builder # [ 5.926257] systemd[1]: Finished Load Kernel Modules. builder # [ 5.933837] systemd[1]: Starting Firewall... builder # [ 5.938050] systemd[1]: Starting Apply Kernel Variables... server # [ 6.086635] systemd[1]: Started Journal Service. server # [ 5.951141] systemd[1]: Queued start job for default target Multi-User System. builder # [ 5.949380] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. server # [ 5.955391] systemd[1]: systemd-journald.service: Deactivated successfully. server # [ 5.956743] systemd-modules-load[283]: Inserted module 'loop' builder # [ 5.958057] systemd[1]: Starting Create Static Device Nodes in /dev... server # [ 5.964119] systemd[1]: Starting Flush Journal to Persistent Storage... builder # [ 5.976828] systemd[1]: Finished Load/Save OS Random Seed. builder # [ 5.977634] systemd[1]: Reached target First Boot Complete. server # [ 5.984248] systemd-modules-load[283]: Inserted module 'tls' server # [ 5.994389] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. server # [ 6.000388] systemd[1]: Starting Create Static Device Nodes in /dev... server # [ 6.144480] tun: Universal TUN/TAP device driver, 1.6 builder # [ 6.004331] systemd-oomd[285]: No swap; memory pressure usage will be degraded builder # [ 6.145878] systemd-journald[283]: Received client request to flush runtime journal. server # [ 6.011308] systemd-modules-load[283]: Inserted module 'tun' server # [ 6.019077] systemd[1]: Finished Load/Save OS Random Seed. server # [ 6.021444] systemd[1]: Finished Load Kernel Modules. server # [ 6.022925] systemd[1]: Reached target First Boot Complete. server # [ 6.025402] systemd[1]: Starting Firewall... server # [ 6.028219] systemd[1]: Starting Apply Kernel Variables... server # [ 6.182795] systemd-journald[282]: Received client request to flush runtime journal. builder # [ 6.110505] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. builder # [ 6.113621] systemd[1]: Finished Apply Kernel Variables. builder # [ 6.115228] systemd[1]: Finished Create Static Device Nodes in /dev. builder # [ 6.116092] systemd[1]: Reached target Preparation for Local File Systems. builder # [ 6.116937] systemd[1]: Starting Rule-based Manager for Device Events and Files... builder # [ 6.121164] systemd[1]: Finished Flush Journal to Persistent Storage. server # [ 6.187598] systemd-oomd[284]: No swap; memory pressure usage will be degraded server # [ 6.196953] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. server # [ 6.198100] systemd[1]: Finished Create Static Device Nodes in /dev. server # [ 6.199468] systemd[1]: Reached target Preparation for Local File Systems. server # [ 6.202949] systemd[1]: Starting Rule-based Manager for Device Events and Files... server # [ 6.203969] systemd[1]: Finished Apply Kernel Variables. server # [ 6.207164] systemd[1]: Finished Flush Journal to Persistent Storage. builder # [ 6.210212] systemd-udevd[315]: Using default interface naming scheme 'v261'. server # [ 6.238097] systemd-udevd[313]: Using default interface naming scheme 'v261'. builder # [ 6.287350] systemd[1]: Started Rule-based Manager for Device Events and Files. server # [ 6.309394] systemd[1]: Started Rule-based Manager for Device Events and Files. builder # [ 6.335481] systemd[1]: Finished Coldplug All udev Devices. server # [ 6.433255] systemd[1]: Finished Coldplug All udev Devices. builder # [ 6.439089] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse server # [ 6.499491] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse builder # [ 6.527517] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. builder # [ 6.552047] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. server # [ 6.589199] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. builder # [ 6.591563] systemd[1]: Mounting /run/wrappers... builder # [ 6.603532] (udev-worker)[356]: Network interface NamePolicy= disabled on kernel command line. builder # [ 6.611688] (udev-worker)[353]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. builder # [ 6.614128] (udev-worker)[353]: Network interface NamePolicy= disabled on kernel command line. server # [ 6.630812] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. server # [ 6.639705] systemd[1]: Mounting /run/wrappers... builder # [ 6.643141] systemd[1]: Mounted /run/wrappers. builder # [ 6.643840] systemd[1]: Reached target Local File Systems. builder # [ 6.648103] systemd[1]: Listening on Boot Loader Control Service Socket. server # [ 6.653184] (udev-worker)[340]: Network interface NamePolicy= disabled on kernel command line. builder # [ 6.653534] systemd[1]: Starting register-nix-paths.service... builder # [ 6.658080] systemd[1]: Starting Create SUID/SGID Wrappers... builder # [ 6.659122] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. server # [ 6.661070] (udev-worker)[335]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. server # [ 6.665832] (udev-worker)[335]: Network interface NamePolicy= disabled on kernel command line. builder # [ 6.665109] systemd[1]: Starting Save Transient machine-id to Disk... builder # [ 6.675415] systemd[1]: Starting Create System Files and Directories... server # [ 6.680911] systemd[1]: Mounted /run/wrappers. server # [ 6.681700] systemd[1]: Reached target Local File Systems. server # [ 6.683154] systemd[1]: Listening on Boot Loader Control Service Socket. server # [ 6.684942] systemd[1]: Starting register-nix-paths.service... server # [ 6.687563] systemd[1]: Starting Create SUID/SGID Wrappers... server # [ 6.689401] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. server # [ 6.694251] systemd[1]: Starting Save Transient machine-id to Disk... server # [ 6.698313] systemd[1]: Starting Create System Files and Directories... builder # [ 6.765185] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. builder # [ 6.768622] systemd[1]: Finished Save Transient machine-id to Disk. server # [ 6.785906] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. server # [ 6.789863] systemd[1]: Finished Save Transient machine-id to Disk. builder # [ 6.807218] systemd[1]: Finished Create System Files and Directories. builder # [ 6.814185] systemd[1]: Starting Rebuild Journal Catalog... builder # [ 6.817239] systemd[1]: Starting Record System Boot/Shutdown in UTMP... server # [ 6.848168] systemd[1]: Finished Create System Files and Directories. server # [ 6.853866] systemd[1]: Starting Rebuild Journal Catalog... server # [ 6.856219] systemd[1]: Starting Record System Boot/Shutdown in UTMP... builder # [ 6.892896] systemd[1]: Finished Record System Boot/Shutdown in UTMP. server # [ 6.933397] systemd[1]: Finished Record System Boot/Shutdown in UTMP. builder # [ 6.946137] systemd[1]: Finished Rebuild Journal Catalog. builder # [ 6.951151] systemd[1]: Starting Update is Completed... builder # [ 6.973995] systemd[1]: Condition check resulted in Virtio network device being skipped. builder # [ 6.975989] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore builder # [ 6.978768] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. builder # [ 6.981513] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 server # [ 6.987115] systemd[1]: Finished Rebuild Journal Catalog. builder # [ 6.983849] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore builder # [ 6.986343] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os server # [ 6.991163] systemd[1]: Starting Update is Completed... builder # [ 6.989223] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os builder # [ 7.023065] systemd[1]: Finished Update is Completed. server # [ 7.047313] systemd[1]: Condition check resulted in Virtio network device being skipped. server # [ 7.049194] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore server # [ 7.051540] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. server # [ 7.055846] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 server # [ 7.057936] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore server # [ 7.059520] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os server # [ 7.062469] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os server # [ 7.066385] systemd[1]: Finished Update is Completed. builder # [ 7.165268] systemd[1]: Finished Firewall. builder # [ 7.334191] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console builder # [ 7.342659] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 builder # [ 7.257631] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. builder # [ 7.262485] systemd[1]: Finished Create SUID/SGID Wrappers. server # [ 7.312649] systemd[1]: Finished Firewall. builder # [ 7.313737] systemd[1]: Finished register-nix-paths.service. builder # [ 7.314714] systemd[1]: Reached target System Initialization. builder # [ 7.318208] systemd[1]: Started Discard unused filesystem blocks once a week. server # [ 7.459468] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 builder # [ 7.319313] systemd[1]: Started Daily Cleanup of Temporary Directories. server # [ 7.322421] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. builder # [ 7.320929] systemd[1]: Reached target Timer Units. server # [ 7.323423] systemd[1]: Finished Create SUID/SGID Wrappers. builder # [ 7.321922] systemd[1]: Listening on D-Bus System Message Bus Socket. builder # [ 7.323979] systemd[1]: Starting niks3 auto-upload socket... server # [ 7.465659] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console builder # [ 7.326193] systemd[1]: Listening on Nix Daemon Socket. builder # [ 7.326912] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. builder # [ 7.330040] systemd[1]: Listening on niks3 auto-upload socket. builder # [ 7.330853] systemd[1]: Reached target Socket Units. builder # [ 7.333512] systemd[1]: Starting D-Bus System Message Bus... server # [ 7.362111] systemd[1]: Finished register-nix-paths.service. server # [ 7.363124] systemd[1]: Reached target System Initialization. server # [ 7.363914] systemd[1]: Started Discard unused filesystem blocks once a week. server # [ 7.364886] systemd[1]: Started niks3 garbage collection timer. server # [ 7.365878] systemd[1]: Started Daily Cleanup of Temporary Directories. server # [ 7.366843] systemd[1]: Reached target Timer Units. server # [ 7.367577] systemd[1]: Listening on D-Bus System Message Bus Socket. server # [ 7.369272] systemd[1]: Listening on niks3 server socket. server # [ 7.377105] systemd[1]: Listening on Nix Daemon Socket. server # [ 7.377872] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. server # [ 7.379840] systemd[1]: Reached target Socket Units. server # [ 7.380577] systemd[1]: Reached target Basic System. server # [ 7.382115] systemd[1]: Started backdoor.service. server # [ 7.385148] systemd[1]: Starting Import lastlog data into lastlog2 database... server # [ 7.390193] systemd[1]: Starting Generate test mTLS certs... builder # [ 7.391540] dbus-broker-launch[504]: Looking up NSS user entry for 'systemd-timesync'... server # [ 7.395079] systemd[1]: Starting Name Service Cache Daemon (nsncd)... server # [ 7.397255] systemd[1]: Starting Post-Boot Actions... builder # [ 7.396251] dbus-broker-launch[504]: NSS returned no entry for 'systemd-timesync' builder # [ 7.397568] dbus-broker-launch[504]: Invalid user-name in /nix/store/01mgrgjbbyxf1g5p5aj0kwc9qlhxz80n-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" builder # [ 7.409574] ACPI: button: Power Button [PWRF] builder # [ 7.419978] mousedev: PS/2 mouse device common for all mice server # [ 7.406230] systemd[1]: Started Reset console on configuration changes. builder # [ 7.407325] systemd[1]: Started D-Bus System Message Bus. builder # [ 7.408481] systemd[1]: Reached target Basic System. server # [ 7.413211] systemd[1]: Starting resolvconf update... builder # [ 7.412079] systemd[1]: Started backdoor.service. builder # [ 7.414382] systemd[1]: Starting Import lastlog data into lastlog2 database... server # [ 7.418800] systemd[1]: Starting D-Bus System Message Bus... builder # [ 7.419431] systemd[1]: Starting Name Service Cache Daemon (nsncd)... builder # [ 7.424340] systemd[1]: Starting Post-Boot Actions... builder # [ 7.430192] systemd[1]: Started Reset console on configuration changes. builder # [ 7.509997] rtc_cmos PNP0B00:00: RTC can wake from S4 builder # [ 7.519345] rtc_cmos PNP0B00:00: registered as rtc0 builder # [ 7.519419] rtc_cmos PNP0B00:00: setting system clock to 2026-09-20T16:24:32 UTC (1789921472) builder # [ 7.456591] systemd[1]: Starting resolvconf update... builder # [ 7.519770] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs builder # [ 7.505276] dbus-broker-launch[504]: Ready server # [ 7.529848] systemd[1]: Finished Post-Boot Actions. builder # [ 7.529321] systemd[1]: Finished Post-Boot Actions. builder # [ 7.526101] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4 server # [ 7.557808] nsncd[520]: Sep 20 16:24:32.670 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" server # [ 7.562226] systemd[1]: Started Name Service Cache Daemon (nsncd). builder # [ 7.561490] systemd[1]: Started Name Service Cache Daemon (nsncd). builder # connecting to host... server # [ 7.565719] systemd[1]: Reached target Host and Network Name Lookups. server # connecting to host... server # [ 7.569121] systemd[1]: Reached target User and Group Name Lookups. server # [ 7.571617] systemd[1]: Starting User Login Management... builder # [ 7.567972] nsncd[508]: Sep 20 16:24:32.679 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" builder # [ 7.573623] systemd[1]: Reached target Host and Network Name Lookups. builder # [ 7.576120] systemd[1]: Reached target User and Group Name Lookups. builder # [ 7.576961] systemd[1]: Starting User Login Management... builder # [ 7.532555] parport_pc 00:02: reported by Plug and Play ACPI builder # [ 7.532645] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] builder # [ 7.541632] Console: switching to colour dummy device 80x25 builder # [ 7.598713] systemd[1]: Finished Import lastlog data into lastlog2 database. server # [ 7.601576] systemd[1]: Finished Import lastlog data into lastlog2 database. server # [ 7.611861] niks3-test-certs-start[534]: ----- server: Guest shell says: b'Spawning backdoor root shell...\n' server: connected to guest root shell server: (connecting took 8.30 seconds) server: (finished: waiting for the VM to finish booting, in 8.30 seconds) builder # [ 7.698097] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized builder # [ 7.778696] [drm] Found bochs VGA, ID 0xb0c5. builder # [ 7.778699] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. builder # [ 7.802338] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt builder # [ 7.803016] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD builder # [ 7.819268] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic builder # [ 7.820105] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 server # [ 7.687887] niks3-test-certs-start[558]: ----- builder # [ 7.693455] systemd[1]: Stopped target Host and Network Name Lookups. builder # [ 7.695301] systemd[1]: Stopping Host and Network Name Lookups... builder # [ 7.699564] systemd[1]: Stopped target User and Group Name Lookups. server # [ 7.491573] ACPI: button: Power Button [PWRF] builder # [ 7.701060] systemd[1]: Stopping User and Group Name Lookups... server # [ 7.670959] rtc_cmos PNP0B00:00: RTC can wake from S4 builder # [ 7.706617] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... server # [ 7.710541] dbus-broker-launch[524]: Looking up NSS user entry for 'systemd-timesync'... server # [ 7.711599] dbus-broker-launch[524]: NSS returned no entry for 'systemd-timesync' builder # [ 7.708137] systemd[1]: nscd.service: Deactivated successfully. server # [ 7.712540] dbus-broker-launch[524]: Invalid user-name in /nix/store/vj7kh9b45jkm37c6v1x3qp4qbkrgrvkm-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" builder # [ 7.712105] systemd[1]: Stopped Name Service Cache Daemon (nsncd). builder # [ 7.712885] systemd[1]: Starting Name Service Cache Daemon (nsncd)... server # [ 7.718239] systemd[1]: Started D-Bus System Message Bus. builder # [ 7.716570] systemd-logind[531]: New seat seat0. builder # [ 7.720552] systemd[1]: Started User Login Management. builder # [ 7.721849] systemd[1]: Starting linger-users.service... server # [ 7.729075] dbus-broker-launch[524]: Ready builder # [ 7.770812] systemd[1]: linger-users.service: Deactivated successfully. builder # [ 7.773376] systemd[1]: Finished linger-users.service. builder # [ 7.781535] systemd[1]: Started Name Service Cache Daemon (nsncd). server # [ 7.784382] niks3-test-certs-start[571]: Certificate request self-signature ok builder # [ 7.782377] systemd[1]: Reached target Host and Network Name Lookups. server # [ 7.785410] niks3-test-certs-start[571]: subject=CN=server builder # [ 7.783544] systemd[1]: Reached target User and Group Name Lookups. builder # [ 7.784812] nsncd[577]: Sep 20 16:24:32.902 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" server # [ 7.720382] mousedev: PS/2 mouse device common for all mice server # [ 7.757442] rtc_cmos PNP0B00:00: registered as rtc0 server # [ 7.757507] rtc_cmos PNP0B00:00: setting system clock to 2026-09-20T16:24:32 UTC (1789921472) builder # [ 7.806814] systemd[1]: Finished resolvconf update. builder # [ 7.807569] systemd[1]: Reached target Preparation for Network. builder # [ 7.816123] systemd[1]: Starting DHCP Client... builder # [ 7.819293] systemd[1]: Starting Address configuration of eth1... builder # [ 7.826069] systemd[1]: Starting Extra networking commands.... server # [ 7.757582] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs server # [ 7.759875] systemd-journald[282]: Time jumped backwards, rotating. server # [ 7.841591] Console: switching to colour dummy device 80x25 builder # [ 7.922586] network-addresses-eth1-start[608]: adding address 192.168.1.1/24... done server # [ 7.872340] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4 builder # [ 7.935456] network-addresses-eth1-start[608]: adding address 2001:db8:1::1/64... done builder # [ 7.955968] systemd[1]: Finished Address configuration of eth1. server # [ 7.888065] parport_pc 00:02: reported by Plug and Play ACPI server # [ 7.888145] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] server # [ 7.955919] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized server # [ 8.099176] [drm] Found bochs VGA, ID 0xb0c5. server # [ 8.099178] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. server # [ 7.816128] niks3-test-certs-start[596]: ----- server # [ 7.966624] systemd[1]: Stopped target Host and Network Name Lookups. server # [ 7.975172] niks3-test-certs-start[598]: Certificate request self-signature ok builder # [ 7.975795] systemd[1]: Starting Virtual Console Setup... server # [ 7.978204] niks3-test-certs-start[598]: subject=CN=niks3 test client server # [ 7.979109] systemd[1]: Stopping Host and Network Name Lookups... builder # [ 7.951743] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 server # [ 7.982317] systemd[1]: Stopped target User and Group Name Lookups. server # [ 7.987270] nsncd[606]: Sep 20 16:24:32.804 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" server # [ 7.990882] systemd[1]: Stopping User and Group Name Lookups... server # [ 7.995140] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... server # [ 7.996055] systemd[1]: nscd.service: Deactivated successfully. server # [ 7.996871] systemd[1]: Stopped Name Service Cache Daemon (nsncd). server # [ 7.997741] systemd[1]: Starting Name Service Cache Daemon (nsncd)... server # [ 8.001509] systemd[1]: Started Name Service Cache Daemon (nsncd). server # [ 8.002426] systemd[1]: Reached target Host and Network Name Lookups. server # [ 8.005475] systemd[1]: Reached target User and Group Name Lookups. server # [ 8.008642] systemd[1]: Finished Generate test mTLS certs. server # [ 8.009395] systemd[1]: Finished resolvconf update. server # [ 8.010981] systemd[1]: Reached target Preparation for Network. server # [ 8.013117] systemd[1]: Starting DHCP Client... server # [ 8.014547] systemd[1]: Starting Address configuration of eth1... server # [ 8.016283] systemd[1]: Starting Extra networking commands.... server # [ 8.018821] systemd-logind[537]: New seat seat0. server # [ 8.022052] systemd[1]: Started User Login Management. server # [ 8.022829] systemd[1]: Starting linger-users.service... builder # [ 7.951983] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 server # [ 8.186676] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt server # [ 8.187400] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD server # [ 8.055685] systemd[1]: linger-users.service: Deactivated successfully. server # [ 8.058187] systemd[1]: Finished linger-users.service. builder # [ 8.118253] Console: switching to colour frame buffer device 160x50 builder # [ 8.203254] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device builder # [ 8.052935] dhcpcd[618]: dhcpcd-10.3.2 starting builder # [ 8.070160] dhcpcd[675]: dev: loaded udev server # [ 8.211740] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic builder # [ 8.075176] systemd[1]: Finished Extra networking commands.. builder # [ 8.076197] systemd[1]: Reached target Network. builder # [ 8.076829] systemd[1]: Starting Permit User Sessions... builder # [ 8.078164] systemd-logind[531]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) server # [ 8.221373] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 builder # [ 8.091269] systemd-logind[531]: Watching system buttons on /dev/input/event2 (Power Button) server # [ 8.094465] network-addresses-eth1-start[636]: adding address 192.168.1.2/24... done builder # [ 8.231702] 8021q: 802.1Q VLAN Support v1.8 builder # [ 8.232113] 8021q: adding VLAN 0 to HW filter on device eth1 server # [ 8.107266] network-addresses-eth1-start[636]: adding address 2001:db8:1::2/64... done server # [ 8.127158] systemd[1]: Finished Address configuration of eth1. builder # [ 8.124786] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. builder # [ 8.133131] systemd[1]: Stopped Virtual Console Setup. builder # [ 8.138064] systemd[1]: Starting Virtual Console Setup... builder # [ 8.148248] systemd[1]: Finished Permit User Sessions. builder # [ 8.153468] systemd[1]: Started Getty on tty1. builder # [ 8.157230] systemd[1]: Reached target Login Prompts. server # [ 8.200605] dhcpcd[648]: dhcpcd-10.3.2 starting server # [ 8.209476] dhcpcd[704]: dev: loaded udev server # [ 8.215377] systemd[1]: Finished Extra networking commands.. server # [ 8.218114] systemd[1]: Reached target Network. server # [ 8.218780] systemd[1]: Started Mock OIDC server for testing. server # [ 8.224316] systemd[1]: Starting Nginx Web Server... server # [ 8.228363] systemd[1]: Starting PostgreSQL Server... server # [ 8.234289] systemd[1]: Started RustFS S3-compatible object storage. builder # [ 8.377959] cfg80211: Loading compiled-in X.509 certificates for regulatory database server # [ 8.247326] systemd[1]: Starting Setup RustFS bucket... server # [ 8.251029] systemd[1]: Starting Permit User Sessions... builder # [ 8.390040] ppdev: user-space parallel port driver builder # [ 8.411045] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' builder # [ 8.411748] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' builder # [ 8.274693] systemd-logind[531]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) builder # [ 8.414921] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 builder # [ 8.415738] cfg80211: failed to load regulatory.db builder # [ 8.466786] 8021q: adding VLAN 0 to HW filter on device eth0 builder # [ 8.331616] dhcpcd[675]: eth0: waiting for carrier builder # [ 8.332560] dhcpcd[675]: libudev: received NULL device builder # [ 8.333258] dhcpcd[675]: libudev: received NULL device builder # [ 8.334067] dhcpcd[675]: eth0: carrier acquired builder # [ 8.477135] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660) builder # [ 8.342265] dhcpcd[675]: DUID 00:01:00:01:32:42:c5:41:52:54:00:12:34:56 builder # [ 8.343221] dhcpcd[675]: eth0: IAID 00:12:34:56 builder # [ 8.343821] dhcpcd[675]: eth0: adding address fe80::5054:ff:fe12:3456 builder # [ 8.366116] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. builder # [ 8.509933] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0) server # [ 8.387969] systemd[1]: Finished Permit User Sessions. builder # [ 8.389270] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. builder # [ 8.391983] systemd[1]: Stopped Virtual Console Setup. builder # [ 8.397849] systemd[1]: Starting Virtual Console Setup... builder # [ 8.628642] kvm_amd: TSC scaling supported builder # [ 8.629063] kvm_amd: Nested Virtualization enabled builder # [ 8.629755] kvm_amd: Nested Paging enabled builder # [ 8.630149] kvm_amd: LBR virtualization supported builder # [ 8.630853] kvm_amd: Virtual VMLOAD VMSAVE supported builder # [ 8.631321] kvm_amd: Virtual GIF supported builder # [ 8.631885] kvm_amd: Virtual NMI enabled server # [ 8.519706] mock-oidc-server[706]: Mock OIDC Server running server # [ 8.522367] mock-oidc-server[706]: OIDC Address: 127.0.0.1:8080 server # [ 8.523883] mock-oidc-server[706]: Issue Address: 127.0.0.1:8081 server # [ 8.528143] mock-oidc-server[706]: Issuer: http://127.0.0.1:8080/oidc server # [ 8.528957] mock-oidc-server[706]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json server # [ 8.529972] mock-oidc-server[706]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration server # [ 8.531189] mock-oidc-server[706]: Issue tokens: http://127.0.0.1:8081/issue?sub=... builder # [ 8.668260] EDAC MC: Ver: 3.0.0 server # [ 8.361061] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 builder # [ 8.578747] systemd-vconsole-setup[700]: Configuration of first virtual console was skipped, ignoring remaining ones. builder # [ 8.581500] systemd[1]: Finished Virtual Console Setup. server # [ 8.589548] dhcpcd[704]: libudev: received NULL device server # [ 8.590336] dhcpcd[704]: eth0: waiting for carrier server # [ 8.591669] dhcpcd[704]: libudev: received NULL device server # [ 8.593134] dhcpcd[704]: eth0: carrier acquired server # [ 8.618814] dhcpcd[704]: DUID 00:01:00:01:32:42:c5:41:52:54:00:12:34:56 server # [ 8.620616] dhcpcd[704]: eth0: IAID 00:12:34:56 server # [ 8.622137] dhcpcd[704]: eth0: adding address fe80::5054:ff:fe12:3456 server # [ 8.361472] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 server # [ 8.380514] 8021q: 802.1Q VLAN Support v1.8 server # [ 8.380527] 8021q: adding VLAN 0 to HW filter on device eth1 server # [ 8.673540] systemd-logind[537]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) server # [ 8.692632] systemd-logind[537]: Watching system buttons on /dev/input/event2 (Power Button) server # [ 8.529382] cfg80211: Loading compiled-in X.509 certificates for regulatory database server # [ 8.705598] dhcpcd[704]: eth0: soliciting a DHCP lease server # [ 8.716453] nginx-pre-start[740]: nginx: the configuration file /nix/store/gmsfsh3cg82ymbpkm9bijq82cs3b9793-nginx.conf syntax is ok server # [ 8.718213] nginx-pre-start[740]: nginx: configuration file /nix/store/gmsfsh3cg82ymbpkm9bijq82cs3b9793-nginx.conf test is successful server # [ 8.571552] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' server # [ 8.571666] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' server # [ 8.737489] dhcpcd[704]: eth0: offered 10.0.2.15 from 10.0.2.2 server # [ 8.739455] dhcpcd[704]: eth0: probing address 10.0.2.15/24 server # [ 8.572474] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 server # [ 8.572481] cfg80211: failed to load regulatory.db server # [ 8.651043] Console: switching to colour frame buffer device 160x50 server # [ 8.725221] 8021q: adding VLAN 0 to HW filter on device eth0 server # [ 8.790566] postgresql-pre-start[746]: The files belonging to this database system will be owned by user "postgres". server # [ 8.867139] NET: Registered PF_PACKET protocol family server # [ 8.930845] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device server # [ 8.795421] postgresql-pre-start[746]: This user must also own the server process. server # [ 8.801898] systemd[1]: Started Getty on tty1. server # [ 8.805362] systemd[1]: Reached target Login Prompts. server # [ 8.809403] postgresql-pre-start[746]: The database cluster will be initialized with locale "en_US.UTF-8". server # [ 8.811098] postgresql-pre-start[746]: The default database encoding has accordingly been set to "UTF8". server # [ 8.814380] postgresql-pre-start[746]: The default text search configuration will be set to "english". server # [ 8.815521] postgresql-pre-start[746]: Data page checksums are enabled. server # [ 8.816433] postgresql-pre-start[746]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok server # [ 8.817654] systemd[1]: Started Nginx Web Server. server # [ 8.818317] postgresql-pre-start[746]: creating subdirectories ... ok server # [ 8.819162] postgresql-pre-start[746]: selecting dynamic shared memory implementation ... posix server # [ 8.827094] systemd[1]: Starting Virtual Console Setup... server # [ 8.846284] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. server # [ 8.847309] systemd[1]: Stopped Virtual Console Setup. server # [ 8.850962] systemd[1]: Starting Virtual Console Setup... server # [ 8.982168] systemd-logind[537]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) server # [ 9.130886] ppdev: user-space parallel port driver server # [ 9.036861] postgresql-pre-start[746]: selecting default "max_connections" ... 100 server # [ 9.232027] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660) server # [ 9.110479] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. server # [ 9.274401] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0) server # [ 9.214425] postgresql-pre-start[746]: selecting default "shared_buffers" ... 128MB server # [ 9.226084] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. server # [ 9.227255] systemd[1]: Stopped Virtual Console Setup. server # [ 9.230834] systemd[1]: Starting Virtual Console Setup... server # [ 9.563770] kvm_amd: TSC scaling supported server # [ 9.564223] kvm_amd: Nested Virtualization enabled server # [ 9.565207] kvm_amd: Nested Paging enabled server # [ 9.566379] kvm_amd: LBR virtualization supported server # [ 9.566911] kvm_amd: Virtual VMLOAD VMSAVE supported server # [ 9.568772] kvm_amd: Virtual GIF supported server # [ 9.569178] kvm_amd: Virtual NMI enabled server # [ 9.520327] systemd-vconsole-setup[783]: Configuration of first virtual console was skipped, ignoring remaining ones. server # [ 9.524566] systemd[1]: Finished Virtual Console Setup. server # [ 9.666133] EDAC MC: Ver: 3.0.0 server # [ 10.070222] postgresql-pre-start[746]: selecting default time zone ... UTC server # [ 10.073362] postgresql-pre-start[746]: creating configuration files ... ok server # [ 10.252273] postgresql-pre-start[746]: running bootstrap script ... ok builder # [ 10.249408] dhcpcd[675]: eth0: soliciting a DHCP lease builder # [ 10.405087] NET: Registered PF_PACKET protocol family builder # [ 10.273990] dhcpcd[675]: eth0: offered 10.0.2.15 from 10.0.2.2 builder # [ 10.276362] dhcpcd[675]: eth0: probing address 10.0.2.15/24 builder # [ 10.592222] dhcpcd[675]: eth0: soliciting an IPv6 router builder # [ 10.593602] dhcpcd[675]: eth0: Router Advertisement from fe80::2 builder # [ 10.594748] dhcpcd[675]: eth0: adding address fec0::5054:ff:fe12:3456/64 builder # [ 10.595785] dhcpcd[675]: eth0: adding route to fec0::/64 builder # [ 10.596634] dhcpcd[675]: eth0: adding default route via fe80::2 server # [ 10.629094] postgresql-pre-start[746]: performing post-bootstrap initialization ... ok server # [ 10.648670] dhcpcd[704]: eth0: soliciting an IPv6 router server # [ 10.650674] dhcpcd[704]: eth0: Router Advertisement from fe80::2 server # [ 10.652256] dhcpcd[704]: eth0: adding address fec0::5054:ff:fe12:3456/64 server # [ 10.653631] dhcpcd[704]: eth0: adding route to fec0::/64 server # [ 10.654595] dhcpcd[704]: eth0: adding default route via fe80::2 server # [ 10.759921] postgresql-pre-start[746]: syncing data to disk ... ok server # [ 10.761677] postgresql-pre-start[746]: initdb: warning: enabling "trust" authentication for local connections server # [ 10.762884] postgresql-pre-start[746]: initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb. server # [ 10.764780] postgresql-pre-start[746]: Success. You can now start the database server using: server # [ 10.765978] postgresql-pre-start[746]: pg_ctl -D /var/lib/postgresql/18 -l logfile start server # [ 10.838178] postgres[810]: [810] LOG: starting PostgreSQL 18.6 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit server # [ 10.840643] postgres[810]: [810] LOG: listening on IPv6 address "::1", port 5432 server # [ 10.841655] postgres[810]: [810] LOG: listening on IPv4 address "127.0.0.1", port 5432 server # [ 10.844461] postgres[810]: [810] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" server # [ 10.856261] postgres[819]: [819] LOG: database system was shut down at 2026-09-20 16:24:35 GMT server # [ 10.862047] postgres[810]: [810] LOG: database system is ready to accept connections server # [ 10.867221] systemd[1]: Started PostgreSQL Server. server # [ 10.871155] systemd[1]: Starting PostgreSQL Setup Scripts... server # [ 10.993898] postgresql-setup-start[830]: CREATE DATABASE server # [ 11.022338] postgresql-setup-start[835]: CREATE ROLE server # [ 11.034232] postgresql-setup-start[837]: ALTER DATABASE server # [ 11.039125] systemd[1]: Finished PostgreSQL Setup Scripts. server # [ 11.040613] systemd[1]: Reached target PostgreSQL. server: (finished: waiting for unit postgresql.service, in 11.75 seconds) server: waiting for unit rustfs.service server: (finished: waiting for unit rustfs.service, in 0.02 seconds) server: waiting for unit rustfs-setup.service server # [ 12.974855] dhcpcd[704]: eth0: leased 10.0.2.15 for 86400 seconds server # [ 12.977574] dhcpcd[704]: eth0: adding route to 10.0.2.0/24 server # [ 12.979706] dhcpcd[704]: eth0: adding default route via 10.0.2.2 server # [ 13.060927] systemd[1]: Started DHCP Client. builder # [ 15.390144] dhcpcd[675]: eth0: leased 10.0.2.15 for 86400 seconds builder # [ 15.391051] dhcpcd[675]: eth0: adding route to 10.0.2.0/24 builder # [ 15.391733] dhcpcd[675]: eth0: adding default route via 10.0.2.2 builder # [ 15.449428] systemd[1]: Started DHCP Client. builder # [ 15.451271] systemd[1]: Reached target Multi-User System. builder # [ 15.451978] systemd[1]: Startup finished in 855ms (kernel) + 3.634s (initrd) + 10.961s (userspace) = 15.451s. server # [ 22.011338] rustfs-setup-start[955]: mb s3://niks3-test server # [ 22.022274] systemd[1]: Finished Setup RustFS bucket. server # [ 22.031253] systemd[1]: Starting niks3 server... server # [ 22.145399] postgres[969]: [969] ERROR: relation "goose_db_version" does not exist at character 36 server # [ 22.146882] postgres[969]: [969] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC server # [ 22.169037] niks3-server[964]: 2026/09/20 16:24:47 OK 20241026095416_initial_model.sql (11.47ms) server # [ 22.174179] niks3-server[964]: 2026/09/20 16:24:47 OK 20251210153512_drop_unused_gin_index.sql (4.2ms) server # [ 22.178188] niks3-server[964]: 2026/09/20 16:24:47 OK 20251218171726_add_pins.sql (5.23ms) server # [ 22.182257] niks3-server[964]: 2026/09/20 16:24:47 OK 20260628120000_add_object_size_and_stats.sql (2.83ms) server # [ 22.186258] niks3-server[964]: 2026/09/20 16:24:47 OK 20260905000000_add_claims.sql (3.97ms) server # [ 22.189800] niks3-server[964]: 2026/09/20 16:24:47 OK 20260920000000_drop_claims.sql (3.59ms) server # [ 22.191194] niks3-server[964]: 2026/09/20 16:24:47 goose: successfully migrated database to version: 20260920000000 server # [ 22.195024] niks3-server[964]: 2026/09/20 16:24:47 OK 1_commit_pending_closure.sql (3.73ms) server # [ 22.203860] niks3-server[964]: 2026/09/20 16:24:47 OK 2_object_stats_trigger.sql (8.78ms) server # [ 22.204952] niks3-server[964]: 2026/09/20 16:24:47 goose: up to current file version: 2 server # [ 22.211937] niks3-server[964]: 2026/09/20 16:24:47 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:8080/oidc server # [ 22.213407] niks3-server[964]: 2026/09/20 16:24:47 INFO OIDC authentication enabled config=/nix/store/sa3h30ggc0ih3s7pyx6hv16myf7hx0lj-niks3-oidc.json server # [ 22.215392] niks3-server[964]: 2026/09/20 16:24:47 INFO Loaded signing key name=niks3-test-1 path=/nix/store/bkg7syhrbck6xib4g0kaji8jiwjnl8sy-niks3-signing-key server # [ 22.245238] niks3-server[964]: 2026/09/20 16:24:47 INFO Using socket-activated listener address=0.0.0.0:5751 server # [ 22.247668] systemd[1]: Started niks3 server. server # [ 22.249663] niks3-server[964]: 2026/09/20 16:24:47 INFO systemd watchdog enabled interval=15s server # [ 22.250952] niks3-server[964]: 2026/09/20 16:24:47 INFO Starting HTTP server address=0.0.0.0:5751 server # [ 22.252095] systemd[1]: Reached target Multi-User System. server # [ 22.252820] systemd[1]: Startup finished in 844ms (kernel) + 3.665s (initrd) + 17.738s (userspace) = 22.248s. server: (finished: waiting for unit rustfs-setup.service, in 11.38 seconds) server: waiting for unit mock-oidc.service server: (finished: waiting for unit mock-oidc.service, in 0.02 seconds) server: waiting for unit niks3.service server: (finished: waiting for unit niks3.service, in 0.02 seconds) server: waiting for TCP port 5751 on localhost server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded! server: (finished: waiting for TCP port 5751 on localhost, in 0.02 seconds) server: waiting for TCP port 8080 on localhost server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded! server: (finished: waiting for TCP port 8080 on localhost, in 0.02 seconds) server: waiting for TCP port 9000 on localhost server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded! server: (finished: waiting for TCP port 9000 on localhost, in 0.02 seconds) server: must succeed: mkdir -p /tmp/test-config server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.01 seconds) server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.01 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 server # [ 22.716078] systemd[1]: Created slice Slice /system/nix-daemon. server # [ 22.719236] systemd[1]: Started Nix Daemon instance (PID 1013/UID 0). server # [ 22.761541] nix-daemon[1015]: remote pid 1013 is unknown user (trusted) server # [ 22.776976] systemd[1]: nix-daemon@0-1-1013_1014-0.service: Deactivated successfully. server # [ 22.782401] niks3-server[964]: 2026/09/20 16:24:47 INFO Received uploads request method=POST path=/api/pending_closures server # time=2026-09-20T16:24:47.682Z level=INFO msg="Uploading 5 paths to server (0 already cached)" server # time=2026-09-20T16:24:47.683Z level=INFO msg="Uploading bv3lx708clpz3m6zbd5742np4dlry0yj-libunistring-1.4.2 (2.0MB)" server # time=2026-09-20T16:24:47.684Z level=INFO msg="Uploading lm3pknxi0ipypy3lxh1wmm8wvvavdwrn-glibc-2.42-84 (33.4MB)" server # time=2026-09-20T16:24:47.684Z level=INFO msg="Uploading i3jw341xs88r6wxf1j22rjx1mmd8cfjw-libidn2-2.3.8 (359.5KB)" server # time=2026-09-20T16:24:47.684Z level=INFO msg="Uploading m07syxhld8hpprrdmzq565ziia6vlw9l-xgcc-15.3.0-libgcc (193.0KB)" server # time=2026-09-20T16:24:47.684Z level=INFO msg="Uploading xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 (273.1KB)" server # [ 22.975609] niks3-server[964]: 2026/09/20 16:24:47 INFO Registered completed upload object_key=nar/1gz22gfl2lcahqzma75rvxgmk9zcfrvarzywlarwy4hkf1k4iz44.nar.zst server # [ 23.005963] niks3-server[964]: 2026/09/20 16:24:47 INFO Registered completed upload object_key=nar/1bvhsl1iqnbbgmxgp2hi0j8nj5ip5n6h6m2awanbnhwrl8fazjvh.nar.zst server # [ 23.024967] niks3-server[964]: 2026/09/20 16:24:47 INFO Registered completed upload object_key=bv3lx708clpz3m6zbd5742np4dlry0yj.ls server # [ 23.060368] niks3-server[964]: 2026/09/20 16:24:47 INFO Registered completed upload object_key=m07syxhld8hpprrdmzq565ziia6vlw9l.ls server # [ 23.076184] niks3-server[964]: 2026/09/20 16:24:47 INFO Registered completed upload object_key=nar/0g2qsgc7dv9zl3i5nc523gicpy0xk2z4r1x4msr0inmsqjpm1ym9.nar.zst server # [ 23.093292] niks3-server[964]: 2026/09/20 16:24:47 INFO Registered completed upload object_key=nar/1ixxaz0n8nfxd2w29qq8frn6pjvdnjj3pl139gbi7jksrj9jd7jg.nar.zst server # [ 23.107521] niks3-server[964]: 2026/09/20 16:24:47 INFO Registered completed upload object_key=i3jw341xs88r6wxf1j22rjx1mmd8cfjw.ls server # [ 23.136976] niks3-server[964]: 2026/09/20 16:24:48 INFO Registered completed upload object_key=xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi.ls server # [ 23.650677] niks3-server[964]: 2026/09/20 16:24:48 INFO Received complete multipart upload request method=POST path=/api/multipart/complete server # [ 23.666297] niks3-server[964]: 2026/09/20 16:24:48 INFO Completed multipart upload object_key=nar/06kkplxk31y11zksgcrdjv0hf6ymlmxvlc0i2h08g3zc22is465s.nar.zst upload_id=ZmJmNTMwYjctODA2OC00ZmY0LWFlMTUtMzdhZjQ4ZWRmYjk0LjJjMmYyOTA2LTY1M2UtNDZlOC1iOWUwLWQ1OGY1NjI0OWZlOHgxNzg5OTIxNDg3NjczNTY5NTQ0 parts=1 server # [ 23.675627] niks3-server[964]: 2026/09/20 16:24:48 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign server # [ 23.680333] niks3-server[964]: 2026/09/20 16:24:48 INFO Signed narinfos id=1 count=5 server # time=2026-09-20T16:24:48.562Z level=INFO msg="Uploading 5 narinfos" server # [ 23.685951] niks3-server[964]: 2026/09/20 16:24:48 INFO Registered completed upload object_key=lm3pknxi0ipypy3lxh1wmm8wvvavdwrn.ls server # [ 23.698678] niks3-server[964]: 2026/09/20 16:24:48 INFO Registered completed upload object_key=i3jw341xs88r6wxf1j22rjx1mmd8cfjw.narinfo server # [ 23.703112] niks3-server[964]: 2026/09/20 16:24:48 INFO Registered completed upload object_key=m07syxhld8hpprrdmzq565ziia6vlw9l.narinfo server # [ 23.708674] niks3-server[964]: 2026/09/20 16:24:48 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete server # [ 23.720706] niks3-server[964]: 2026/09/20 16:24:48 INFO Completed upload id=1 server # time=2026-09-20T16:24:48.602Z level=INFO msg="Upload complete. (1.047s)" server # [ 23.723840] niks3-server[964]: 2026/09/20 16:24:48 INFO Registered completed upload object_key=xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi.narinfo server # time=2026-09-20T16:24:48.612Z level=WARN msg="Failed to register uploaded object" key=bv3lx708clpz3m6zbd5742np4dlry0yj.narinfo error="server returned 404: object is not pending upload\n" server # [ 23.736601] niks3-server[964]: 2026/09/20 16:24:48 INFO Registered completed upload object_key=lm3pknxi0ipypy3lxh1wmm8wvvavdwrn.narinfo server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 1.15 seconds) server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.01 seconds) server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 server # [ 23.798691] niks3-server[964]: 2026/09/20 16:24:48 WARN Authentication failed token_preview=invalid-token token_length=13 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test] server # [ 23.831728] systemd[1]: Started Nix Daemon instance (PID 1044/UID 0). server # [ 23.870299] nix-daemon[1046]: remote pid 1044 is unknown user (trusted) server # [ 23.880578] systemd[1]: nix-daemon@1-2-1044_1045-0.service: Deactivated successfully. server # [ 23.884175] niks3-server[964]: 2026/09/20 16:24:48 WARN Authentication failed token_preview=invalid-token token_length=13 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test] server # time=2026-09-20T16:24:48.768Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n" server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.14 seconds) server: waiting for unit nginx.service server: (finished: waiting for unit nginx.service, in 0.02 seconds) server: waiting for TCP port 443 on localhost server # Connection to localhost (::1) 443 port [tcp/https] succeeded! server: (finished: waiting for TCP port 443 on localhost, in 0.02 seconds) server: must succeed: /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/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/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 server # time=2026-09-20T16:24:48.864Z level=INFO msg="Configuring client TLS" cert=/etc/niks3-test-certs/client.pem key=/etc/niks3-test-certs/client.key ca=/etc/niks3-test-certs/ca.pem server # time=2026-09-20T16:24:48.879Z level=INFO msg="All 1 paths already cached" server: (finished: must succeed: /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/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/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.06 seconds) server: must fail: /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 server # time=2026-09-20T16:24:48.893Z level=ERROR msg="Fatal error" error="auth token is required (use --auth-token-path, --auth-token-script, NIKS3_AUTH_TOKEN_FILE, or $XDG_CONFIG_HOME/niks3/auth-token)" server: (finished: must fail: /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.01 seconds) server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 server # time=2026-09-20T16:24:48.938Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem server # time=2026-09-20T16:24:48.946Z level=INFO msg="All 1 paths already cached" server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.05 seconds) server: must succeed: cd /etc/niks3-test-certs && /nix/store/44gxi8li8y8wrxrb93sya9hljkpigsqw-openssl-3.6.4-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client' server # ----- server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/44gxi8li8y8wrxrb93sya9hljkpigsqw-openssl-3.6.4-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client', in 0.02 seconds) server: must succeed: cd /etc/niks3-test-certs && /nix/store/44gxi8li8y8wrxrb93sya9hljkpigsqw-openssl-3.6.4-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem server # Certificate request self-signature ok server # subject=CN=other client server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/44gxi8li8y8wrxrb93sya9hljkpigsqw-openssl-3.6.4-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem, in 0.03 seconds) server: must fail: /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/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/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 server # time=2026-09-20T16:24:49.033Z level=INFO msg="Configuring client TLS" cert=/etc/niks3-test-certs/other.pem key=/etc/niks3-test-certs/other.key ca=/etc/niks3-test-certs/ca.pem server # [ 24.160317] niks3-server[964]: 2026/09/20 16:24:49 WARN mTLS auth: subject not in bound subjects subject="CN=other client" server # [ 24.192948] systemd[1]: Started Nix Daemon instance (PID 1109/UID 0). server # [ 24.230279] nix-daemon[1111]: remote pid 1109 is unknown user (trusted) server # [ 24.240642] systemd[1]: nix-daemon@2-3-1109_1110-0.service: Deactivated successfully. server # [ 24.246526] niks3-server[964]: 2026/09/20 16:24:49 WARN mTLS auth: subject not in bound subjects subject="CN=other client" server # time=2026-09-20T16:24:49.129Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n" server: (finished: must fail: /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/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/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.14 seconds) server: must succeed: mkdir -p /tmp/test-store server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.01 seconds) server: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 server: (finished: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 , in 0.52 seconds) server: must succeed: cat > /tmp/test-drv.nix << 'EOF' derivation { name = "test-build-log"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ]; } EOF server: (finished: must succeed: cat > /tmp/test-drv.nix << 'EOF' derivation { name = "test-build-log"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ]; } EOF , in 0.01 seconds) server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring server # [ 24.844634] systemd[1]: Started Nix Daemon instance (PID 1155/UID 0). server # [ 24.882219] nix-daemon[1159]: remote pid 1155 is unknown user (trusted) server # this derivation will be built: server # /nix/store/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv server # building '/nix/store/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv'... server # test-build-log> test build log output server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.17 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log server # [ 24.978914] systemd[1]: nix-daemon@3-4-1155_1156-0.service: Deactivated successfully. server # [ 25.054862] systemd[1]: Started Nix Daemon instance (PID 1185/UID 0). server # [ 25.093801] nix-daemon[1187]: remote pid 1185 is unknown user (trusted) server # [ 25.103725] systemd[1]: nix-daemon@4-5-1185_1186-0.service: Deactivated successfully. server # [ 25.107615] niks3-server[964]: 2026/09/20 16:24:49 INFO Received uploads request method=POST path=/api/pending_closures server # time=2026-09-20T16:24:49.995Z level=INFO msg="Uploading 1 paths to server (0 already cached)" server # time=2026-09-20T16:24:49.996Z level=INFO msg="Uploading dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log (128B)" server # [ 25.135470] niks3-server[964]: 2026/09/20 16:24:50 INFO Registered completed upload object_key=nar/00zns3gj9hwz2a4b0i07y7nmxybq59lh24bl3xsxblcl6333mjil.nar.zst server # [ 25.138325] niks3-server[964]: 2026/09/20 16:24:50 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign server # [ 25.140548] niks3-server[964]: 2026/09/20 16:24:50 INFO Signed narinfos id=2 count=1 server # time=2026-09-20T16:24:50.022Z level=INFO msg="Uploading 1 narinfos" server # [ 25.146072] niks3-server[964]: 2026/09/20 16:24:50 INFO Registered completed upload object_key=log/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv server # [ 25.150920] niks3-server[964]: 2026/09/20 16:24:50 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete server # time=2026-09-20T16:24:50.038Z level=INFO msg="Upload complete. (137ms)" server # [ 25.158628] niks3-server[964]: 2026/09/20 16:24:50 INFO Completed upload id=2 server # [ 25.160493] niks3-server[964]: 2026/09/20 16:24:50 INFO Registered completed upload object_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.narinfo server # [ 25.163762] niks3-server[964]: 2026/09/20 16:24:50 INFO Registered completed upload object_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.ls server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log, in 0.20 seconds) server: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log server: (finished: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log , in 0.11 seconds) subtest: push --stdin streams paths and reports each one server: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }' server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring server # [ 25.319807] systemd[1]: Started Nix Daemon instance (PID 1203/UID 0). server # [ 25.357593] nix-daemon[1207]: remote pid 1203 is unknown user (trusted) server # this derivation will be built: server # /nix/store/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv server # building '/nix/store/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv'... server: (finished: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }', in 0.16 seconds) server: 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/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --stdin server # [ 25.445088] systemd[1]: nix-daemon@5-6-1203_1204-0.service: Deactivated successfully. server # [ 25.524195] systemd[1]: Started Nix Daemon instance (PID 1235/UID 0). server # [ 25.563247] nix-daemon[1237]: remote pid 1235 is unknown user (trusted) server # [ 25.573484] systemd[1]: nix-daemon@6-7-1235_1236-0.service: Deactivated successfully. server # [ 25.578842] niks3-server[964]: 2026/09/20 16:24:50 INFO Received uploads request method=POST path=/api/pending_closures server # time=2026-09-20T16:24:50.464Z level=INFO msg="Uploading 1 paths to server (0 already cached)" server # time=2026-09-20T16:24:50.466Z level=INFO msg="Uploading 3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test (120B)" server # [ 25.603473] niks3-server[964]: 2026/09/20 16:24:50 INFO Registered completed upload object_key=nar/1hj1r0g515rfxjx685mfn3dgr6akqbn4gf64snlv6bxaxph69lna.nar.zst server # [ 25.607304] niks3-server[964]: 2026/09/20 16:24:50 INFO Registered completed upload object_key=log/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv server # [ 25.610084] niks3-server[964]: 2026/09/20 16:24:50 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign server # [ 25.611719] niks3-server[964]: 2026/09/20 16:24:50 INFO Signed narinfos id=3 count=1 server # time=2026-09-20T16:24:50.493Z level=INFO msg="Uploading 1 narinfos" server # [ 25.616862] niks3-server[964]: 2026/09/20 16:24:50 INFO Registered completed upload object_key=3lzkfq9fx7rridh3i11ly8xyqdbqb44w.ls server # [ 25.620300] niks3-server[964]: 2026/09/20 16:24:50 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete server # time=2026-09-20T16:24:50.504Z level=INFO msg="Upload complete. (136ms)" server # [ 25.625047] niks3-server[964]: 2026/09/20 16:24:50 INFO Completed upload id=3 server # [ 25.627511] niks3-server[964]: 2026/09/20 16:24:50 INFO Registered completed upload object_key=3lzkfq9fx7rridh3i11ly8xyqdbqb44w.narinfo server: (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/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --stdin, in 0.19 seconds) server: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test server: (finished: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test , in 0.13 seconds) (finished: subtest: push --stdin streams paths and reports each one, in 0.49 seconds) server: must succeed: readlink /etc/niks3-test/symlink-wrapper server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.01 seconds) server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.01 seconds) server: must succeed: test -L /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper server: (finished: must succeed: test -L /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper, in 0.01 seconds) server: must succeed: readlink /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper server: (finished: must succeed: readlink /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper, in 0.01 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper server # [ 25.886937] systemd[1]: Started Nix Daemon instance (PID 1283/UID 0). server # [ 25.924295] nix-daemon[1285]: remote pid 1283 is unknown user (trusted) server # [ 25.934335] systemd[1]: nix-daemon@7-8-1283_1284-0.service: Deactivated successfully. server # [ 25.937910] niks3-server[964]: 2026/09/20 16:24:50 INFO Received uploads request method=POST path=/api/pending_closures server # time=2026-09-20T16:24:50.824Z level=INFO msg="Uploading 2 paths to server (0 already cached)" server # time=2026-09-20T16:24:50.825Z level=INFO msg="Uploading 1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper (192B)" server # time=2026-09-20T16:24:50.826Z level=INFO msg="Uploading x9d9hyw8xybijhpvipmkiryn9n8nw3ij-base-package (536B)" server # [ 25.965719] niks3-server[964]: 2026/09/20 16:24:50 INFO Registered completed upload object_key=nar/0v342j6sk8pcizzisl5bwzw5xdrdjdmynpgxd3f6827yp5bzbrl7.nar.zst server # [ 25.972376] niks3-server[964]: 2026/09/20 16:24:50 INFO Registered completed upload object_key=nar/0820av37plmfgrxw6pcbym6phk1sv14dilyrablrnkzb7z1fd5js.nar.zst server # [ 25.975953] niks3-server[964]: 2026/09/20 16:24:50 INFO Received sign narinfos request method=POST path=/api/pending_closures/4/sign server # [ 25.979853] niks3-server[964]: 2026/09/20 16:24:50 INFO Signed narinfos id=4 count=2 server # time=2026-09-20T16:24:50.861Z level=INFO msg="Uploading 2 narinfos" server # [ 25.983476] niks3-server[964]: 2026/09/20 16:24:50 INFO Registered completed upload object_key=1czajpbpppk7h9y4k4w3hxz0xs25srq3.ls server # [ 25.987117] niks3-server[964]: 2026/09/20 16:24:50 INFO Registered completed upload object_key=x9d9hyw8xybijhpvipmkiryn9n8nw3ij.ls server # [ 25.995204] niks3-server[964]: 2026/09/20 16:24:50 INFO Received complete upload request method=POST path=/api/pending_closures/4/complete server # [ 25.998263] niks3-server[964]: 2026/09/20 16:24:50 INFO Completed upload id=4 server # time=2026-09-20T16:24:50.880Z level=INFO msg="Upload complete. (147ms)" server # [ 26.001484] niks3-server[964]: 2026/09/20 16:24:50 INFO Registered completed upload object_key=1czajpbpppk7h9y4k4w3hxz0xs25srq3.narinfo server # [ 26.003670] niks3-server[964]: 2026/09/20 16:24:50 INFO Registered completed upload object_key=x9d9hyw8xybijhpvipmkiryn9n8nw3ij.narinfo server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper, in 0.20 seconds) server: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper server: (finished: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper , in 0.13 seconds) server: must succeed: cat > /tmp/oidc-test.nix << 'EOF' derivation { name = "oidc-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'OIDC test derivation' > $out" ]; } EOF server: (finished: must succeed: cat > /tmp/oidc-test.nix << 'EOF' derivation { name = "oidc-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'OIDC test derivation' > $out" ]; } EOF , in 0.01 seconds) server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring server # [ 26.193245] systemd[1]: Started Nix Daemon instance (PID 1312/UID 0). server # [ 26.232288] nix-daemon[1316]: remote pid 1312 is unknown user (trusted) server # this derivation will be built: server # /nix/store/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv server # building '/nix/store/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv'... server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link, in 0.16 seconds) server: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://server:5751&repository_owner=myorg' server # [ 26.319117] systemd[1]: nix-daemon@8-9-1312_1313-0.service: Deactivated successfully. server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://server:5751&repository_owner=myorg', in 0.03 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODk5MjUwOTEsImlhdCI6MTc4OTkyMTQ5MSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.W9vwtbE-lcIBNn2lWXchNlOM2x6-hT8DcSe1MH7Oc2pvqs3Cn1WikBM0BvVo_grXKhEpbrF67UsGmpk1wSvPzQa0N91-XPxnf8PDKxS3-WK_JdQ14wXBGIbN84g83FW-qNffNUx_ayKWDjDKL3s1kdFWg1v0wBw6K0SsxdcA68-9c8U3nzWjuXREszgup1bCIFltd-NCKVD-vDtoy7PrEYqryqXZYyBrRAxg_e-EIKhWYGmvIqOkODbkNcjOdjBvcLM69l1AdIx2YRF4IOpBZAncey9Fw2GYEVeaSszKiV5bg8SwqkPVcK6MCPtJIrKKcY8VgHzpUXrru6wfd9RNhQ' /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test server # time=2026-09-20T16:24:51.237Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script" server # [ 26.424221] systemd[1]: Started Nix Daemon instance (PID 1345/UID 0). server # [ 26.461341] nix-daemon[1347]: remote pid 1345 is unknown user (trusted) server # [ 26.471582] systemd[1]: nix-daemon@9-10-1345_1346-0.service: Deactivated successfully. server # [ 26.475307] niks3-server[964]: 2026/09/20 16:24:51 INFO Received uploads request method=POST path=/api/pending_closures server # time=2026-09-20T16:24:51.362Z level=INFO msg="Uploading 1 paths to server (0 already cached)" server # time=2026-09-20T16:24:51.363Z level=INFO msg="Uploading hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test (136B)" server # [ 26.497414] niks3-server[964]: 2026/09/20 16:24:51 INFO Registered completed upload object_key=nar/17h0qgwf5334wia961ibqy9hmpsyvfhrvj5ji9m031klp6sx4v0q.nar.zst server # [ 26.504551] niks3-server[964]: 2026/09/20 16:24:51 INFO Registered completed upload object_key=log/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv server # [ 26.506171] niks3-server[964]: 2026/09/20 16:24:51 INFO Received sign narinfos request method=POST path=/api/pending_closures/5/sign server # [ 26.508809] niks3-server[964]: 2026/09/20 16:24:51 INFO Signed narinfos id=5 count=1 server # time=2026-09-20T16:24:51.391Z level=INFO msg="Uploading 1 narinfos" server # [ 26.514792] niks3-server[964]: 2026/09/20 16:24:51 INFO Registered completed upload object_key=hp57zlhhih1lr1f0hfrf8sca8fvgxc2i.ls server # [ 26.518696] niks3-server[964]: 2026/09/20 16:24:51 INFO Received complete upload request method=POST path=/api/pending_closures/5/complete server # time=2026-09-20T16:24:51.403Z level=INFO msg="Upload complete. (134ms)" server # [ 26.523635] niks3-server[964]: 2026/09/20 16:24:51 INFO Completed upload id=5 server # [ 26.526124] niks3-server[964]: 2026/09/20 16:24:51 INFO Registered completed upload object_key=hp57zlhhih1lr1f0hfrf8sca8fvgxc2i.narinfo server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODk5MjUwOTEsImlhdCI6MTc4OTkyMTQ5MSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.W9vwtbE-lcIBNn2lWXchNlOM2x6-hT8DcSe1MH7Oc2pvqs3Cn1WikBM0BvVo_grXKhEpbrF67UsGmpk1wSvPzQa0N91-XPxnf8PDKxS3-WK_JdQ14wXBGIbN84g83FW-qNffNUx_ayKWDjDKL3s1kdFWg1v0wBw6K0SsxdcA68-9c8U3nzWjuXREszgup1bCIFltd-NCKVD-vDtoy7PrEYqryqXZYyBrRAxg_e-EIKhWYGmvIqOkODbkNcjOdjBvcLM69l1AdIx2YRF4IOpBZAncey9Fw2GYEVeaSszKiV5bg8SwqkPVcK6MCPtJIrKKcY8VgHzpUXrru6wfd9RNhQ' /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test, in 0.19 seconds) server: must succeed: cat > /tmp/oidc-test2.nix << 'EOF' derivation { name = "oidc-test2"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'OIDC test 2' > $out" ]; } EOF server: (finished: must succeed: cat > /tmp/oidc-test2.nix << 'EOF' derivation { name = "oidc-test2"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'OIDC test 2' > $out" ]; } EOF , in 0.01 seconds) server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring server # [ 26.586256] systemd[1]: Started Nix Daemon instance (PID 1357/UID 0). server # [ 26.625382] nix-daemon[1361]: remote pid 1357 is unknown user (trusted) server # this derivation will be built: server # /nix/store/1sl2zp65pyaksf29w1lxwgl7mc3mrw4x-oidc-test2.drv server # building '/nix/store/1sl2zp65pyaksf29w1lxwgl7mc3mrw4x-oidc-test2.drv'... server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link, in 0.16 seconds) server: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:otherorg/repo:ref:refs/heads/main&aud=http://server:5751&repository_owner=otherorg' server # [ 26.714322] systemd[1]: nix-daemon@10-11-1357_1358-0.service: Deactivated successfully. server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:otherorg/repo:ref:refs/heads/main&aud=http://server:5751&repository_owner=otherorg', in 0.03 seconds) server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODk5MjUwOTEsImlhdCI6MTc4OTkyMTQ5MSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.fftWnJNnyh_WP8OBopCSr-X-9XlRtWFhZb5pnU0Zu3YzvlHrI-aXwBKkmYADYkOC2gM-6bQuj2_dXdLLIWpD3-gy1OyfmniYIDQCe6qo-vxnCgEonXZBFojv_ROkxX-_bkBjLcSf9yqUXjBMSj7OBTNqylkUoat31EsOwSIkN9VP7Ex8yxxmva3xVE9MuEHE2HUvLazDujMTn8G-Hx-q2k8yaYlOSJVHJoozsIM0X3SYGid2cHAzzc-THhTIdoZhK8K0fCxVYkNrftOcAzmSOWCOdWajuw55_O3wPds1cI_QToh31O4mG8OrZh7QhqYYIzBVEfzMIb7yaPX5zJu2AA' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2 server # time=2026-09-20T16:24:51.628Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script" server # [ 26.783044] niks3-server[964]: 2026/09/20 16:24:51 WARN Authentication failed token_preview=eyJhbGciOi...aPX5zJu2AA token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test] server # [ 26.816850] systemd[1]: Started Nix Daemon instance (PID 1391/UID 0). server # [ 26.855147] nix-daemon[1393]: remote pid 1391 is unknown user (trusted) server # [ 26.865964] systemd[1]: nix-daemon@11-12-1391_1392-0.service: Deactivated successfully. server # [ 26.870388] niks3-server[964]: 2026/09/20 16:24:51 WARN Authentication failed token_preview=eyJhbGciOi...aPX5zJu2AA token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test] server # time=2026-09-20T16:24:51.754Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n" server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODk5MjUwOTEsImlhdCI6MTc4OTkyMTQ5MSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.fftWnJNnyh_WP8OBopCSr-X-9XlRtWFhZb5pnU0Zu3YzvlHrI-aXwBKkmYADYkOC2gM-6bQuj2_dXdLLIWpD3-gy1OyfmniYIDQCe6qo-vxnCgEonXZBFojv_ROkxX-_bkBjLcSf9yqUXjBMSj7OBTNqylkUoat31EsOwSIkN9VP7Ex8yxxmva3xVE9MuEHE2HUvLazDujMTn8G-Hx-q2k8yaYlOSJVHJoozsIM0X3SYGid2cHAzzc-THhTIdoZhK8K0fCxVYkNrftOcAzmSOWCOdWajuw55_O3wPds1cI_QToh31O4mG8OrZh7QhqYYIzBVEfzMIb7yaPX5zJu2AA' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.14 seconds) server: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://wrong:5751&repository_owner=myorg' server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://wrong:5751&repository_owner=myorg', in 0.02 seconds) server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc4OTkyNTA5MSwiaWF0IjoxNzg5OTIxNDkxLCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.DtcfdlJF8RuNZkqxvXIQIXTvmZ1wCb6LjwH1K6Cd5mKZRffavbJfRHLryuTyfagNJRGjCtxdHadzVTHVIYuWY-3kjqoDIDXclTJUZlwNcl-qvBRQEciWZ1ebz5YnbhuPj7Dm2_Y_X4NtD5jpGdS2ODu3Yr_xqiN_JreuDWFHeV7403DB3vBUAZUqGKZeWkBFWTs5BUEwLk4E7HYTVEdaqq3SQeXNHLZmn7H7ZV9a0tij0CPQYzwtagrC0uJpXbkMPEqjVIP0hO6MhdBsZoH4xU5BXXZpzF1j1OvGMb1Fwb7bmyU79PL3x5efHIqyEIVGATHwZTbFGK-SHlaBM8hXjQ' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2 server # time=2026-09-20T16:24:51.791Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script" server # [ 26.946080] niks3-server[964]: 2026/09/20 16:24:51 WARN Authentication failed token_preview=eyJhbGciOi...HlaBM8hXjQ token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test] server # [ 26.979639] systemd[1]: Started Nix Daemon instance (PID 1413/UID 0). server # [ 27.016395] nix-daemon[1415]: remote pid 1413 is unknown user (trusted) server # [ 27.026341] systemd[1]: nix-daemon@12-13-1413_1414-0.service: Deactivated successfully. server # time=2026-09-20T16:24:51.912Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n" server # [ 27.033786] niks3-server[964]: 2026/09/20 16:24:51 WARN Authentication failed token_preview=eyJhbGciOi...HlaBM8hXjQ token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test] server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc4OTkyNTA5MSwiaWF0IjoxNzg5OTIxNDkxLCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.DtcfdlJF8RuNZkqxvXIQIXTvmZ1wCb6LjwH1K6Cd5mKZRffavbJfRHLryuTyfagNJRGjCtxdHadzVTHVIYuWY-3kjqoDIDXclTJUZlwNcl-qvBRQEciWZ1ebz5YnbhuPj7Dm2_Y_X4NtD5jpGdS2ODu3Yr_xqiN_JreuDWFHeV7403DB3vBUAZUqGKZeWkBFWTs5BUEwLk4E7HYTVEdaqq3SQeXNHLZmn7H7ZV9a0tij0CPQYzwtagrC0uJpXbkMPEqjVIP0hO6MhdBsZoH4xU5BXXZpzF1j1OvGMb1Fwb7bmyU79PL3x5efHIqyEIVGATHwZTbFGK-SHlaBM8hXjQ' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.14 seconds) server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2 server # time=2026-09-20T16:24:51.930Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script" server # [ 27.085071] niks3-server[964]: 2026/09/20 16:24:51 WARN Authentication failed token_preview=not-a-valid-jwt-token token_length=21 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test] server # [ 27.118026] systemd[1]: Started Nix Daemon instance (PID 1433/UID 0). server # [ 27.154888] nix-daemon[1435]: remote pid 1433 is unknown user (trusted) server # [ 27.166637] systemd[1]: nix-daemon@13-14-1433_1434-0.service: Deactivated successfully. server # [ 27.171598] niks3-server[964]: 2026/09/20 16:24:52 WARN Authentication failed token_preview=not-a-valid-jwt-token token_length=21 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test] server # time=2026-09-20T16:24:52.055Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n" server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.14 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins create hello-pin /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 server # [ 27.223163] niks3-server[964]: 2026/09/20 16:24:52 INFO Received create pin request method=POST path=/api/pins/hello-pin server # [ 27.233119] niks3-server[964]: 2026/09/20 16:24:52 INFO Created/updated pin name=hello-pin store_path=/nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 narinfo_key=xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi.narinfo server # time=2026-09-20T16:24:52.116Z level=INFO msg="Created pin" name=hello-pin store_path=/nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins create hello-pin /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 , in 0.06 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins list server # [ 27.283275] niks3-server[964]: 2026/09/20 16:24:52 INFO Received list pins request method=GET path=/api/pins server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins list , in 0.05 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins list --names-only server # [ 27.330863] niks3-server[964]: 2026/09/20 16:24:52 INFO Received list pins request method=GET path=/api/pins server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins list --names-only , in 0.05 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins list --json server # [ 27.378127] niks3-server[964]: 2026/09/20 16:24:52 INFO Received list pins request method=GET path=/api/pins server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins list --json , in 0.05 seconds) server: must succeed: export S3_ENDPOINT_URL=http://localhost:9000 export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin /nix/store/59rphn0qd8k90mdggjdak165a0ysaclw-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin server: (finished: must succeed: export S3_ENDPOINT_URL=http://localhost:9000 export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin /nix/store/59rphn0qd8k90mdggjdak165a0ysaclw-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin , in 0.02 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --pin ca-pin /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log server # time=2026-09-20T16:24:52.332Z level=INFO msg="All 1 paths already cached" server # [ 27.452605] niks3-server[964]: 2026/09/20 16:24:52 INFO Received create pin request method=POST path=/api/pins/ca-pin server # [ 27.496738] niks3-server[964]: 2026/09/20 16:24:52 INFO Created/updated pin name=ca-pin store_path=/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log narinfo_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.narinfo server # time=2026-09-20T16:24:52.379Z level=INFO msg="Created pin" name=ca-pin store_path=/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 push --pin ca-pin /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log , in 0.10 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins list --names-only server # [ 27.547921] niks3-server[964]: 2026/09/20 16:24:52 INFO Received list pins request method=GET path=/api/pins server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins list --names-only , in 0.05 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins delete hello-pin server # [ 27.594446] niks3-server[964]: 2026/09/20 16:24:52 INFO Received delete pin request method=DELETE path=/api/pins/hello-pin server # [ 27.602052] niks3-server[964]: 2026/09/20 16:24:52 INFO Deleted pin name=hello-pin server # time=2026-09-20T16:24:52.483Z level=INFO msg="Deleted pin" name=hello-pin server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins delete hello-pin , in 0.06 seconds) server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins list --names-only server # [ 27.671891] niks3-server[964]: 2026/09/20 16:24:52 INFO Received list pins request method=GET path=/api/pins server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins list --names-only , in 0.07 seconds) server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent server # [ 27.719194] niks3-server[964]: 2026/09/20 16:24:52 INFO Received create pin request method=POST path=/api/pins/bad-pin server # [ 27.721034] niks3-server[964]: 2026/09/20 16:24:52 ERROR Failed to get closure for pin narinfo_key=aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.narinfo error="no rows in result set" server # time=2026-09-20T16:24:52.604Z level=ERROR msg="Fatal error" error="creating pin: server returned 404: closure not found: store path must be pushed before pinning\n" server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/52ijsbffw6z8rz7wp3g0y2yhw3nglhap-niks3-1.12.0-beta.2/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent , in 0.05 seconds) server: must succeed: systemctl start niks3-gc.service server # [ 27.744335] systemd[1]: Starting niks3 garbage collection... server # [ 27.784513] niks3[1560]: time=2026-09-20T16:24:52.664Z level=INFO msg="Starting garbage collection" older-than=720h failed-uploads-older-than=6h force=false server # [ 27.788417] niks3-server[964]: 2026/09/20 16:24:52 INFO Starting cleanup of old closures method=DELETE path=/api/closures server # [ 27.791453] niks3[1560]: time=2026-09-20T16:24:52.671Z level=INFO msg="Garbage collection started" server # [ 27.794156] niks3-server[964]: 2026/09/20 16:24:52 INFO Aborted multipart uploads count=0 server # [ 27.801708] niks3-server[964]: 2026/09/20 16:24:52 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=0 server # [ 27.807622] niks3-server[964]: 2026/09/20 16:24:52 INFO Vacuumed table table=pending_closures server # [ 27.811306] niks3-server[964]: 2026/09/20 16:24:52 INFO Vacuumed table table=pending_objects server # [ 27.814996] niks3-server[964]: 2026/09/20 16:24:52 INFO Vacuumed table table=multipart_uploads server # [ 27.817766] niks3-server[964]: 2026/09/20 16:24:52 INFO Vacuumed table table=closures server # [ 27.820635] niks3-server[964]: 2026/09/20 16:24:52 INFO Vacuumed table table=objects server # [ 29.792586] niks3[1560]: time=2026-09-20T16:24:54.672Z level=INFO msg="Garbage collection progress" phase="" failed_uploads_deleted=0 old_closures_deleted=0 objects_marked=3 objects_deleted=0 objects_failed=0 server # [ 29.795614] niks3[1560]: time=2026-09-20T16:24:54.672Z 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=0 server # [ 29.799829] systemd[1]: niks3-gc.service: Deactivated successfully. server # [ 29.801451] systemd[1]: Finished niks3 garbage collection. server # [ 29.802944] systemd[1]: niks3-gc.service: Consumed 33ms CPU time over 2.057s wall clock time, 2.7M memory peak, 1.2K incoming IP traffic, 823B outgoing IP traffic. server: (finished: must succeed: systemctl start niks3-gc.service, in 2.08 seconds) builder: waiting for unit niks3-auto-upload.socket builder: waiting for the VM to finish booting builder: Guest shell says: b'Spawning backdoor root shell...\n' builder: connected to guest root shell builder: (connecting took 0.00 seconds) builder: (finished: waiting for the VM to finish booting, in 0.00 seconds) builder: (finished: waiting for unit niks3-auto-upload.socket, in 0.05 seconds) builder: must succeed: test -S /run/niks3/upload-to-cache.sock builder: (finished: must succeed: test -S /run/niks3/upload-to-cache.sock, in 0.02 seconds) builder: must succeed: grep post-build-hook /etc/nix/nix.conf builder: (finished: must succeed: grep post-build-hook /etc/nix/nix.conf, in 0.02 seconds) builder: must succeed: cat > /tmp/test-drv.nix << 'EOF' derivation { name = "post-build-hook-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'hello from post-build-hook test' > $out" ]; } EOF builder: (finished: must succeed: cat > /tmp/test-drv.nix << 'EOF' derivation { name = "post-build-hook-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo 'hello from post-build-hook test' > $out" ]; } EOF , in 0.01 seconds) builder: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link builder # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring builder # [ 29.965213] systemd[1]: Created slice Slice /system/nix-daemon. builder # [ 29.968142] systemd[1]: Started Nix Daemon instance (PID 785/UID 0). builder # [ 30.014586] nix-daemon[789]: remote pid 785 is unknown user (trusted) builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 437 ms (attempt 1/5) builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 1024 ms (attempt 2/5) builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 2105 ms (attempt 3/5) builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 4108 ms (attempt 4/5) builder # warning: Failed to setup the substituter at URI 'https://cache.nixos.org/': error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6) builder # this derivation will be built: builder # /nix/store/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv builder # building '/nix/store/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv'... builder # [ 37.885673] systemd[1]: Started niks3 auto-upload daemon. builder # [ 37.994228] niks3-hook[816]: time=2026-09-20T16:25:03.112Z level=INFO msg="niks3-hook serve starting" socket=/run/niks3/upload-to-cache.sock socket-activated=true db-path=/var/lib/niks3-hook/upload-queue.db batch-size=5 idle-exit-timeout=5s drain-timeout=0s builder # [ 38.002187] niks3-hook[816]: time=2026-09-20T16:25:03.120Z level=INFO msg="Upload queue status" pending=1 builder # [ 38.003395] niks3-hook[816]: time=2026-09-20T16:25:03.120Z level=INFO msg="Uploading batch" count=1 builder: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link, in 8.11 seconds) builder: waiting for unit niks3-auto-upload.service builder # [ 38.021743] systemd[1]: nix-daemon@0-1-785_786-0.service: Deactivated successfully. builder # [ 38.024185] systemd[1]: nix-daemon@0-1-785_786-0.service: Consumed 151ms CPU time over 8.053s wall clock time, 20.7M memory peak, 1.4K outgoing IP traffic. builder: (finished: waiting for unit niks3-auto-upload.service, in 0.06 seconds) ??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead. File "/nix/store/q8bka5k1zvss9dd482k8ivsflp9lkmxw-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 builder: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive ??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead. File "/nix/store/q8bka5k1zvss9dd482k8ivsflp9lkmxw-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 builder # [ 38.085586] systemd[1]: Started Nix Daemon instance (PID 828/UID 0). builder # [ 38.136415] nix-daemon[837]: remote pid 828 is unknown user (trusted) builder # [ 38.147571] systemd[1]: nix-daemon@1-2-828_829-0.service: Deactivated successfully. server # [ 38.156365] niks3-server[964]: 2026/09/20 16:25:03 INFO Received uploads request method=POST path=/api/pending_closures builder # [ 38.165183] niks3-hook[816]: time=2026-09-20T16:25:03.284Z level=INFO msg="Uploading 1 paths to server (0 already cached)" builder # [ 38.166869] niks3-hook[816]: time=2026-09-20T16:25:03.285Z level=INFO msg="Uploading ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test (144B)" server # [ 38.202100] niks3-server[964]: 2026/09/20 16:25:03 INFO Registered completed upload object_key=nar/1a0vfw93shmvlz6lac7d1lv82jxzfdswigh5s32hpvncf74i1i0y.nar.zst server # [ 38.209492] niks3-server[964]: 2026/09/20 16:25:03 INFO Registered completed upload object_key=log/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv server # [ 38.218929] niks3-server[964]: 2026/09/20 16:25:03 INFO Received sign narinfos request method=POST path=/api/pending_closures/6/sign builder # [ 38.222846] niks3-hook[816]: time=2026-09-20T16:25:03.341Z level=INFO msg="Uploading 1 narinfos" server # [ 38.224985] niks3-server[964]: 2026/09/20 16:25:03 INFO Signed narinfos id=6 count=1 server # [ 38.231545] niks3-server[964]: 2026/09/20 16:25:03 INFO Registered completed upload object_key=ykg16qhbiwqmava2ahq779zd0dfd9mjd.ls server # [ 38.238825] niks3-server[964]: 2026/09/20 16:25:03 INFO Received complete upload request method=POST path=/api/pending_closures/6/complete server # [ 38.242551] niks3-server[964]: 2026/09/20 16:25:03 INFO Completed upload id=6 builder # [ 38.240792] niks3-hook[816]: time=2026-09-20T16:25:03.359Z level=INFO msg="Upload complete. (239ms)" server # [ 38.244837] niks3-server[964]: 2026/09/20 16:25:03 INFO Registered completed upload object_key=ykg16qhbiwqmava2ahq779zd0dfd9mjd.narinfo builder # [ 43.001956] niks3-hook[816]: time=2026-09-20T16:25:08.120Z level=INFO msg="Idle timeout reached and queue is empty, shutting down" builder # [ 43.007378] niks3-hook[816]: time=2026-09-20T16:25:08.125Z level=INFO msg="niks3-hook serve stopped" builder # [ 43.019295] systemd[1]: niks3-auto-upload.service: Deactivated successfully. builder # [ 43.024514] systemd[1]: niks3-auto-upload.service: Consumed 116ms CPU time over 5.136s wall clock time, 10.8M memory peak, 68K written to disk, 5.3K incoming IP traffic, 7.9K outgoing IP traffic. builder: (finished: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive, in 5.22 seconds) server: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/hook-test-store /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test server: (finished: must succeed: export AWS_ACCESS_KEY_ID=rustfsadmin export AWS_SECRET_ACCESS_KEY=rustfsadmin nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/hook-test-store /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test , in 0.14 seconds) server: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test server: (finished: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test, in 0.04 seconds) (finished: run the VM test script, in 44.41 seconds) test script finished in 44.48s cleanup kill QemuMachine (pid 47) builder # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14) builder # [2026-09-20T16:25:08Z INFO virtiofsd] Client disconnected, shutting down builder # [2026-09-20T16:25:08Z INFO virtiofsd] Client disconnected, shutting down builder # [2026-09-20T16:25:08Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 48) server # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14) server # [2026-09-20T16:25:09Z INFO virtiofsd] Client disconnected, shutting down server # [2026-09-20T16:25:09Z INFO virtiofsd] Client disconnected, shutting down server # [2026-09-20T16:25:09Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.54 seconds) additionally exposed symbols: builder, server, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh Hello store path: /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 Test output path: /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log Symlink wrapper store path: /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper Symlink wrapper points to: /nix/store/x9d9hyw8xybijhpvipmkiryn9n8nw3ij-base-package/bin/test-program OIDC test store path: /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test Valid OIDC token obtained (length=677) OIDC push with valid token: SUCCESS Invalid OIDC token obtained (wrong org) OIDC push with wrong org: correctly rejected Wrong audience OIDC token obtained OIDC push with wrong audience: correctly rejected OIDC push with malformed token: correctly rejected All OIDC tests passed! All pin tests passed! Built store path: /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test Post-build-hook pipeline test passed!